builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-066 starttime: 1506725310.66 results: success (0) revision: 57f68296c350469d73d788eb3695a898947b4acb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:30.662053) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:30.662504) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:30.662970) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:30.742157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:30.742408) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-29 15:48:30-- 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% 36.8M=0s 2017-09-29 15:48:30 (36.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.094427 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:30.863041) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:30.863348) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014664 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:30.920212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:30.920499) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 57f68296c350469d73d788eb3695a898947b4acb --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 57f68296c350469d73d788eb3695a898947b4acb --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=82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-29 15:48:30,970 truncating revision to first 12 chars 2017-09-29 15:48:30,970 Setting DEBUG logging. 2017-09-29 15:48:30,970 attempt 1/10 2017-09-29 15:48:30,970 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/57f68296c350?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-29 15:48:31,388 unpacking tar archive at: mozilla-central-57f68296c350/testing/mozharness/ program finished with exit code 0 elapsedTime=0.698748 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:31.635633) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:31.635992) ========= script_repo_revision: 57f68296c350469d73d788eb3695a898947b4acb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:31.636392) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:31.636661) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-29 15:48:31.650090) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-29 15:48:31.650517) ========= /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=82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:48:31 INFO - MultiFileLogger online at 20170929 15:48:31 in /builds/slave/test 15:48:31 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:48:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:48:31 INFO - {'append_to_log': False, 15:48:31 INFO - 'base_work_dir': '/builds/slave/test', 15:48:31 INFO - 'blob_upload_branch': 'Firefox', 15:48:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:48:31 INFO - 'branch': 'Firefox', 15:48:31 INFO - 'buildbot_json_path': 'buildprops.json', 15:48:31 INFO - 'code_coverage': False, 15:48:31 INFO - 'config_files': ('talos/linux_config.py',), 15:48:31 INFO - 'default_actions': ('clobber', 15:48:31 INFO - 'read-buildbot-config', 15:48:31 INFO - 'download-and-extract', 15:48:31 INFO - 'populate-webroot', 15:48:31 INFO - 'create-virtualenv', 15:48:31 INFO - 'install', 15:48:31 INFO - 'setup-mitmproxy', 15:48:31 INFO - 'run-tests'), 15:48:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:48:31 INFO - 'disable_ccov_upload': False, 15:48:31 INFO - 'disable_stylo': False, 15:48:31 INFO - 'download_minidump_stackwalk': True, 15:48:31 INFO - 'download_symbols': 'ondemand', 15:48:31 INFO - 'enable_stylo': False, 15:48:31 INFO - 'enable_webrender': False, 15:48:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:48:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:48:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:48:31 INFO - 'gecko_profile': False, 15:48:31 INFO - 'gecko_profile_interval': 0, 15:48:31 INFO - 'installer_path': 'installer.exe', 15:48:31 INFO - 'log_level': 'info', 15:48:31 INFO - 'log_name': 'talos', 15:48:31 INFO - 'log_to_console': True, 15:48:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:48:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:48:31 INFO - 'opt_config_files': (), 15:48:31 INFO - 'pip_index': False, 15:48:31 INFO - 'suite': 'dromaeojs-e10s', 15:48:31 INFO - 'system_bits': '32', 15:48:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:48:31 INFO - 'title': 'talos-linux64-ix-066', 15:48:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:48:31 INFO - 'use_talos_json': True, 15:48:31 INFO - 'verify': 'False', 15:48:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:48:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:48:31 INFO - 'work_dir': 'build'} 15:48:31 INFO - [mozharness: 2017-09-29 22:48:31.851722Z] Running clobber step. 15:48:31 INFO - Running pre-action listener: _resource_record_pre_action 15:48:31 INFO - Running main action method: clobber 15:48:31 INFO - rmtree: /builds/slave/test/build 15:48:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:48:33 INFO - Running post-action listener: _resource_record_post_action 15:48:33 INFO - [mozharness: 2017-09-29 22:48:33.397629Z] Finished clobber step (success) 15:48:33 INFO - [mozharness: 2017-09-29 22:48:33.397728Z] Running read-buildbot-config step. 15:48:33 INFO - Running pre-action listener: _resource_record_pre_action 15:48:33 INFO - Running main action method: read_buildbot_config 15:48:33 INFO - Using buildbot properties: 15:48:33 INFO - { 15:48:33 INFO - "project": "", 15:48:33 INFO - "product": "firefox", 15:48:33 INFO - "who": "kwierso@gmail.com", 15:48:33 INFO - "installer_path": "public/build/target.tar.bz2", 15:48:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 15:48:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:48:33 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 15:48:33 INFO - "stage_platform": "linux64-qr", 15:48:33 INFO - "basedir": "/builds/slave/test", 15:48:33 INFO - "buildnumber": 93, 15:48:33 INFO - "platform": "ubuntu64_hw_qr", 15:48:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:48:33 INFO - "slavebuilddir": "test", 15:48:33 INFO - "taskId": "bOgAi-KvS3mITAaANJRUAQ", 15:48:33 INFO - "branch": "mozilla-central", 15:48:33 INFO - "script_repo_revision": "production", 15:48:33 INFO - "revision": "57f68296c350469d73d788eb3695a898947b4acb", 15:48:33 INFO - "slavename": "talos-linux64-ix-066", 15:48:33 INFO - "repo_path": "mozilla-central" 15:48:33 INFO - } 15:48:33 INFO - Finding installer, test and symbols from parent task. 15:48:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bOgAi-KvS3mITAaANJRUAQ'}, attempt #1 15:48:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bOgAi-KvS3mITAaANJRUAQ'}, attempt #1 15:48:34 INFO - Task dependencies: fp7g7_mhRC22F8P10CUW5Q 15:48:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q'}, attempt #1 15:48:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.tar.bz2 15:48:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.test_packages.json 15:48:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.crashreporter-symbols.zip 15:48:34 INFO - Running post-action listener: _resource_record_post_action 15:48:34 INFO - [mozharness: 2017-09-29 22:48:34.823754Z] Finished read-buildbot-config step (success) 15:48:34 INFO - [mozharness: 2017-09-29 22:48:34.823972Z] Running download-and-extract step. 15:48:34 INFO - Running pre-action listener: _resource_record_pre_action 15:48:34 INFO - Running main action method: download_and_extract 15:48:34 INFO - mkdir: /builds/slave/test/build/tests 15:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:34 INFO - trying https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.test_packages.json 15:48:34 INFO - Downloading https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:48:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:48:35 INFO - Downloaded 989 bytes. 15:48:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:48:35 INFO - Using the following test package requirements: 15:48:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:48:35 INFO - u'common': [u'target.common.tests.zip'], 15:48:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:48:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:48:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:48:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:48:35 INFO - u'mozbase': [u'target.common.tests.zip'], 15:48:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:48:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:48:35 INFO - u'web-platform': [u'target.common.tests.zip', 15:48:35 INFO - u'target.web-platform.tests.tar.gz'], 15:48:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:48:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:48:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.common.tests.zip 15:48:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:48:35 INFO - Fetch https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.common.tests.zip into memory 15:48:36 INFO - Content-Length response header: 55366784 15:48:36 INFO - Bytes received: 55366784 15:48:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.talos.tests.zip 15:48:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:48:47 INFO - Fetch https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.talos.tests.zip into memory 15:48:48 INFO - Content-Length response header: 14522385 15:48:48 INFO - Bytes received: 14522385 15:48:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:48 INFO - trying https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.tar.bz2 15:48:48 INFO - Downloading https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:48:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:48:49 INFO - Downloaded 61665053 bytes. 15:48:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.tar.bz2 15:48:50 INFO - mkdir: /builds/slave/test/properties 15:48:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:48:50 INFO - Writing to file /builds/slave/test/properties/build_url 15:48:50 INFO - Contents: 15:48:50 INFO - build_url:https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.tar.bz2 15:48:50 INFO - Running post-action listener: _resource_record_post_action 15:48:50 INFO - Running post-action listener: find_tests_for_verification 15:48:50 INFO - Running post-action listener: set_extra_try_arguments 15:48:50 INFO - [mozharness: 2017-09-29 22:48:50.324035Z] Finished download-and-extract step (success) 15:48:50 INFO - [mozharness: 2017-09-29 22:48:50.324137Z] Running populate-webroot step. 15:48:50 INFO - Running pre-action listener: _resource_record_pre_action 15:48:50 INFO - Running main action method: populate_webroot 15:48:50 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:48:50 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:50 INFO - 'tests': ['tresize', 15:48:50 INFO - 'tcanvasmark']}, 15:48:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:48:50 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:50 INFO - 'tests': ['dromaeo_css', 15:48:50 INFO - 'kraken']}, 15:48:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:48:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:50 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:48:50 INFO - 'talos_options': ['--disable-stylo'], 15:48:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:48:50 INFO - 'tests': ['damp', 'tps']}, 15:48:50 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:48:50 INFO - 'talos_options': ['--disable-stylo'], 15:48:50 INFO - 'tests': ['damp', 'tps']}, 15:48:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:48:50 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:50 INFO - 'tests': ['dromaeo_dom']}, 15:48:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:48:50 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:50 INFO - 'tests': ['basic_compositor_video', 15:48:50 INFO - 'glvideo']}, 15:48:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:48:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:48:50 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:48:50 INFO - 'talos_options': ['--disable-stylo'], 15:48:50 INFO - 'tests': ['ts_paint_webext', 15:48:50 INFO - 'tp5o_webext']}, 15:48:50 INFO - 'other-e10s': {'tests': ['a11yr', 15:48:50 INFO - 'ts_paint', 15:48:50 INFO - 'tpaint', 15:48:50 INFO - 'sessionrestore', 15:48:50 INFO - 'sessionrestore_many_windows', 15:48:50 INFO - 'sessionrestore_no_auto_restore', 15:48:50 INFO - 'tabpaint', 15:48:50 INFO - 'cpstartup']}, 15:48:50 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:50 INFO - 'tests': ['a11yr', 15:48:50 INFO - 'ts_paint', 15:48:50 INFO - 'tpaint', 15:48:50 INFO - 'sessionrestore', 15:48:50 INFO - 'sessionrestore_many_windows', 15:48:50 INFO - 'sessionrestore_no_auto_restore', 15:48:50 INFO - 'tabpaint', 15:48:50 INFO - 'cpstartup']}, 15:48:50 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 15:48:50 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 15:48:50 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:50 INFO - 'tests': ['perf_reftest_singletons']}, 15:48:50 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:50 INFO - 'tests': ['perf_reftest']}, 15:48:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:48:50 INFO - 'tsvgr_opacity', 15:48:50 INFO - 'tart', 15:48:50 INFO - 'tscrollx', 15:48:50 INFO - 'tsvg_static']}, 15:48:50 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:50 INFO - 'tests': ['tsvgx', 15:48:50 INFO - 'tsvgr_opacity', 15:48:50 INFO - 'tart', 15:48:50 INFO - 'tscrollx', 15:48:50 INFO - 'tsvg_static']}, 15:48:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:48:50 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:48:50 INFO - 'talos_options': ['--disable-stylo'], 15:48:50 INFO - 'tests': ['tp5o']}, 15:48:50 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:48:50 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:48:50 INFO - 'talos_options': ['--mitmproxy', 15:48:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:48:50 INFO - '--firstNonBlankPaint'], 15:48:50 INFO - 'tests': ['tp6_google', 15:48:50 INFO - 'tp6_youtube', 15:48:50 INFO - 'tp6_amazon', 15:48:50 INFO - 'tp6_facebook']}, 15:48:50 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:48:50 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:48:50 INFO - 'talos_options': ['--disable-stylo', 15:48:50 INFO - '--mitmproxy', 15:48:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:48:50 INFO - '--firstNonBlankPaint'], 15:48:50 INFO - 'tests': ['tp6_google', 15:48:50 INFO - 'tp6_youtube', 15:48:50 INFO - 'tp6_amazon', 15:48:50 INFO - 'tp6_facebook']}, 15:48:50 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:48:50 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:48:50 INFO - 'talos_options': ['--stylo-threads=1', 15:48:50 INFO - '--mitmproxy', 15:48:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:48:50 INFO - '--firstNonBlankPaint'], 15:48:50 INFO - 'tests': ['tp6_google', 15:48:50 INFO - 'tp6_youtube', 15:48:50 INFO - 'tp6_amazon', 15:48:50 INFO - 'tp6_facebook']}, 15:48:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:48:50 INFO - 'talos_options': ['--xperf_path', 15:48:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:50 INFO - 'tests': ['tp5n']}, 15:48:50 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:48:50 INFO - 'talos_options': ['--disable-stylo', 15:48:50 INFO - '--xperf_path', 15:48:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:50 INFO - 'tests': ['tp5n']}}} 15:48:50 INFO - Running post-action listener: _resource_record_post_action 15:48:50 INFO - [mozharness: 2017-09-29 22:48:50.348413Z] Finished populate-webroot step (success) 15:48:50 INFO - [mozharness: 2017-09-29 22:48:50.348595Z] Running create-virtualenv step. 15:48:50 INFO - Running pre-action listener: _resource_record_pre_action 15:48:50 INFO - Running main action method: create_virtualenv 15:48:50 INFO - Creating virtualenv /builds/slave/test/build/venv 15:48:50 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:48:50 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:48:50 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:48:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:48:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:48:50 INFO - Installing distribute.............................................................................................................................................................................................done. 15:48:51 INFO - Installing pip...............done. 15:48:51 INFO - Return code: 0 15:48:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:48:51 INFO - Reading from file tmpfile_stdout 15:48:51 INFO - Output received: 15:48:51 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:48:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c47df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4b440>, '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 0x2c4c130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c46320>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', '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:48:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:48:51 INFO - Using env: {'DISPLAY': ':0', 15:48:51 INFO - 'HOME': '/home/cltbld', 15:48:51 INFO - 'LANG': 'en_US.UTF-8', 15:48:51 INFO - 'LANGUAGE': 'en_US:en', 15:48:51 INFO - 'LOGNAME': 'cltbld', 15:48:51 INFO - 'MAIL': '/var/mail/cltbld', 15:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:51 INFO - 'MOZ_NO_REMOTE': '1', 15:48:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:51 INFO - 'NO_EM_RESTART': '1', 15:48:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:51 INFO - 'PWD': '/builds/slave/test', 15:48:51 INFO - 'SHELL': '/bin/bash', 15:48:51 INFO - 'SHLVL': '1', 15:48:51 INFO - 'TERM': 'linux', 15:48:51 INFO - 'TMOUT': '86400', 15:48:51 INFO - 'USER': 'cltbld', 15:48:51 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', 15:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:51 INFO - '_': '/tools/buildbot/bin/python'} 15:48:52 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:48:52 INFO - Downloading/unpacking pip>=1.5 15:48:52 INFO - Running setup.py egg_info for package pip 15:48:52 INFO - warning: no files found matching 'pip/cacert.pem' 15:48:52 INFO - warning: no files found matching '*.html' under directory 'docs' 15:48:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:48:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:48:52 INFO - Installing collected packages: pip 15:48:52 INFO - Found existing installation: pip 1.1 15:48:52 INFO - Uninstalling pip: 15:48:52 INFO - Successfully uninstalled pip 15:48:52 INFO - Running setup.py install for pip 15:48:52 INFO - warning: no files found matching 'pip/cacert.pem' 15:48:52 INFO - warning: no files found matching '*.html' under directory 'docs' 15:48:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:48:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:48:52 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:48:52 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:48:52 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:48:52 INFO - Successfully installed pip 15:48:52 INFO - Cleaning up... 15:48:52 INFO - Return code: 0 15:48:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:48:52 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:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:52 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:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:52 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 0x28dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c47df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4b440>, '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 0x2c4c130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c46320>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', '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:48:52 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:48:52 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:48:52 INFO - Using env: {'DISPLAY': ':0', 15:48:52 INFO - 'HOME': '/home/cltbld', 15:48:52 INFO - 'LANG': 'en_US.UTF-8', 15:48:52 INFO - 'LANGUAGE': 'en_US:en', 15:48:52 INFO - 'LOGNAME': 'cltbld', 15:48:52 INFO - 'MAIL': '/var/mail/cltbld', 15:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:52 INFO - 'MOZ_NO_REMOTE': '1', 15:48:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:52 INFO - 'NO_EM_RESTART': '1', 15:48:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:52 INFO - 'PWD': '/builds/slave/test', 15:48:52 INFO - 'SHELL': '/bin/bash', 15:48:52 INFO - 'SHLVL': '1', 15:48:52 INFO - 'TERM': 'linux', 15:48:52 INFO - 'TMOUT': '86400', 15:48:52 INFO - 'USER': 'cltbld', 15:48:52 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', 15:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:52 INFO - '_': '/tools/buildbot/bin/python'} 15:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:52 INFO - Downloading/unpacking psutil>=3.1.1 15:48:52 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:48:52 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:48:52 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:48:52 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:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:48:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:54 INFO - Installing collected packages: psutil 15:48:54 INFO - Running setup.py install for psutil 15:48:54 INFO - building 'psutil._psutil_linux' extension 15:48:54 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:48:54 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:48:54 INFO - building 'psutil._psutil_posix' extension 15:48:54 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:48:54 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:48:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:54 INFO - Successfully installed psutil 15:48:54 INFO - Cleaning up... 15:48:54 INFO - Return code: 0 15:48:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c47df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4b440>, '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 0x2c4c130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c46320>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', '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:48:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:48:54 INFO - Using env: {'DISPLAY': ':0', 15:48:54 INFO - 'HOME': '/home/cltbld', 15:48:54 INFO - 'LANG': 'en_US.UTF-8', 15:48:54 INFO - 'LANGUAGE': 'en_US:en', 15:48:54 INFO - 'LOGNAME': 'cltbld', 15:48:54 INFO - 'MAIL': '/var/mail/cltbld', 15:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:54 INFO - 'MOZ_NO_REMOTE': '1', 15:48:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:54 INFO - 'NO_EM_RESTART': '1', 15:48:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:54 INFO - 'PWD': '/builds/slave/test', 15:48:54 INFO - 'SHELL': '/bin/bash', 15:48:54 INFO - 'SHLVL': '1', 15:48:54 INFO - 'TERM': 'linux', 15:48:54 INFO - 'TMOUT': '86400', 15:48:54 INFO - 'USER': 'cltbld', 15:48:54 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', 15:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:54 INFO - '_': '/tools/buildbot/bin/python'} 15:48:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:48:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:48:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:48:56 INFO - Installing collected packages: mozsystemmonitor 15:48:56 INFO - Running setup.py install for mozsystemmonitor 15:48:56 INFO - Successfully installed mozsystemmonitor 15:48:56 INFO - Cleaning up... 15:48:57 INFO - Return code: 0 15:48:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:48:57 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:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:57 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:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:57 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 0x28dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c47df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4b440>, '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 0x2c4c130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c46320>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', '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:48:57 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:48:57 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:48:57 INFO - Using env: {'DISPLAY': ':0', 15:48:57 INFO - 'HOME': '/home/cltbld', 15:48:57 INFO - 'LANG': 'en_US.UTF-8', 15:48:57 INFO - 'LANGUAGE': 'en_US:en', 15:48:57 INFO - 'LOGNAME': 'cltbld', 15:48:57 INFO - 'MAIL': '/var/mail/cltbld', 15:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:57 INFO - 'MOZ_NO_REMOTE': '1', 15:48:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:57 INFO - 'NO_EM_RESTART': '1', 15:48:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:57 INFO - 'PWD': '/builds/slave/test', 15:48:57 INFO - 'SHELL': '/bin/bash', 15:48:57 INFO - 'SHLVL': '1', 15:48:57 INFO - 'TERM': 'linux', 15:48:57 INFO - 'TMOUT': '86400', 15:48:57 INFO - 'USER': 'cltbld', 15:48:57 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', 15:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:57 INFO - '_': '/tools/buildbot/bin/python'} 15:48:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:57 INFO - Downloading/unpacking jsonschema==2.5.1 15:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:49:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:00 INFO - Downloading functools32-3.2.3-2.tar.gz 15:49:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:49:00 INFO - warning: no files found matching '*.txt' 15:49:00 INFO - no previously-included directories found matching 'build' 15:49:00 INFO - no previously-included directories found matching 'dist' 15:49:00 INFO - no previously-included directories found matching '.git*' 15:49:00 INFO - Installing collected packages: jsonschema, functools32 15:49:00 INFO - Running setup.py install for functools32 15:49:00 INFO - warning: no files found matching '*.txt' 15:49:00 INFO - no previously-included directories found matching 'build' 15:49:00 INFO - no previously-included directories found matching 'dist' 15:49:00 INFO - no previously-included directories found matching '.git*' 15:49:00 INFO - Successfully installed jsonschema functools32 15:49:00 INFO - Cleaning up... 15:49:00 INFO - Return code: 0 15:49:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:00 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 0x28dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c47df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4b440>, '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 0x2c4c130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c46320>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', '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:49:00 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:49:00 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:49:00 INFO - Using env: {'DISPLAY': ':0', 15:49:00 INFO - 'HOME': '/home/cltbld', 15:49:00 INFO - 'LANG': 'en_US.UTF-8', 15:49:00 INFO - 'LANGUAGE': 'en_US:en', 15:49:00 INFO - 'LOGNAME': 'cltbld', 15:49:00 INFO - 'MAIL': '/var/mail/cltbld', 15:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:00 INFO - 'MOZ_NO_REMOTE': '1', 15:49:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:00 INFO - 'NO_EM_RESTART': '1', 15:49:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:00 INFO - 'PWD': '/builds/slave/test', 15:49:00 INFO - 'SHELL': '/bin/bash', 15:49:00 INFO - 'SHLVL': '1', 15:49:00 INFO - 'TERM': 'linux', 15:49:00 INFO - 'TMOUT': '86400', 15:49:00 INFO - 'USER': 'cltbld', 15:49:00 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', 15:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:00 INFO - '_': '/tools/buildbot/bin/python'} 15:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:49:00 INFO - Cleaning up... 15:49:00 INFO - Return code: 0 15:49:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:00 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 0x28dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c47df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4b440>, '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 0x2c4c130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c46320>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', '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:49:00 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:49:00 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:49:00 INFO - Using env: {'DISPLAY': ':0', 15:49:00 INFO - 'HOME': '/home/cltbld', 15:49:00 INFO - 'LANG': 'en_US.UTF-8', 15:49:00 INFO - 'LANGUAGE': 'en_US:en', 15:49:00 INFO - 'LOGNAME': 'cltbld', 15:49:00 INFO - 'MAIL': '/var/mail/cltbld', 15:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:00 INFO - 'MOZ_NO_REMOTE': '1', 15:49:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:00 INFO - 'NO_EM_RESTART': '1', 15:49:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:00 INFO - 'PWD': '/builds/slave/test', 15:49:00 INFO - 'SHELL': '/bin/bash', 15:49:00 INFO - 'SHLVL': '1', 15:49:00 INFO - 'TERM': 'linux', 15:49:00 INFO - 'TMOUT': '86400', 15:49:00 INFO - 'USER': 'cltbld', 15:49:00 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', 15:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:00 INFO - '_': '/tools/buildbot/bin/python'} 15:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:00 INFO - Downloading/unpacking blobuploader==1.2.4 15:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:02 INFO - Downloading blobuploader-1.2.4.tar.gz 15:49:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:49:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:49:02 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:49:02 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:49:02 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:49:02 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:49:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:49:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:49:02 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:49:02 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:49:02 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:49:02 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:49:02 INFO - Downloading docopt-0.6.1.tar.gz 15:49:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:49:02 INFO - Installing collected packages: blobuploader, requests, docopt 15:49:02 INFO - Running setup.py install for blobuploader 15:49:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:49:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:49:02 INFO - Running setup.py install for requests 15:49:03 INFO - Running setup.py install for docopt 15:49:03 INFO - Successfully installed blobuploader requests docopt 15:49:03 INFO - Cleaning up... 15:49:03 INFO - Return code: 0 15:49:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49: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:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49: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:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:03 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 0x28dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c47df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4b440>, '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 0x2c4c130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c46320>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', '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:49:03 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:49:03 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:49:03 INFO - Using env: {'DISPLAY': ':0', 15:49:03 INFO - 'HOME': '/home/cltbld', 15:49:03 INFO - 'LANG': 'en_US.UTF-8', 15:49:03 INFO - 'LANGUAGE': 'en_US:en', 15:49:03 INFO - 'LOGNAME': 'cltbld', 15:49:03 INFO - 'MAIL': '/var/mail/cltbld', 15:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:03 INFO - 'MOZ_NO_REMOTE': '1', 15:49:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:03 INFO - 'NO_EM_RESTART': '1', 15:49:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:03 INFO - 'PWD': '/builds/slave/test', 15:49:03 INFO - 'SHELL': '/bin/bash', 15:49:03 INFO - 'SHLVL': '1', 15:49:03 INFO - 'TERM': 'linux', 15:49:03 INFO - 'TMOUT': '86400', 15:49:03 INFO - 'USER': 'cltbld', 15:49:03 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', 15:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:03 INFO - '_': '/tools/buildbot/bin/python'} 15:49:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:03 INFO - Running setup.py (path:/tmp/pip-6u4wZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:03 INFO - Running setup.py (path:/tmp/pip-wVB77C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:03 INFO - Running setup.py (path:/tmp/pip-cK_oNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:03 INFO - Running setup.py (path:/tmp/pip-XDkIje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:03 INFO - Running setup.py (path:/tmp/pip-apYqrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:03 INFO - Running setup.py (path:/tmp/pip-kH6Yj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:03 INFO - Running setup.py (path:/tmp/pip-Kou_Bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:03 INFO - Running setup.py (path:/tmp/pip-e6nQhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:04 INFO - Running setup.py (path:/tmp/pip-qPZwre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:04 INFO - Running setup.py (path:/tmp/pip-NaqYa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:04 INFO - Running setup.py (path:/tmp/pip-KJIuYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:04 INFO - Running setup.py (path:/tmp/pip-6NF7_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:04 INFO - Running setup.py (path:/tmp/pip-FF0jxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:04 INFO - Running setup.py (path:/tmp/pip-bFxgqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:04 INFO - Running setup.py (path:/tmp/pip-Lxg1Lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:04 INFO - Running setup.py (path:/tmp/pip-BejLKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:04 INFO - Running setup.py (path:/tmp/pip-uykAcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:49:04 INFO - Running setup.py install for manifestparser 15:49:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:49:04 INFO - Running setup.py install for mozcrash 15:49:04 INFO - Running setup.py install for mozdebug 15:49:04 INFO - Running setup.py install for mozdevice 15:49:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:49:05 INFO - Running setup.py install for mozfile 15:49:05 INFO - Running setup.py install for mozhttpd 15:49:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:49:05 INFO - Running setup.py install for mozinfo 15:49:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:49:05 INFO - Running setup.py install for mozInstall 15:49:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:49:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:49:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:49:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:49:05 INFO - Running setup.py install for mozleak 15:49:05 INFO - Running setup.py install for mozlog 15:49:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:49:05 INFO - Running setup.py install for moznetwork 15:49:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:49:05 INFO - Running setup.py install for mozprocess 15:49:05 INFO - Running setup.py install for mozprofile 15:49:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:49:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:49:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:49:05 INFO - Running setup.py install for mozrunner 15:49:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:49:06 INFO - Running setup.py install for mozscreenshot 15:49:06 INFO - Running setup.py install for moztest 15:49:06 INFO - Running setup.py install for mozversion 15:49:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:49:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:49:06 INFO - Cleaning up... 15:49:06 INFO - Return code: 0 15:49:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:06 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 0x28dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c47df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4b440>, '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 0x2c4c130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c46320>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', '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:49:06 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:49:06 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:49:06 INFO - Using env: {'DISPLAY': ':0', 15:49:06 INFO - 'HOME': '/home/cltbld', 15:49:06 INFO - 'LANG': 'en_US.UTF-8', 15:49:06 INFO - 'LANGUAGE': 'en_US:en', 15:49:06 INFO - 'LOGNAME': 'cltbld', 15:49:06 INFO - 'MAIL': '/var/mail/cltbld', 15:49:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:06 INFO - 'MOZ_NO_REMOTE': '1', 15:49:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:06 INFO - 'NO_EM_RESTART': '1', 15:49:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:06 INFO - 'PWD': '/builds/slave/test', 15:49:06 INFO - 'SHELL': '/bin/bash', 15:49:06 INFO - 'SHLVL': '1', 15:49:06 INFO - 'TERM': 'linux', 15:49:06 INFO - 'TMOUT': '86400', 15:49:06 INFO - 'USER': 'cltbld', 15:49:06 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', 15:49:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:06 INFO - '_': '/tools/buildbot/bin/python'} 15:49:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:06 INFO - Running setup.py (path:/tmp/pip-PKybG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:06 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:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:06 INFO - Running setup.py (path:/tmp/pip-AAd7bR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:06 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:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:06 INFO - Running setup.py (path:/tmp/pip-0vUd0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:06 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:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:06 INFO - Running setup.py (path:/tmp/pip-mFAMWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:49:06 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:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:49:06 INFO - Running setup.py (path:/tmp/pip-2u1dtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:49:07 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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:49:07 INFO - Running setup.py (path:/tmp/pip-0yTs9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:49:07 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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:49:07 INFO - Running setup.py (path:/tmp/pip-vp7dHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:49:07 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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:49:07 INFO - Running setup.py (path:/tmp/pip-ZN4RlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:49:07 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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:49:07 INFO - Running setup.py (path:/tmp/pip-N9gJXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:49:07 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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:49:07 INFO - Running setup.py (path:/tmp/pip-SYgq5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:49:07 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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:49:07 INFO - Running setup.py (path:/tmp/pip-giZXvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:49:07 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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:49:07 INFO - Running setup.py (path:/tmp/pip-f_CqoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:49:07 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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:49:07 INFO - Running setup.py (path:/tmp/pip-2k3EaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:49:07 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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:49:07 INFO - Running setup.py (path:/tmp/pip-1siqkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:49:07 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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:07 INFO - Running setup.py (path:/tmp/pip-XKJfc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:49:07 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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:49:07 INFO - Running setup.py (path:/tmp/pip-1NqdZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:49:07 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:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:49:07 INFO - Running setup.py (path:/tmp/pip-bRvy68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:49:07 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:49:07 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:49:07 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:49:07 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:49:07 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:49:07 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:49:07 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:49:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:49: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:49: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:49: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:49: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:49:08 INFO - Downloading blessings-1.6.tar.gz 15:49:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:49:09 INFO - Installing collected packages: blessings 15:49:09 INFO - Running setup.py install for blessings 15:49:09 INFO - Successfully installed blessings 15:49:09 INFO - Cleaning up... 15:49:09 INFO - Return code: 0 15:49:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:49:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:09 INFO - Reading from file tmpfile_stdout 15:49:09 INFO - Current package versions: 15:49:09 INFO - argparse == 1.2.1 15:49:09 INFO - blessings == 1.6 15:49:09 INFO - blobuploader == 1.2.4 15:49:09 INFO - docopt == 0.6.1 15:49:09 INFO - functools32 == 3.2.3-2 15:49:09 INFO - jsonschema == 2.5.1 15:49:09 INFO - manifestparser == 1.1 15:49:09 INFO - mozInstall == 1.13 15:49:09 INFO - mozcrash == 1.0 15:49:09 INFO - mozdebug == 0.1 15:49:09 INFO - mozdevice == 0.51 15:49:09 INFO - mozfile == 1.2 15:49:09 INFO - mozhttpd == 0.7 15:49:09 INFO - mozinfo == 0.10 15:49:09 INFO - mozleak == 0.1 15:49:09 INFO - mozlog == 3.5 15:49:09 INFO - moznetwork == 0.27 15:49:09 INFO - mozprocess == 0.25 15:49:09 INFO - mozprofile == 0.28 15:49:09 INFO - mozrunner == 6.13 15:49:09 INFO - mozscreenshot == 0.1 15:49:09 INFO - mozsystemmonitor == 0.3 15:49:09 INFO - moztest == 0.8 15:49:09 INFO - mozversion == 1.4 15:49:09 INFO - psutil == 3.1.1 15:49:09 INFO - requests == 1.2.3 15:49:09 INFO - wsgiref == 0.1.2 15:49:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49: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:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49: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:49:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:09 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 0x28dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c47df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4b440>, '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 0x2c4c130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c46320>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', '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:49:09 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:49:09 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:49:09 INFO - Using env: {'DISPLAY': ':0', 15:49:09 INFO - 'HOME': '/home/cltbld', 15:49:09 INFO - 'LANG': 'en_US.UTF-8', 15:49:09 INFO - 'LANGUAGE': 'en_US:en', 15:49:09 INFO - 'LOGNAME': 'cltbld', 15:49:09 INFO - 'MAIL': '/var/mail/cltbld', 15:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:09 INFO - 'MOZ_NO_REMOTE': '1', 15:49:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:09 INFO - 'NO_EM_RESTART': '1', 15:49:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:09 INFO - 'PWD': '/builds/slave/test', 15:49:09 INFO - 'SHELL': '/bin/bash', 15:49:09 INFO - 'SHLVL': '1', 15:49:09 INFO - 'TERM': 'linux', 15:49:09 INFO - 'TMOUT': '86400', 15:49:09 INFO - 'USER': 'cltbld', 15:49:09 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', 15:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:09 INFO - '_': '/tools/buildbot/bin/python'} 15:49:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:09 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:49:09 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:49:09 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:49:09 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:49:09 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:49:09 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:49:09 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:49:09 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:49:09 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:49:09 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:49: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:49: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:49: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:49: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:49:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:49:10 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:49:10 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:49:10 INFO - Installing collected packages: simplejson 15:49:10 INFO - Running setup.py install for simplejson 15:49:10 INFO - building 'simplejson._speedups' extension 15:49:10 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:49:11 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:49:11 INFO - Successfully installed simplejson 15:49:11 INFO - Cleaning up... 15:49:11 INFO - Return code: 0 15:49:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:11 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 0x28dc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2aa4b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c47df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c4b440>, '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 0x2c4c130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c46320>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', '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:49:11 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:49:11 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:49:11 INFO - Using env: {'DISPLAY': ':0', 15:49:11 INFO - 'HOME': '/home/cltbld', 15:49:11 INFO - 'LANG': 'en_US.UTF-8', 15:49:11 INFO - 'LANGUAGE': 'en_US:en', 15:49:11 INFO - 'LOGNAME': 'cltbld', 15:49:11 INFO - 'MAIL': '/var/mail/cltbld', 15:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:11 INFO - 'MOZ_NO_REMOTE': '1', 15:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:11 INFO - 'NO_EM_RESTART': '1', 15:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:11 INFO - 'PWD': '/builds/slave/test', 15:49:11 INFO - 'SHELL': '/bin/bash', 15:49:11 INFO - 'SHLVL': '1', 15:49:11 INFO - 'TERM': 'linux', 15:49:11 INFO - 'TMOUT': '86400', 15:49:11 INFO - 'USER': 'cltbld', 15:49:11 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', 15:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:11 INFO - '_': '/tools/buildbot/bin/python'} 15:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:49:12 INFO - Cleaning up... 15:49:12 INFO - Return code: 0 15:49:12 INFO - Running post-action listener: _resource_record_post_action 15:49:12 INFO - Running post-action listener: _start_resource_monitoring 15:49:12 INFO - Starting resource monitoring. 15:49:12 INFO - [mozharness: 2017-09-29 22:49:12.163132Z] Finished create-virtualenv step (success) 15:49:12 INFO - [mozharness: 2017-09-29 22:49:12.163647Z] Running install step. 15:49:12 INFO - Running pre-action listener: _resource_record_pre_action 15:49:12 INFO - Running main action method: install 15:49:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:49:12 INFO - Reading from file tmpfile_stdout 15:49:12 INFO - Detecting whether we're running mozinstall >=1.0... 15:49:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:49:12 INFO - Reading from file tmpfile_stdout 15:49:12 INFO - Output received: 15:49:12 INFO - Usage: mozinstall [options] installer 15:49:12 INFO - Options: 15:49:12 INFO - -h, --help show this help message and exit 15:49:12 INFO - -d DEST, --destination=DEST 15:49:12 INFO - Directory to install application into. [default: 15:49:12 INFO - "/builds/slave/test"] 15:49:12 INFO - --app=APP Application being installed. [default: firefox] 15:49:12 INFO - mkdir: /builds/slave/test/build/application 15:49:12 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:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:49:30 INFO - Reading from file tmpfile_stdout 15:49:30 INFO - Output received: 15:49:30 INFO - /builds/slave/test/build/application/firefox/firefox 15:49:30 INFO - Running post-action listener: _resource_record_post_action 15:49:30 INFO - [mozharness: 2017-09-29 22:49:30.481344Z] Finished install step (success) 15:49:30 INFO - [mozharness: 2017-09-29 22:49:30.481595Z] Running setup-mitmproxy step. 15:49:30 INFO - Running pre-action listener: _resource_record_pre_action 15:49:30 INFO - Running main action method: setup_mitmproxy 15:49:30 INFO - Skipping: mitmproxy is not required 15:49:30 INFO - Running post-action listener: _resource_record_post_action 15:49:30 INFO - [mozharness: 2017-09-29 22:49:30.482723Z] Finished setup-mitmproxy step (success) 15:49:30 INFO - [mozharness: 2017-09-29 22:49:30.482943Z] Running run-tests step. 15:49:30 INFO - Running pre-action listener: _resource_record_pre_action 15:49:30 INFO - Running pre-action listener: _set_gcov_prefix 15:49:30 INFO - Running main action method: run_tests 15:49:30 WARNING - Try message not found. 15:49:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:49:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:49:30 INFO - Python 2.7.3 15:49:30 INFO - Return code: 0 15:49:30 INFO - grabbing minidump binary from tooltool 15:49: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:49:30 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 0x2c4b440>, '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 0x2c4c130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c46320>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:49:30 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:49:30 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:49:30 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:49:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:49:30 INFO - Return code: 0 15:49:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:49:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:49:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:49:30 INFO - ENV: RUST_BACKTRACE is now full 15:49:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:49:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:49:30 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/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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:49:30 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/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-066 --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:49:30 INFO - Using env: {'DISPLAY': ':0', 15:49:30 INFO - 'HOME': '/home/cltbld', 15:49:30 INFO - 'LANG': 'en_US.UTF-8', 15:49:30 INFO - 'LANGUAGE': 'en_US:en', 15:49:30 INFO - 'LOGNAME': 'cltbld', 15:49:30 INFO - 'MAIL': '/var/mail/cltbld', 15:49:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:49:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:49:30 INFO - 'MOZ_ACCELERATED': '1', 15:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:30 INFO - 'MOZ_NO_REMOTE': '1', 15:49:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:49:30 INFO - 'MOZ_WEBRENDER': '1', 15:49:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:30 INFO - 'NO_EM_RESTART': '1', 15:49:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:30 INFO - 'PWD': '/builds/slave/test', 15:49:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:49:30 INFO - 'RUST_BACKTRACE': 'full', 15:49:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:49:30 INFO - 'SHELL': '/bin/bash', 15:49:30 INFO - 'SHLVL': '1', 15:49:30 INFO - 'TERM': 'linux', 15:49:30 INFO - 'TMOUT': '86400', 15:49:30 INFO - 'USER': 'cltbld', 15:49:30 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', 15:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:30 INFO - '_': '/tools/buildbot/bin/python'} 15:49:30 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/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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:49:30 INFO - mozversion application_buildid: 20170929214740 15:49:30 INFO - mozversion application_changeset: 57f68296c350469d73d788eb3695a898947b4acb 15:49:30 INFO - mozversion application_display_name: Nightly 15:49:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:49:30 INFO - mozversion application_name: Firefox 15:49:30 INFO - mozversion application_remotingname: firefox 15:49:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:49:30 INFO - mozversion application_vendor: Mozilla 15:49:30 INFO - mozversion application_version: 58.0a1 15:49:30 INFO - mozversion platform_buildid: 20170929214740 15:49:30 INFO - mozversion platform_changeset: 57f68296c350469d73d788eb3695a898947b4acb 15:49:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:49:30 INFO - mozversion platform_version: 58.0a1 15:49:30 INFO - using testdate: 1506725370 15:49:30 INFO - actual date: 1506725370 15:49:30 INFO - starting webserver on 'localhost:34158' 15:49:30 INFO - SUITE-START | Running 2 tests 15:49:30 INFO - TEST-START | dromaeo_css 15:49:30 INFO - Initialising browser for dromaeo_css test... 15:49:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34158/getInfo.html -profile /tmp/tmpsUtlEQ/profile 15:49:30 INFO - TEST-INFO | started process 1693 (/builds/slave/test/build/application/firefox/firefox http://localhost:34158/getInfo.html) 15:49:38 INFO - TEST-INFO | 1693: exit 0 15:49:39 INFO - Browser initialized. 15:49:39 INFO - Running cycle 1/1 for dromaeo_css test... 15:49:39 INFO - Using env: {'DISPLAY': ':0', 15:49:39 INFO - 'HOME': '/home/cltbld', 15:49:39 INFO - 'JSGC_DISABLE_POISONING': '1', 15:49:39 INFO - 'LANG': 'en_US.UTF-8', 15:49:39 INFO - 'LANGUAGE': 'en_US:en', 15:49:39 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:49:39 INFO - 'LOGNAME': 'cltbld', 15:49:39 INFO - 'MAIL': '/var/mail/cltbld', 15:49:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:49:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:49:39 INFO - 'MOZ_ACCELERATED': '1', 15:49:39 INFO - 'MOZ_CRASHREPORTER': '1', 15:49:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:39 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:49:39 INFO - 'MOZ_NO_REMOTE': '1', 15:49:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:49:39 INFO - 'MOZ_WEBRENDER': '1', 15:49:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:49:39 INFO - 'NO_EM_RESTART': '1', 15:49:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:49:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:39 INFO - 'PWD': '/builds/slave/test', 15:49:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:49:39 INFO - 'RUST_BACKTRACE': 'full', 15:49:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:49:39 INFO - 'SHELL': '/bin/bash', 15:49:39 INFO - 'SHLVL': '1', 15:49:39 INFO - 'TERM': 'linux', 15:49:39 INFO - 'TMOUT': '86400', 15:49:39 INFO - 'USER': 'cltbld', 15:49:39 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', 15:49:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:39 INFO - '_': '/tools/buildbot/bin/python'} 15:49:39 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsUtlEQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:49:39 INFO - PID 1938 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:49:40 INFO - PID 1938 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:49:40 INFO - PID 1938 | 15:49:40 INFO - PID 1938 | (/builds/slave/test/build/application/firefox/firefox:1997): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:49:40 INFO - PID 1938 | 15:49:50 INFO - PID 1938 | RSS: Main: 201023488 15:49:50 INFO - PID 1938 | 15:52:48 INFO - PID 1938 | Cycle 1(1): loaded http://localhost:34158/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34158/tests/dromaeo/cssquery-ext.html) 15:52:49 INFO - PID 1938 | RSS: Main: 200187904 15:52:49 INFO - PID 1938 | 15:55:51 INFO - PID 1938 | Cycle 1(1): loaded http://localhost:34158/tests/dromaeo/cssquery-ext.html (next: http://localhost:34158/tests/dromaeo/cssquery-jquery.html) 15:55:52 INFO - PID 1938 | RSS: Main: 199565312 15:55:52 INFO - PID 1938 | 15:58:54 INFO - PID 1938 | Cycle 1(1): loaded http://localhost:34158/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34158/tests/dromaeo/cssquery-mootools.html) 15:58:55 INFO - PID 1938 | RSS: Main: 200814592 15:58:55 INFO - PID 1938 | 16:01:57 INFO - PID 1938 | Cycle 1(1): loaded http://localhost:34158/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34158/tests/dromaeo/cssquery-prototype.html) 16:01:57 INFO - PID 1938 | RSS: Main: 200630272 16:01:57 INFO - PID 1938 | 16:05:01 INFO - PID 1938 | Cycle 1(1): loaded http://localhost:34158/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34158/tests/dromaeo/cssquery-yui.html) 16:05:02 INFO - PID 1938 | RSS: Main: 203288576 16:05:02 INFO - PID 1938 | 16:08:04 INFO - PID 1938 | Cycle 1(1): loaded http://localhost:34158/tests/dromaeo/cssquery-yui.html (next: http://localhost:34158/tests/dromaeo/cssquery-dojo.html) 16:08:04 INFO - PID 1938 | RSS: Main: 204840960 16:08:04 INFO - PID 1938 | 16:08:04 INFO - PID 1938 | __start_tp_report 16:08:04 INFO - PID 1938 | _x_x_mozilla_page_load 16:08:04 INFO - PID 1938 | _x_x_mozilla_page_load_details 16:08:04 INFO - PID 1938 | |i|pagename|runs| 16:08:04 INFO - PID 1938 | |0;dojo.html;3123.4690102682544;3149.165471150144;3187.7131058204764;3236.110069730824;3264.2002709336216;6110.755569777203;6188.443040126388;6217.067439884026;6227.844303892405;6245.843853903654;6246.906296405557;6249.218847644044;6257.4368306852375;6263.906041409383;6283.120363149163;5748.9651862664805;5770.971145144289;5816.651000939936;5837.708114594275;5919.704014799243;5828.125781132821;5885.823425297248;5938.910916336236;5981.671008094553;5996.7301471433675;5755.712214389285;5807.332156801979;5827.475527202551;5827.533797296206;5853.034249348852;5652.152177173416;5709.4290570942985;5802;5893.499052580536;5915.970420147914;5661.8584535386535;5790.884182316372;5793.333766616851;5817.156512305734;5870.530357571384;6265.87468250637;6327.588706734094;6344.841378965517;6401.455876250524;6421.454176395012;6627.536072474929;6724.798256052326;6735.629540375277;6741.056252124707;6796.966015169893;6829.385355317995;6895.965520172367;6916.757913473004;6921.688524016431;6941.652917354112;6293.118963345135;6349.682515874188;6369.490440764727;6384.680765961683;6428.325025872263;6980.476464265201;7020.859582808315;7022.367986881205;7109.502334836512;7140.607266600283;6447;6457.418832305115;6529.95520716683;6532.967335163294;6601.339866013456;6601.2738598754095;6650.168728908887;6690.59856408617;6709.731610735614;6728.293529179366;6630.270670226271;6685.732570697118;6740.865182696319;6764.985252212109;6824.93175068243;6829.078074459884;6861.4853885958755;6904.689288982008;6908.620025898624;6924.549904256207;5337.733113344318;5343.759530821122;5405.891882162335;5415.512603865593;5419.756110975016;4524.09518096382;4574.222382195035;4604.102200071021;4634.976825115853;4647.6281897448125;5733.254676891977;5745.942540574624;5777.191193232867;5800.738966746507;5881.9705901470215;6819;6842;6849;6901.447884169254;6904.3095690430555;5847.619904706181;5872.677002764804;5904.232449781501;5905.46850783423;5923.229980102559;5808.273965754281;5887.735051922674;5891.381404952418;5918.615290006136;5929.436703513159;6338;6436.935630643634;6507.544471886922;6508.511861610398;6615.933840661628;6724.798256052326;6781.966090169518;6794.558353706993;6860.656967151723;6865.485088618374;6593.571417857824;6623.701933413105;6648.6010839347855;6661.300563440963;6687.063811066358;6633.336666333328;6662;6731.899021514779;6826.522143449911;6833.043373927753;6611.636360000054;6666.699998499982;6737.157855268091;6823.317668233137;6926.688299026657;6488.480921526361;6516.543841931019;6542.247641521286;6543;6546.410823025951;6435.2599451063725;6546.0508226307875;6671.9658452939875;6674.966625167037;6686.431653309523;6563.146790917151;6610.3389661035435;6627.900581491185;6643.335666433318;6652.767153149616;6618.305077966744;6636.568623039487;6678.465722742072;6681.064650948968;6753.797386078426;6208;6332.588381755171;6348.682565871595;6473.417392434703;6492.902606460812 16:08:04 INFO - PID 1938 | |1;ext.html;881.0484676549327;902.9051949545302;903.169084442313;906.8005038891446;909.7043460875213;39299.41050884235;42740.78629606855;42754.57245427548;42790.78604606973;42860.142797144035;40084;40171;40320;40506.79746601263;40531.39202911959;7360.2271761464945;7439.330460258576;7480.214577469369;7503.362214211798;7516.624168791539;7369.152547457057;7374.852502949938;7448.180700122982;7511.849763004737;7522.962385188066;7390.815229619276;7421.81445463865;7458.552486850779;7476.962615186916;7485;7419.629018549078;7460.32857042866;7947.801304967365;8145.104038555754;8164.061132969725;7982.760517184435;8008.319292860114;8076.111627720946;8077.555734434603;8078.111407745143;38640.42039369412;39080.413793793115;39102.21795564073;39252.21495570073;39328.21343573112;3402.3705614461405;3424.9486257706153;3458.654134586521;3495.702865256431;3516.156122530574;32929.506057409155;33001;33157.6684233157;33239.50140747891;33582;5130;5148.768305426227;5215.1134307167495;5240.580753539746;5312.787488500456;208539;209175.95412022996;209226;209889;210146;43186.13627727428;43808.78095609502;51411;51700.74149629228;51837;51506.48493515092;52116.47883521192;52712;52810;52863;16468;16498.092604906844;16675.583110422336;16741.162941852857;16746.078965657005;13680.31598420075;13718.588442346745;13750.724985500234;13794.379252933642;13797.24115173655;13496.730065398637;13655.38550765218;13819.378127984264;13890.791638125436;14048.508302209373;5486.478784515464;5611.410801865827;5661;5668.943310566924;5776.364599894008;968.3705591365668;977.3012296208103;977.6480467031863;988.1600639456548;1004.6232662751469;22957.311280661608;23039.88480057589;23223.303300901;23503.17738879131;23633.29100126999;1065.94137322448;1105.7456784939347;1106.5241945963312;1107.2194103157256;1113.4043286841502;12357.75284494305;12427.37863106841;12454.875451245372;12561.434735436927;12575;247530;249656;249771;250345;251053;54542.72728636331;54549.72725136349;54575.45424545704;54657.72671136698;54942.450575494535;1420.3324437514352;1474.0934325389808;1478.3569147420692;1479.1568805780603;1500.9774853377207;22911.65632515547;23036.4240893979;23135.88432057829;23141.07435702553;23259.651105233097;36786.26427471489;36811.815940920125;36989;37159.28407159262;37163.81418092892;195018;195749;196178;197109;197194;31602.367952641278;32414.51378229281;34831.30337393289;35031.82484087563;35227.119322017155;43529.78235108804;43911.78044109759;44210;44508.33237501375;44862.55137448714;31651.52522712115;31959.680403196602;31981.360372791947;32303.35393292168;32458.188545286557;31143.549878771577;31391.21521961969;31427;31612.367752645278;31902;35880.820595896854;36197.63802361942;36207.094822629646;36250.27499450049;36352.45471317985;30930.69069309278;31392.529112063796;31412;31493.212669683442;32056.35887282288;23350.065997359914;23561.29316120519;23616.409589760395;23902.760972390748;24018.87990560036 16:08:04 INFO - PID 1938 | |2;jquery.html;571.1746526269542;578.722213337598;597.2236093078997;600.849787553112;611.5657882903139;22522.54954900903;23289.534209315847;23298.301050968457;23326.41683957902;23355.182568610082;24364.87817560914;24525.509489810192;24558.631620525663;24682.75317246827;25096.874515627398;6606.570572912758;6655.900161497581;6718.529702920798;6757.189137303506;6789.558678685894;6271.153394291758;6526.608403495782;6766.729330826761;6767.56010859295;6771.052052712625;6456.515761317896;6497.3827486388955;6527.902081468782;6533.05270735743;6557.278699343068;6533.771318003847;6539.117219175422;6541.803745887607;6546.247181574132;6547.836304092388;5838.854028649275;6040.848978775522;6064.696765161724;6071.180390647294;6098.298695650013;27532.311692207655;28490.430191396055;28585.285367865763;28593;28593.71406285931;1940.0784627301919;1960.284496158906;1974.2497850816742;1981.2273213446767;1985.186073709772;18691.439256822316;19031.714524282146;19277.132529036226;19435.22259109634;19446.513837154183;2404.326788499223;2456.3367890669597;2476.7151777545632;2477.256822953107;2479.962800557993;122624;129617;130817;136466;144941;14453.101942034027;14543.545645435372;14695.559133226021;14742.41030358795;15033.947623666458;22552.887235563718;22866;22953.196638117584;23153.536929261656;23185.3044408668;10038.64864729731;10046.296759226783;10075.647352342632;10103.797924041479;10281.383117013003;9219.400738951947;9311.580978855967;9398.107179817905;9451.527423628791;9537.523123843781;8729.738107856774;8784;8871.112888711206;8979.281657467387;9110.726678199664;2675.331167208198;2761.3648860761737;2770.34896799252;2786.8049236553247;2791.706870778539;938.666773295484;956.4309236004566;966.6761634852263;967.7628980899725;978.9657361992296;18534.44396668102;18948.715769263472;19172.71240931387;19523.316683915993;19704.802951970298;1022.1005515146624;1022.6734179741;1036.6371769880482;1043.8747350317858;1044.3577200021932;9977.950110249401;10401.94799026;10454.268201225994;10575.576976920991;10757.56969721218;465591;484719;487627;490361;492996;108120;113375.43312283386;113987;114595;114623;1163.831244469547;1169.8245263210417;1200.3938011304235;1200.8018676918211;1209.6371088673538;16900.40848570294;17045.48863534096;17236.310547578458;17506.387276445264;17665.82334176642;54480.455195449125;56567.71716141393;56955;57381;57872.421275786706;70039.64980175067;70194.64902675658;70449.64775176092;70561.64719176576;71225.28774712187;14354.49759258421;14444.638884027983;14563.92718036403;14734.852651473348;14745.336459859118;22691.432714182276;22895.65656515187;23066.423339416648;23157;23464.6480302799;18118.365857194935;18143.818561814212;18221.726674100166;18391.816081839544;18662;15008.324625392102;15086.396544138111;15536.92231538835;16612.750808737637;16649.41727039548;18059.18733657015;18164.636707266043;18500.814991849908;18638.72041919345;18765.71851422257;14122.576322710334;14266.358013889192;14328.14031158134;14350.856491434952;14409.927950360181;18145.45563633093;18332.266709331478;18373.632527349644;18477.72283415723;18489.445316640522 16:08:04 INFO - PID 1938 | |3;mootools.html;1342.7311190924577;1384.2525036480297;1389.7220555888825;1402.291842619477;1417.178036738691;14471.855281447182;15032.624184395369;15152.1666308353;15265.084094954296;15311.770323445156;14960.401583936657;15062.096274223555;15065;15065.924670376633;15098.698026039472;2579.651747014148;2675.8929642814264;2719.3745438549145;2723.564229723245;2726.82275652082;2728.0724553651753;2816.098848368523;2839.488891999439;2840.2757296889367;2842.4315136972587;2727.4545090981783;2739.315171207198;2739.630149929764;2771.2517620242525;2781.123945957027;2774.5838124281318;2779.138467075203;2819.3233623930314;2824.166870773127;2828.61813655156;2654.0710751237107;2704.148193319118;2722.863856807152;2741.9588706169425;2749.4501099780123;14451.421943122263;14819.925900370428;14944.476943307041;14974.62563435912;15010.324535395934;7906.881396779053;8029.598520073973;8142.959285203596;8165.877511837327;8171.223733745344;5623.8594035149035;5724.16999535065;5748.936446757363;5866.76532938682;5886.440788125121;1925.4512463947708;1929.3440230321617;1962.4897526643176;1984.5435549823478;1998.890061046642;25737.485250295078;25817.870910645514;26397.07610233652;26793.46413071747;26815.865920670272;6933.4106600939485;6947.305269473113;6948.756793512202;7142.678579463936;7205.315495027964;17072.317107315816;17113.91443042777;17134.914325428294;17163.112057673647;17165.57086072833;8095.190480951857;8150;8174.591270436455;8211.548364839991;8223.05434874985;7204.53170543913;7338.302861228175;7352.816179595553;7421;7526.698932042657;5192.584593232532;5311.628186027018;5321;5333.093374126408;5367.114426119647;1465.765477523591;1468.7833644318907;1476.5201309574431;1478.3716920308825;1503.5188739603223;881.7839629290864;918.9540522973824;923.5474617437408;923.8521836506127;927.3554879358916;7424.591647459441;7485.887711684329;7538.434617403607;8774.912250877538;8785.253253473398;721.7509959064115;726.7783326085595;733.5268751655259;737.3511310047231;757.9696812127453;4708.152532544175;4771.093492236464;5406.887475323558;5407.459254074561;5562.332520097533;19347.322843700404;19916.402507924784;20397.49006274855;20532.486687832923;21492.677609835864;20082.39752807418;21019.8949005254;21082.683759743617;21212.25757098494;21567.78432215689;9126.361154719107;9493.240540756722;10418.322809017389;10630.30902991303;10639.202059845542;9038.367314287936;9064.456132632064;9074.410163339362;9118.635254589743;9205.723828285161;18297.81702182961;18402.81597184011;18531.629367412308;18559.721604175676;18700.625987479903;19245.13396897115;19292.903535482234;19293.517662058563;19516.121774519896;19795.604087918447;10735.570577176826;10893.237473376788;10894.237403382003;10986;11113.110951123728;13475.460981560627;13662.521811736542;13689.794653080013;13695.383707732977;13701.177929324273;11267.154963377943;11416;11488.597899073493;11508.36703981289;11747.353895535827;10945.179111566506;11064.170187235828;11119;11180.161487888277;11321;9836.655717049869;10049.246306526893;10092.899071009197;10116.140128089195;10123.493825308853;7410.258974102547;7514.774556763306;7533.472656913963;7608.353289970193;7613.847723045619;7583.658735357032;7623.4663573549315;7698.422618303762;7712.421568382506;7733.38132949374 16:08:04 INFO - PID 1938 | |4;prototype.html;508.32139094309093;515.4433212130901;539.6114797345911;543.2530270877544;551.2365373957068;6092.725827337769;6118.694065296734;6150.939435289178;6172.567920245585;6211.906821397672;6057.879292330922;6067.150598916155;6088.3302836688035;6127.3872612738705;6142.385761423855;5008.974955125228;5113.284140220372;5127.512886275797;5156.278121063054;5169.612279079065;5128.743562821863;5133.328670909473;5155.7422128893595;5158.664686795365;5185.870353241162;4701.9059618807605;4844.07962487128;4874.65877388583;4907.877303067434;5023.271625614284;4881.194602890519;4943.258511223309;4950.653454258203;4951.504849515056;4971.701697898137;4429.844955426577;4437.378766972626;4458.420405347284;4473.776311184428;4480.731156130642;5932.3474417814;6031.849203769941;6032.849178770522;6045.6674882881425;6063.18147050147;5675.914861277084;5684.317881854163;5691.743871525791;5728.484436400702;5734.741936612862;6414.679266036726;6444.065610486453;6465.288818229991;6504.902426463606;6515.348465153446;4616.8614941551805;4833.516648335173;4869.561739443467;4888.73111978841;4889.633277504166;6392.264889537716;6551.148350714424;6639.86720265592;6702.932970670328;6709.865802683968;4911.87720306988;4951.480094590088;4960.727160006162;4970.627202959792;5028.145215313405;4994.875128121754;5021.347224860818;5024.874378140576;5037.168867136881;5077.416097148801;5572.164175373727;5573.303337082864;5587.944120558743;5592.944070559242;5655.1517272409465;6409.359064093646;6421.325760795143;6463.15978922737;6494;6499.562664001;1723.284836792742;1740.6779745746824;1741.2164525963367;1752.5706201980342;1758.7977382601164;1167.3521195736448;1204.3556697166973;1231.593574120538;1232.5377983256278;1242.6396345059832;5539.845116162878;5560.555155587544;5606.794539174097;5655.745491452894;5664.150377443417;6392.328805475451;6412.012276194395;6524.478041756648;6539.574927629689;6558.639274839834;5466.644668096561;5655.86882623477;5656.8020119295625;5679.545636349082;5716.171155182558;3363.5291059251726;3435.7251419886347;3469.548958635361;3492.6158122606494;3532.604621174673;6274.341194174546;6290.748370065146;6301.4328710416285;6309.3690630936535;6372.36276372359;6669.599824010575;6679.298673639295;6738.157730283715;6754.6284954327;6792.218894827158;5888.617239879394;6163.630182189083;6323.747050118036;6351.777687780906;6361.459275961595;6435.358983459375;6515.153030106246;6520.608763474207;6522.608643481406;6568.014797780371;6437.903431448438;6467.159269294962;6495.382938620824;6501.139498620591;6656.30108838565;6354.4598709108395;6375.2149398168585;6404.743810247538;6416.882428798392;6420.807375778734;4903.215485522299;4979.751012449291;4994.225894986283;4996.15065438869;5027.095122877917;6298.84252893663;6322.841928951813;6351.490441786384;6584.604923704593;6584.934150658432;6224.968875155595;6352.333005034589;6379.808605741649;6394.616323020634;6418.839529011812;6360.809175724736;6389.329120442472;6439.7424103035355;6441.874181520181;6459.967700161469;6296.905546416899;6308.369163083655;6323.114763932961;6324.3991820776955;6374.171357723466;6376.840578985562;6393.264774550986;6462.159919210472;6468.8706225874275;6488.286288508166;6247.718852651551;6257.655828929453;6260.436560709558;6265.932242359919;6284.497240220863 16:08:04 INFO - PID 1938 | |5;yui.html;831.7920519870032;860.6686425726093;875.6191056890253;915.3318077803203;927.4481683398376;19094.331698390542;19477.220911163535;19491.805081949176;19492.415227543177;19540.413787586396;18513.907430462863;18778.718319225223;18787;18793.90603046983;18812.341568045085;958.6932181701858;978.7259567321162;986.2750878104623;988.3575675810745;992.910638042576;962.1725316228038;972.8443449048153;975.5366201054478;977.218225419665;978.432509144698;981.7938232971085;1022.3610243597751;1027.3014350241833;1032.5611615063606;1032.901874321942;1019.9847002294972;1023.8566600675912;1025.9794804103951;1027.3168343051861;1029.711680729397;717.3292971072065;742.3949481172826;743.1490942870437;746.0301607909696;751.1024325930515;20368.490787730276;20660.793392066036;20696;20816.47958801027;20944.685829712565;21597.24409645655;21699.891500542395;22446.887765561067;22503.437414064614;22662;17291.56771080733;17332.740008899877;17457.610213445067;17490;17589.472315830546;3578.7315951303494;3617.0595645132194;3637.963620363789;3643.344198044351;3643.362411577963;110594;141616;142032;143415;144651;17735.201915913814;17861.821381786274;17978.280868765363;18078;18118.275268989357;32690.182745431553;33178.00465986024;33183.668163318056;33237.3352532948;33272.3345533088;8641.30869530436;8811.295096392274;8824.382293239412;8894.421862579042;8957.641694332153;4113.362428823537;4231.344141658048;4317.287647538184;4341.630961368256;4364.454443194601;5331.86670333237;5341.5459685927135;5425.891482170335;5429.755660995264;5445.264889239902;1075.6665433715573;1090.1333439915286;1093.3603841752504;1101.686019484443;1101.8402331661864;676.509530590318;696.7874798186601;698.2284575544053;701.0815831261065;713.0516413170575;11800;11959.461824217931;12151.939240303742;12197.250382722419;12573.99408047354;671.4460570029745;672.5191488086042;679.6533767778468;683.064202043204;696.0498918975562;5575.665460072409;5670.06443936754;5700.116481945305;5702.7718891243885;5810.738516766755;119074;119108;120152;122518;122564.77435225713;98208;99146.50426747964;99271.50364248133;100088.49955750321;101824.49087754662;95424.52287738655;95630.5218473917;97364;98097.50951245199;99224;10405.531751071372;10554.947225263824;10720.571177152826;10738.516766745672;10784.406857622593;42855.785721071195;44196.55803441924;44417.333739994574;44621.77689111534;44845.55154448544;56898.43101568931;57103;59251.40748592459;59252.111218330894;59513;15170.848291516944;15399.692006160038;15486.148261845707;15488.380464781283;15614.063156210665;18478.63042739111;18599.256029758657;18609.44171674852;18703.719444208076;19040.428787136407;16569.33722651129;16587.25357358946;16922;16959.576010599834;17157.056361899715;17093.658126837145;17267.82732172662;17278.827211728225;17371.478855634352;17419.129043548026;16253.918730406745;16508.25712842901;16552.42066527666;16592.004479731255;16763.832361676228;9399.295052870926;9733.221342292458;9782.951085244527;9996.300258982092;10035.648752293635;9497.382670126419;9522;9654.855177172207;9752.756181095529;9832.26258030665 16:08:04 INFO - PID 1938 | __end_tp_report 16:08:04 INFO - PID 1938 | __start_cc_report 16:08:04 INFO - PID 1938 | _x_x_mozilla_cycle_collect,275 16:08:04 INFO - PID 1938 | __end_cc_report 16:08:04 INFO - PID 1938 | __startTimestamp1506726484391__endTimestamp 16:08:04 INFO - PID 1938 | 16:08:04 INFO - PID 1938 | ------- Summary: start ------- 16:08:04 INFO - PID 1938 | Number of tests: 6 16:08:04 INFO - PID 1938 | 16:08:04 INFO - PID 1938 | [#0] dojo.html Cycles:165 Average:6230.88 Median:6457.42 stddev:749.78 (11.6%) stddev-sans-first:711.35 16:08:04 INFO - PID 1938 | Values: 3123.5 3149.2 3187.7 3236.1 3264.2 6110.8 6188.4 6217.1 6227.8 6245.8 6246.9 6249.2 6257.4 6263.9 6283.1 5749.0 5771.0 5816.7 5837.7 5919.7 5828.1 5885.8 5938.9 5981.7 5996.7 5755.7 5807.3 5827.5 5827.5 5853.0 5652.2 5709.4 5802.0 5893.5 5916.0 5661.9 5790.9 5793.3 5817.2 5870.5 6265.9 6327.6 6344.8 6401.5 6421.5 6627.5 6724.8 6735.6 6741.1 6797.0 6829.4 6896.0 6916.8 6921.7 6941.7 6293.1 6349.7 6369.5 6384.7 6428.3 6980.5 7020.9 7022.4 7109.5 7140.6 6447.0 6457.4 6530.0 6533.0 6601.3 6601.3 6650.2 6690.6 6709.7 6728.3 6630.3 6685.7 6740.9 6765.0 6824.9 6829.1 6861.5 6904.7 6908.6 6924.5 5337.7 5343.8 5405.9 5415.5 5419.8 4524.1 4574.2 4604.1 4635.0 4647.6 5733.3 5745.9 5777.2 5800.7 5882.0 6819.0 6842.0 6849.0 6901.4 6904.3 5847.6 5872.7 5904.2 5905.5 5923.2 5808.3 5887.7 5891.4 5918.6 5929.4 6338.0 6436.9 6507.5 6508.5 6615.9 6724.8 6782.0 6794.6 6860.7 6865.5 6593.6 6623.7 6648.6 6661.3 6687.1 6633.3 6662.0 6731.9 6826.5 6833.0 6611.6 6666.7 6737.2 6823.3 6926.7 6488.5 6516.5 6542.2 6543.0 6546.4 6435.3 6546.1 6672.0 6675.0 6686.4 6563.1 6610.3 6627.9 6643.3 6652.8 6618.3 6636.6 6678.5 6681.1 6753.8 6208.0 6332.6 6348.7 6473.4 6492.9 16:08:04 INFO - PID 1938 | 16:08:04 INFO - PID 1938 | [#1] ext.html Cycles:180 Average:38758.90 Median:35553.97 stddev:57006.11 (160.3%) stddev-sans-first:57095.08 16:08:04 INFO - PID 1938 | Values: 881.0 902.9 903.2 906.8 909.7 39299.4 42740.8 42754.6 42790.8 42860.1 40084.0 40171.0 40320.0 40506.8 40531.4 7360.2 7439.3 7480.2 7503.4 7516.6 7369.2 7374.9 7448.2 7511.8 7523.0 7390.8 7421.8 7458.6 7477.0 7485.0 7419.6 7460.3 7947.8 8145.1 8164.1 7982.8 8008.3 8076.1 8077.6 8078.1 38640.4 39080.4 39102.2 39252.2 39328.2 3402.4 3424.9 3458.7 3495.7 3516.2 32929.5 33001.0 33157.7 33239.5 33582.0 5130.0 5148.8 5215.1 5240.6 5312.8 208539.0 209176.0 209226.0 209889.0 210146.0 43186.1 43808.8 51411.0 51700.7 51837.0 51506.5 52116.5 52712.0 52810.0 52863.0 16468.0 16498.1 16675.6 16741.2 16746.1 13680.3 13718.6 13750.7 13794.4 13797.2 13496.7 13655.4 13819.4 13890.8 14048.5 5486.5 5611.4 5661.0 5668.9 5776.4 968.4 977.3 977.6 988.2 1004.6 22957.3 23039.9 23223.3 23503.2 23633.3 1065.9 1105.7 1106.5 1107.2 1113.4 12357.8 12427.4 12454.9 12561.4 12575.0 247530.0 249656.0 249771.0 250345.0 251053.0 54542.7 54549.7 54575.5 54657.7 54942.5 1420.3 1474.1 1478.4 1479.2 1501.0 22911.7 23036.4 23135.9 23141.1 23259.7 36786.3 36811.8 36989.0 37159.3 37163.8 195018.0 195749.0 196178.0 197109.0 197194.0 31602.4 32414.5 34831.3 35031.8 35227.1 43529.8 43911.8 44210.0 44508.3 44862.6 31651.5 31959.7 31981.4 32303.4 32458.2 31143.5 31391.2 31427.0 31612.4 31902.0 35880.8 36197.6 36207.1 36250.3 36352.5 30930.7 31392.5 31412.0 31493.2 32056.4 23350.1 23561.3 23616.4 23902.8 24018.9 16:08:04 INFO - PID 1938 | 16:08:04 INFO - PID 1938 | [#2] jquery.html Cycles:180 Average:34212.29 Median:23109.98 stddev:81478.40 (352.6%) stddev-sans-first:81667.81 16:08:04 INFO - PID 1938 | Values: 571.2 578.7 597.2 600.8 611.6 22522.5 23289.5 23298.3 23326.4 23355.2 24364.9 24525.5 24558.6 24682.8 25096.9 6606.6 6655.9 6718.5 6757.2 6789.6 6271.2 6526.6 6766.7 6767.6 6771.1 6456.5 6497.4 6527.9 6533.1 6557.3 6533.8 6539.1 6541.8 6546.2 6547.8 5838.9 6040.8 6064.7 6071.2 6098.3 27532.3 28490.4 28585.3 28593.0 28593.7 1940.1 1960.3 1974.2 1981.2 1985.2 18691.4 19031.7 19277.1 19435.2 19446.5 2404.3 2456.3 2476.7 2477.3 2480.0 122624.0 129617.0 130817.0 136466.0 144941.0 14453.1 14543.5 14695.6 14742.4 15033.9 22552.9 22866.0 22953.2 23153.5 23185.3 10038.6 10046.3 10075.6 10103.8 10281.4 9219.4 9311.6 9398.1 9451.5 9537.5 8729.7 8784.0 8871.1 8979.3 9110.7 2675.3 2761.4 2770.3 2786.8 2791.7 938.7 956.4 966.7 967.8 979.0 18534.4 18948.7 19172.7 19523.3 19704.8 1022.1 1022.7 1036.6 1043.9 1044.4 9978.0 10401.9 10454.3 10575.6 10757.6 465591.0 484719.0 487627.0 490361.0 492996.0 108120.0 113375.4 113987.0 114595.0 114623.0 1163.8 1169.8 1200.4 1200.8 1209.6 16900.4 17045.5 17236.3 17506.4 17665.8 54480.5 56567.7 56955.0 57381.0 57872.4 70039.6 70194.6 70449.6 70561.6 71225.3 14354.5 14444.6 14563.9 14734.9 14745.3 22691.4 22895.7 23066.4 23157.0 23464.6 18118.4 18143.8 18221.7 18391.8 18662.0 15008.3 15086.4 15536.9 16612.8 16649.4 18059.2 18164.6 18500.8 18638.7 18765.7 14122.6 14266.4 14328.1 14350.9 14409.9 18145.5 18332.3 18373.6 18477.7 18489.4 16:08:04 INFO - PID 1938 | 16:08:04 INFO - PID 1938 | [#3] mootools.html Cycles:180 Average:9288.40 Median:2711.76 stddev:6529.72 (240.8%) stddev-sans-first:6520.74 16:08:04 INFO - PID 1938 | Values: 1342.7 1384.3 1389.7 1402.3 1417.2 14471.9 15032.6 15152.2 15265.1 15311.8 14960.4 15062.1 15065.0 15065.9 15098.7 2579.7 2675.9 2719.4 2723.6 2726.8 2728.1 2816.1 2839.5 2840.3 2842.4 2727.5 2739.3 2739.6 2771.3 2781.1 2774.6 2779.1 2819.3 2824.2 2828.6 2654.1 2704.1 2722.9 2742.0 2749.5 14451.4 14819.9 14944.5 14974.6 15010.3 7906.9 8029.6 8143.0 8165.9 8171.2 5623.9 5724.2 5748.9 5866.8 5886.4 1925.5 1929.3 1962.5 1984.5 1998.9 25737.5 25817.9 26397.1 26793.5 26815.9 6933.4 6947.3 6948.8 7142.7 7205.3 17072.3 17113.9 17134.9 17163.1 17165.6 8095.2 8150.0 8174.6 8211.5 8223.1 7204.5 7338.3 7352.8 7421.0 7526.7 5192.6 5311.6 5321.0 5333.1 5367.1 1465.8 1468.8 1476.5 1478.4 1503.5 881.8 919.0 923.5 923.9 927.4 7424.6 7485.9 7538.4 8774.9 8785.3 721.8 726.8 733.5 737.4 758.0 4708.2 4771.1 5406.9 5407.5 5562.3 19347.3 19916.4 20397.5 20532.5 21492.7 20082.4 21019.9 21082.7 21212.3 21567.8 9126.4 9493.2 10418.3 10630.3 10639.2 9038.4 9064.5 9074.4 9118.6 9205.7 18297.8 18402.8 18531.6 18559.7 18700.6 19245.1 19292.9 19293.5 19516.1 19795.6 10735.6 10893.2 10894.2 10986.0 11113.1 13475.5 13662.5 13689.8 13695.4 13701.2 11267.2 11416.0 11488.6 11508.4 11747.4 10945.2 11064.2 11119.0 11180.2 11321.0 9836.7 10049.2 10092.9 10116.1 10123.5 7410.3 7514.8 7533.5 7608.4 7613.8 7583.7 7623.5 7698.4 7712.4 7733.4 16:08:04 INFO - PID 1938 | 16:08:04 INFO - PID 1938 | [#4] prototype.html Cycles:180 Average:5416.57 Median:6060.53 stddev:1492.87 (24.6%) stddev-sans-first:1450.89 16:08:04 INFO - PID 1938 | Values: 508.3 515.4 539.6 543.3 551.2 6092.7 6118.7 6150.9 6172.6 6211.9 6057.9 6067.2 6088.3 6127.4 6142.4 5009.0 5113.3 5127.5 5156.3 5169.6 5128.7 5133.3 5155.7 5158.7 5185.9 4701.9 4844.1 4874.7 4907.9 5023.3 4881.2 4943.3 4950.7 4951.5 4971.7 4429.8 4437.4 4458.4 4473.8 4480.7 5932.3 6031.8 6032.8 6045.7 6063.2 5675.9 5684.3 5691.7 5728.5 5734.7 6414.7 6444.1 6465.3 6504.9 6515.3 4616.9 4833.5 4869.6 4888.7 4889.6 6392.3 6551.1 6639.9 6702.9 6709.9 4911.9 4951.5 4960.7 4970.6 5028.1 4994.9 5021.3 5024.9 5037.2 5077.4 5572.2 5573.3 5587.9 5592.9 5655.2 6409.4 6421.3 6463.2 6494.0 6499.6 1723.3 1740.7 1741.2 1752.6 1758.8 1167.4 1204.4 1231.6 1232.5 1242.6 5539.8 5560.6 5606.8 5655.7 5664.2 6392.3 6412.0 6524.5 6539.6 6558.6 5466.6 5655.9 5656.8 5679.5 5716.2 3363.5 3435.7 3469.5 3492.6 3532.6 6274.3 6290.7 6301.4 6309.4 6372.4 6669.6 6679.3 6738.2 6754.6 6792.2 5888.6 6163.6 6323.7 6351.8 6361.5 6435.4 6515.2 6520.6 6522.6 6568.0 6437.9 6467.2 6495.4 6501.1 6656.3 6354.5 6375.2 6404.7 6416.9 6420.8 4903.2 4979.8 4994.2 4996.2 5027.1 6298.8 6322.8 6351.5 6584.6 6584.9 6225.0 6352.3 6379.8 6394.6 6418.8 6360.8 6389.3 6439.7 6441.9 6460.0 6296.9 6308.4 6323.1 6324.4 6374.2 6376.8 6393.3 6462.2 6468.9 6488.3 6247.7 6257.7 6260.4 6265.9 6284.5 16:08:04 INFO - PID 1938 | 16:08:04 INFO - PID 1938 | [#5] yui.html Cycles:180 Average:24139.56 Median:22582.72 stddev:34483.56 (152.7%) stddev-sans-first:34535.89 16:08:04 INFO - PID 1938 | Values: 831.8 860.7 875.6 915.3 927.4 19094.3 19477.2 19491.8 19492.4 19540.4 18513.9 18778.7 18787.0 18793.9 18812.3 958.7 978.7 986.3 988.4 992.9 962.2 972.8 975.5 977.2 978.4 981.8 1022.4 1027.3 1032.6 1032.9 1020.0 1023.9 1026.0 1027.3 1029.7 717.3 742.4 743.1 746.0 751.1 20368.5 20660.8 20696.0 20816.5 20944.7 21597.2 21699.9 22446.9 22503.4 22662.0 17291.6 17332.7 17457.6 17490.0 17589.5 3578.7 3617.1 3638.0 3643.3 3643.4 110594.0 141616.0 142032.0 143415.0 144651.0 17735.2 17861.8 17978.3 18078.0 18118.3 32690.2 33178.0 33183.7 33237.3 33272.3 8641.3 8811.3 8824.4 8894.4 8957.6 4113.4 4231.3 4317.3 4341.6 4364.5 5331.9 5341.5 5425.9 5429.8 5445.3 1075.7 1090.1 1093.4 1101.7 1101.8 676.5 696.8 698.2 701.1 713.1 11800.0 11959.5 12151.9 12197.3 12574.0 671.4 672.5 679.7 683.1 696.0 5575.7 5670.1 5700.1 5702.8 5810.7 119074.0 119108.0 120152.0 122518.0 122564.8 98208.0 99146.5 99271.5 100088.5 101824.5 95424.5 95630.5 97364.0 98097.5 99224.0 10405.5 10554.9 10720.6 10738.5 10784.4 42855.8 44196.6 44417.3 44621.8 44845.6 56898.4 57103.0 59251.4 59252.1 59513.0 15170.8 15399.7 15486.1 15488.4 15614.1 18478.6 18599.3 18609.4 18703.7 19040.4 16569.3 16587.3 16922.0 16959.6 17157.1 17093.7 17267.8 17278.8 17371.5 17419.1 16253.9 16508.3 16552.4 16592.0 16763.8 9399.3 9733.2 9783.0 9996.3 10035.6 9497.4 9522.0 9654.9 9752.8 9832.3 16:08:04 INFO - PID 1938 | -------- Summary: end -------- 16:08:04 INFO - PID 1938 | 16:08:05 INFO - TEST-INFO | 1938: exit 0 16:08:05 INFO - TEST-OK | dromaeo_css | took 1114316ms 16:08:05 INFO - TEST-START | kraken 16:08:05 INFO - Initialising browser for kraken test... 16:08:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34158/getInfo.html -profile /tmp/tmp6DsWRi/profile 16:08:05 INFO - TEST-INFO | started process 2311 (/builds/slave/test/build/application/firefox/firefox http://localhost:34158/getInfo.html) 16:08:11 INFO - TEST-INFO | 2311: exit 0 16:08:11 INFO - Browser initialized. 16:08:11 INFO - Running cycle 1/1 for kraken test... 16:08:11 INFO - Using env: {'DISPLAY': ':0', 16:08:11 INFO - 'HOME': '/home/cltbld', 16:08:11 INFO - 'JSGC_DISABLE_POISONING': '1', 16:08:11 INFO - 'LANG': 'en_US.UTF-8', 16:08:11 INFO - 'LANGUAGE': 'en_US:en', 16:08:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:08:11 INFO - 'LOGNAME': 'cltbld', 16:08:11 INFO - 'MAIL': '/var/mail/cltbld', 16:08:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:08:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:08:11 INFO - 'MOZ_ACCELERATED': '1', 16:08:11 INFO - 'MOZ_CRASHREPORTER': '1', 16:08:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:11 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:08:11 INFO - 'MOZ_NO_REMOTE': '1', 16:08:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:08:11 INFO - 'MOZ_WEBRENDER': '1', 16:08:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:11 INFO - 'NO_EM_RESTART': '1', 16:08:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:11 INFO - 'PWD': '/builds/slave/test', 16:08:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:08:11 INFO - 'RUST_BACKTRACE': 'full', 16:08:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:08:11 INFO - 'SHELL': '/bin/bash', 16:08:11 INFO - 'SHLVL': '1', 16:08:11 INFO - 'TERM': 'linux', 16:08:11 INFO - 'TMOUT': '86400', 16:08:11 INFO - 'USER': 'cltbld', 16:08:11 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817', 16:08:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:11 INFO - '_': '/tools/buildbot/bin/python'} 16:08:11 INFO - TEST-INFO | started process 2557 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6DsWRi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:08:12 INFO - PID 2557 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:08:12 INFO - PID 2557 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:08:12 INFO - PID 2557 | 16:08:12 INFO - PID 2557 | (/builds/slave/test/build/application/firefox/firefox:2615): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:08:12 INFO - PID 2557 | 16:08:22 INFO - PID 2557 | RSS: Main: 209448960 16:08:22 INFO - PID 2557 | 16:08:25 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34158/tests/kraken/driver.html?testName=audio-beat-detection) 16:08:26 INFO - PID 2557 | RSS: Main: 206254080 16:08:26 INFO - PID 2557 | 16:08:29 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34158/tests/kraken/driver.html?testName=audio-dft) 16:08:29 INFO - PID 2557 | RSS: Main: 206110720 16:08:29 INFO - PID 2557 | 16:08:32 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34158/tests/kraken/driver.html?testName=audio-fft) 16:08:33 INFO - PID 2557 | RSS: Main: 203280384 16:08:33 INFO - PID 2557 | 16:08:35 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34158/tests/kraken/driver.html?testName=audio-oscillator) 16:08:36 INFO - PID 2557 | RSS: Main: 203558912 16:08:36 INFO - PID 2557 | 16:08:38 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34158/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:08:39 INFO - PID 2557 | RSS: Main: 203726848 16:08:39 INFO - PID 2557 | 16:08:42 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34158/tests/kraken/driver.html?testName=imaging-darkroom) 16:08:42 INFO - PID 2557 | RSS: Main: 206225408 16:08:42 INFO - PID 2557 | 16:08:46 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34158/tests/kraken/driver.html?testName=imaging-desaturate) 16:08:47 INFO - PID 2557 | RSS: Main: 205553664 16:08:47 INFO - PID 2557 | 16:08:50 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34158/tests/kraken/driver.html?testName=json-parse-financial) 16:08:50 INFO - PID 2557 | RSS: Main: 206409728 16:08:50 INFO - PID 2557 | 16:08:52 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34158/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:08:53 INFO - PID 2557 | RSS: Main: 206274560 16:08:53 INFO - PID 2557 | 16:08:54 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34158/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:08:55 INFO - PID 2557 | RSS: Main: 203231232 16:08:55 INFO - PID 2557 | 16:08:57 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34158/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:08:58 INFO - PID 2557 | RSS: Main: 203210752 16:08:58 INFO - PID 2557 | 16:09:00 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34158/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:09:01 INFO - PID 2557 | RSS: Main: 204226560 16:09:01 INFO - PID 2557 | 16:09:04 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34158/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:09:05 INFO - PID 2557 | RSS: Main: 204238848 16:09:05 INFO - PID 2557 | 16:09:06 INFO - PID 2557 | Cycle 1(1): loaded http://localhost:34158/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34158/tests/kraken/driver.html?testName=ai-astar) 16:09:07 INFO - PID 2557 | RSS: Main: 205778944 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | __start_tp_report 16:09:07 INFO - PID 2557 | _x_x_mozilla_page_load 16:09:07 INFO - PID 2557 | _x_x_mozilla_page_load_details 16:09:07 INFO - PID 2557 | |i|pagename|runs| 16:09:07 INFO - PID 2557 | |0;ai-astar;110;108;115;113;110;107;111;119;116;108 16:09:07 INFO - PID 2557 | |1;audio-beat-detection;124;125;117;115;116;123;125;118;127;125 16:09:07 INFO - PID 2557 | |2;audio-dft;163;174;172;169;166;182;165;169;165;165 16:09:07 INFO - PID 2557 | |3;audio-fft;74;82;76;84;81;81;85;81;74;83 16:09:07 INFO - PID 2557 | |4;audio-oscillator;86;80;83;85;82;81;86;78;83;89 16:09:07 INFO - PID 2557 | |5;imaging-gaussian-blur;125;113;117;124;113;120;113;121;113;115 16:09:07 INFO - PID 2557 | |6;imaging-darkroom;160;148;148;157;152;151;152;153;151;148 16:09:07 INFO - PID 2557 | |7;imaging-desaturate;104;96;97;95;96;94;93;93;97;94 16:09:07 INFO - PID 2557 | |8;json-parse-financial;60;55;51;52;52;60;51;67;55;51 16:09:07 INFO - PID 2557 | |9;json-stringify-tinderbox;43;48;48;38;42;43;45;41;38;45 16:09:07 INFO - PID 2557 | |10;stanford-crypto-aes;81;79;81;77;72;79;80;73;79;75 16:09:07 INFO - PID 2557 | |11;stanford-crypto-ccm;109;118;116;114;127;110;117;113;116;115 16:09:07 INFO - PID 2557 | |12;stanford-crypto-pbkdf2;161;171;177;172;168;169;159;164;174;168 16:09:07 INFO - PID 2557 | |13;stanford-crypto-sha256-iterative;73;62;65;65;68;65;62;71;65;66 16:09:07 INFO - PID 2557 | __end_tp_report 16:09:07 INFO - PID 2557 | __start_cc_report 16:09:07 INFO - PID 2557 | _x_x_mozilla_cycle_collect,755 16:09:07 INFO - PID 2557 | __end_cc_report 16:09:07 INFO - PID 2557 | __startTimestamp1506726547248__endTimestamp 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | ------- Summary: start ------- 16:09:07 INFO - PID 2557 | Number of tests: 14 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:3.95 (3.5%) stddev-sans-first:4.14 16:09:07 INFO - PID 2557 | Values: 110.0 108.0 115.0 113.0 110.0 107.0 111.0 119.0 116.0 108.0 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#1] audio-beat-detection Cycles:10 Average:121.50 Median:124.50 stddev:4.48 (3.6%) stddev-sans-first:4.66 16:09:07 INFO - PID 2557 | Values: 124.0 125.0 117.0 115.0 116.0 123.0 125.0 118.0 127.0 125.0 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#2] audio-dft Cycles:10 Average:169.00 Median:169.00 stddev:5.73 (3.4%) stddev-sans-first:5.66 16:09:07 INFO - PID 2557 | Values: 163.0 174.0 172.0 169.0 166.0 182.0 165.0 169.0 165.0 165.0 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#3] audio-fft Cycles:10 Average:80.10 Median:81.50 stddev:4.01 (4.9%) stddev-sans-first:3.60 16:09:07 INFO - PID 2557 | Values: 74.0 82.0 76.0 84.0 81.0 81.0 85.0 81.0 74.0 83.0 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#4] audio-oscillator Cycles:10 Average:83.30 Median:84.00 stddev:3.27 (3.9%) stddev-sans-first:3.32 16:09:07 INFO - PID 2557 | Values: 86.0 80.0 83.0 85.0 82.0 81.0 86.0 78.0 83.0 89.0 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:118.50 stddev:4.77 (4.0%) stddev-sans-first:4.19 16:09:07 INFO - PID 2557 | Values: 125.0 113.0 117.0 124.0 113.0 120.0 113.0 121.0 113.0 115.0 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#6] imaging-darkroom Cycles:10 Average:152.00 Median:152.00 stddev:3.94 (2.6%) stddev-sans-first:2.93 16:09:07 INFO - PID 2557 | Values: 160.0 148.0 148.0 157.0 152.0 151.0 152.0 153.0 151.0 148.0 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:95.50 stddev:3.21 (3.4%) stddev-sans-first:1.58 16:09:07 INFO - PID 2557 | Values: 104.0 96.0 97.0 95.0 96.0 94.0 93.0 93.0 97.0 94.0 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.00 stddev:5.36 (9.7%) stddev-sans-first:5.42 16:09:07 INFO - PID 2557 | Values: 60.0 55.0 51.0 52.0 52.0 60.0 51.0 67.0 55.0 51.0 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#9] json-stringify-tinderbox Cycles:10 Average:43.10 Median:44.00 stddev:3.54 (8.0%) stddev-sans-first:3.76 16:09:07 INFO - PID 2557 | Values: 43.0 48.0 48.0 38.0 42.0 43.0 45.0 41.0 38.0 45.0 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#10] stanford-crypto-aes Cycles:10 Average:77.60 Median:79.00 stddev:3.24 (4.1%) stddev-sans-first:3.19 16:09:07 INFO - PID 2557 | Values: 81.0 79.0 81.0 77.0 72.0 79.0 80.0 73.0 79.0 75.0 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#11] stanford-crypto-ccm Cycles:10 Average:115.50 Median:116.00 stddev:4.97 (4.3%) stddev-sans-first:4.68 16:09:07 INFO - PID 2557 | Values: 109.0 118.0 116.0 114.0 127.0 110.0 117.0 113.0 116.0 115.0 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.30 Median:170.00 stddev:5.66 (3.3%) stddev-sans-first:5.35 16:09:07 INFO - PID 2557 | Values: 161.0 171.0 177.0 172.0 168.0 169.0 159.0 164.0 174.0 168.0 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:65.50 stddev:3.55 (5.4%) stddev-sans-first:2.79 16:09:07 INFO - PID 2557 | Values: 73.0 62.0 65.0 65.0 68.0 65.0 62.0 71.0 65.0 66.0 16:09:07 INFO - PID 2557 | -------- Summary: end -------- 16:09:07 INFO - PID 2557 | 16:09:07 INFO - PID 2557 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:09:08 INFO - TEST-INFO | 2557: exit 0 16:09:08 INFO - TEST-OK | kraken | took 63062ms 16:09:08 INFO - SUITE-END | took 1177s 16:09:08 INFO - Completed test suite (00:19:37) 16:09:08 INFO - javascript benchmark 16:09:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8492.840600426482, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3123.4690102682544, 3149.165471150144, 3187.7131058204764, 3236.110069730824, 3264.2002709336216, 6110.755569777203, 6188.443040126388, 6217.067439884026, 6227.844303892405, 6245.843853903654, 6246.906296405557, 6249.218847644044, 6257.4368306852375, 6263.906041409383, 6283.120363149163, 5748.9651862664805, 5770.971145144289, 5816.651000939936, 5837.708114594275, 5919.704014799243, 5828.125781132821, 5885.823425297248, 5938.910916336236, 5981.671008094553, 5996.7301471433675, 5755.712214389285, 5807.332156801979, 5827.475527202551, 5827.533797296206, 5853.034249348852, 5652.152177173416, 5709.4290570942985, 5802.0, 5893.499052580536, 5915.970420147914, 5661.8584535386535, 5790.884182316372, 5793.333766616851, 5817.156512305734, 5870.530357571384, 6265.87468250637, 6327.588706734094, 6344.841378965517, 6401.455876250524, 6421.454176395012, 6627.536072474929, 6724.798256052326, 6735.629540375277, 6741.056252124707, 6796.966015169893, 6829.385355317995, 6895.965520172367, 6916.757913473004, 6921.688524016431, 6941.652917354112, 6293.118963345135, 6349.682515874188, 6369.490440764727, 6384.680765961683, 6428.325025872263, 6980.476464265201, 7020.859582808315, 7022.367986881205, 7109.502334836512, 7140.607266600283, 6447.0, 6457.418832305115, 6529.95520716683, 6532.967335163294, 6601.339866013456, 6601.2738598754095, 6650.168728908887, 6690.59856408617, 6709.731610735614, 6728.293529179366, 6630.270670226271, 6685.732570697118, 6740.865182696319, 6764.985252212109, 6824.93175068243, 6829.078074459884, 6861.4853885958755, 6904.689288982008, 6908.620025898624, 6924.549904256207, 5337.733113344318, 5343.759530821122, 5405.891882162335, 5415.512603865593, 5419.756110975016, 4524.09518096382, 4574.222382195035, 4604.102200071021, 4634.976825115853, 4647.6281897448125, 5733.254676891977, 5745.942540574624, 5777.191193232867, 5800.738966746507, 5881.9705901470215, 6819.0, 6842.0, 6849.0, 6901.447884169254, 6904.3095690430555, 5847.619904706181, 5872.677002764804, 5904.232449781501, 5905.46850783423, 5923.229980102559, 5808.273965754281, 5887.735051922674, 5891.381404952418, 5918.615290006136, 5929.436703513159, 6338.0, 6436.935630643634, 6507.544471886922, 6508.511861610398, 6615.933840661628, 6724.798256052326, 6781.966090169518, 6794.558353706993, 6860.656967151723, 6865.485088618374, 6593.571417857824, 6623.701933413105, 6648.6010839347855, 6661.300563440963, 6687.063811066358, 6633.336666333328, 6662.0, 6731.899021514779, 6826.522143449911, 6833.043373927753, 6611.636360000054, 6666.699998499982, 6737.157855268091, 6823.317668233137, 6926.688299026657, 6488.480921526361, 6516.543841931019, 6542.247641521286, 6543.0, 6546.410823025951, 6435.2599451063725, 6546.0508226307875, 6671.9658452939875, 6674.966625167037, 6686.431653309523, 6563.146790917151, 6610.3389661035435, 6627.900581491185, 6643.335666433318, 6652.767153149616, 6618.305077966744, 6636.568623039487, 6678.465722742072, 6681.064650948968, 6753.797386078426, 6208.0, 6332.588381755171, 6348.682565871595, 6473.417392434703, 6492.902606460812], "value": 6173.73599300773, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [881.0484676549327, 902.9051949545302, 903.169084442313, 906.8005038891446, 909.7043460875213, 39299.41050884235, 42740.78629606855, 42754.57245427548, 42790.78604606973, 42860.142797144035, 40084.0, 40171.0, 40320.0, 40506.79746601263, 40531.39202911959, 7360.2271761464945, 7439.330460258576, 7480.214577469369, 7503.362214211798, 7516.624168791539, 7369.152547457057, 7374.852502949938, 7448.180700122982, 7511.849763004737, 7522.962385188066, 7390.815229619276, 7421.81445463865, 7458.552486850779, 7476.962615186916, 7485.0, 7419.629018549078, 7460.32857042866, 7947.801304967365, 8145.104038555754, 8164.061132969725, 7982.760517184435, 8008.319292860114, 8076.111627720946, 8077.555734434603, 8078.111407745143, 38640.42039369412, 39080.413793793115, 39102.21795564073, 39252.21495570073, 39328.21343573112, 3402.3705614461405, 3424.9486257706153, 3458.654134586521, 3495.702865256431, 3516.156122530574, 32929.506057409155, 33001.0, 33157.6684233157, 33239.50140747891, 33582.0, 5130.0, 5148.768305426227, 5215.1134307167495, 5240.580753539746, 5312.787488500456, 208539.0, 209175.95412022996, 209226.0, 209889.0, 210146.0, 43186.13627727428, 43808.78095609502, 51411.0, 51700.74149629228, 51837.0, 51506.48493515092, 52116.47883521192, 52712.0, 52810.0, 52863.0, 16468.0, 16498.092604906844, 16675.583110422336, 16741.162941852857, 16746.078965657005, 13680.31598420075, 13718.588442346745, 13750.724985500234, 13794.379252933642, 13797.24115173655, 13496.730065398637, 13655.38550765218, 13819.378127984264, 13890.791638125436, 14048.508302209373, 5486.478784515464, 5611.410801865827, 5661.0, 5668.943310566924, 5776.364599894008, 968.3705591365668, 977.3012296208103, 977.6480467031863, 988.1600639456548, 1004.6232662751469, 22957.311280661608, 23039.88480057589, 23223.303300901, 23503.17738879131, 23633.29100126999, 1065.94137322448, 1105.7456784939347, 1106.5241945963312, 1107.2194103157256, 1113.4043286841502, 12357.75284494305, 12427.37863106841, 12454.875451245372, 12561.434735436927, 12575.0, 247530.0, 249656.0, 249771.0, 250345.0, 251053.0, 54542.72728636331, 54549.72725136349, 54575.45424545704, 54657.72671136698, 54942.450575494535, 1420.3324437514352, 1474.0934325389808, 1478.3569147420692, 1479.1568805780603, 1500.9774853377207, 22911.65632515547, 23036.4240893979, 23135.88432057829, 23141.07435702553, 23259.651105233097, 36786.26427471489, 36811.815940920125, 36989.0, 37159.28407159262, 37163.81418092892, 195018.0, 195749.0, 196178.0, 197109.0, 197194.0, 31602.367952641278, 32414.51378229281, 34831.30337393289, 35031.82484087563, 35227.119322017155, 43529.78235108804, 43911.78044109759, 44210.0, 44508.33237501375, 44862.55137448714, 31651.52522712115, 31959.680403196602, 31981.360372791947, 32303.35393292168, 32458.188545286557, 31143.549878771577, 31391.21521961969, 31427.0, 31612.367752645278, 31902.0, 35880.820595896854, 36197.63802361942, 36207.094822629646, 36250.27499450049, 36352.45471317985, 30930.69069309278, 31392.529112063796, 31412.0, 31493.212669683442, 32056.35887282288, 23350.065997359914, 23561.29316120519, 23616.409589760395, 23902.760972390748, 24018.87990560036], "value": 17309.730487945162, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.1746526269542, 578.722213337598, 597.2236093078997, 600.849787553112, 611.5657882903139, 22522.54954900903, 23289.534209315847, 23298.301050968457, 23326.41683957902, 23355.182568610082, 24364.87817560914, 24525.509489810192, 24558.631620525663, 24682.75317246827, 25096.874515627398, 6606.570572912758, 6655.900161497581, 6718.529702920798, 6757.189137303506, 6789.558678685894, 6271.153394291758, 6526.608403495782, 6766.729330826761, 6767.56010859295, 6771.052052712625, 6456.515761317896, 6497.3827486388955, 6527.902081468782, 6533.05270735743, 6557.278699343068, 6533.771318003847, 6539.117219175422, 6541.803745887607, 6546.247181574132, 6547.836304092388, 5838.854028649275, 6040.848978775522, 6064.696765161724, 6071.180390647294, 6098.298695650013, 27532.311692207655, 28490.430191396055, 28585.285367865763, 28593.0, 28593.71406285931, 1940.0784627301919, 1960.284496158906, 1974.2497850816742, 1981.2273213446767, 1985.186073709772, 18691.439256822316, 19031.714524282146, 19277.132529036226, 19435.22259109634, 19446.513837154183, 2404.326788499223, 2456.3367890669597, 2476.7151777545632, 2477.256822953107, 2479.962800557993, 122624.0, 129617.0, 130817.0, 136466.0, 144941.0, 14453.101942034027, 14543.545645435372, 14695.559133226021, 14742.41030358795, 15033.947623666458, 22552.887235563718, 22866.0, 22953.196638117584, 23153.536929261656, 23185.3044408668, 10038.64864729731, 10046.296759226783, 10075.647352342632, 10103.797924041479, 10281.383117013003, 9219.400738951947, 9311.580978855967, 9398.107179817905, 9451.527423628791, 9537.523123843781, 8729.738107856774, 8784.0, 8871.112888711206, 8979.281657467387, 9110.726678199664, 2675.331167208198, 2761.3648860761737, 2770.34896799252, 2786.8049236553247, 2791.706870778539, 938.666773295484, 956.4309236004566, 966.6761634852263, 967.7628980899725, 978.9657361992296, 18534.44396668102, 18948.715769263472, 19172.71240931387, 19523.316683915993, 19704.802951970298, 1022.1005515146624, 1022.6734179741, 1036.6371769880482, 1043.8747350317858, 1044.3577200021932, 9977.950110249401, 10401.94799026, 10454.268201225994, 10575.576976920991, 10757.56969721218, 465591.0, 484719.0, 487627.0, 490361.0, 492996.0, 108120.0, 113375.43312283386, 113987.0, 114595.0, 114623.0, 1163.831244469547, 1169.8245263210417, 1200.3938011304235, 1200.8018676918211, 1209.6371088673538, 16900.40848570294, 17045.48863534096, 17236.310547578458, 17506.387276445264, 17665.82334176642, 54480.455195449125, 56567.71716141393, 56955.0, 57381.0, 57872.421275786706, 70039.64980175067, 70194.64902675658, 70449.64775176092, 70561.64719176576, 71225.28774712187, 14354.49759258421, 14444.638884027983, 14563.92718036403, 14734.852651473348, 14745.336459859118, 22691.432714182276, 22895.65656515187, 23066.423339416648, 23157.0, 23464.6480302799, 18118.365857194935, 18143.818561814212, 18221.726674100166, 18391.816081839544, 18662.0, 15008.324625392102, 15086.396544138111, 15536.92231538835, 16612.750808737637, 16649.41727039548, 18059.18733657015, 18164.636707266043, 18500.814991849908, 18638.72041919345, 18765.71851422257, 14122.576322710334, 14266.358013889192, 14328.14031158134, 14350.856491434952, 14409.927950360181, 18145.45563633093, 18332.266709331478, 18373.632527349644, 18477.72283415723, 18489.445316640522], "value": 11845.314930216777, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.7311190924577, 1384.2525036480297, 1389.7220555888825, 1402.291842619477, 1417.178036738691, 14471.855281447182, 15032.624184395369, 15152.1666308353, 15265.084094954296, 15311.770323445156, 14960.401583936657, 15062.096274223555, 15065.0, 15065.924670376633, 15098.698026039472, 2579.651747014148, 2675.8929642814264, 2719.3745438549145, 2723.564229723245, 2726.82275652082, 2728.0724553651753, 2816.098848368523, 2839.488891999439, 2840.2757296889367, 2842.4315136972587, 2727.4545090981783, 2739.315171207198, 2739.630149929764, 2771.2517620242525, 2781.123945957027, 2774.5838124281318, 2779.138467075203, 2819.3233623930314, 2824.166870773127, 2828.61813655156, 2654.0710751237107, 2704.148193319118, 2722.863856807152, 2741.9588706169425, 2749.4501099780123, 14451.421943122263, 14819.925900370428, 14944.476943307041, 14974.62563435912, 15010.324535395934, 7906.881396779053, 8029.598520073973, 8142.959285203596, 8165.877511837327, 8171.223733745344, 5623.8594035149035, 5724.16999535065, 5748.936446757363, 5866.76532938682, 5886.440788125121, 1925.4512463947708, 1929.3440230321617, 1962.4897526643176, 1984.5435549823478, 1998.890061046642, 25737.485250295078, 25817.870910645514, 26397.07610233652, 26793.46413071747, 26815.865920670272, 6933.4106600939485, 6947.305269473113, 6948.756793512202, 7142.678579463936, 7205.315495027964, 17072.317107315816, 17113.91443042777, 17134.914325428294, 17163.112057673647, 17165.57086072833, 8095.190480951857, 8150.0, 8174.591270436455, 8211.548364839991, 8223.05434874985, 7204.53170543913, 7338.302861228175, 7352.816179595553, 7421.0, 7526.698932042657, 5192.584593232532, 5311.628186027018, 5321.0, 5333.093374126408, 5367.114426119647, 1465.765477523591, 1468.7833644318907, 1476.5201309574431, 1478.3716920308825, 1503.5188739603223, 881.7839629290864, 918.9540522973824, 923.5474617437408, 923.8521836506127, 927.3554879358916, 7424.591647459441, 7485.887711684329, 7538.434617403607, 8774.912250877538, 8785.253253473398, 721.7509959064115, 726.7783326085595, 733.5268751655259, 737.3511310047231, 757.9696812127453, 4708.152532544175, 4771.093492236464, 5406.887475323558, 5407.459254074561, 5562.332520097533, 19347.322843700404, 19916.402507924784, 20397.49006274855, 20532.486687832923, 21492.677609835864, 20082.39752807418, 21019.8949005254, 21082.683759743617, 21212.25757098494, 21567.78432215689, 9126.361154719107, 9493.240540756722, 10418.322809017389, 10630.30902991303, 10639.202059845542, 9038.367314287936, 9064.456132632064, 9074.410163339362, 9118.635254589743, 9205.723828285161, 18297.81702182961, 18402.81597184011, 18531.629367412308, 18559.721604175676, 18700.625987479903, 19245.13396897115, 19292.903535482234, 19293.517662058563, 19516.121774519896, 19795.604087918447, 10735.570577176826, 10893.237473376788, 10894.237403382003, 10986.0, 11113.110951123728, 13475.460981560627, 13662.521811736542, 13689.794653080013, 13695.383707732977, 13701.177929324273, 11267.154963377943, 11416.0, 11488.597899073493, 11508.36703981289, 11747.353895535827, 10945.179111566506, 11064.170187235828, 11119.0, 11180.161487888277, 11321.0, 9836.655717049869, 10049.246306526893, 10092.899071009197, 10116.140128089195, 10123.493825308853, 7410.258974102547, 7514.774556763306, 7533.472656913963, 7608.353289970193, 7613.847723045619, 7583.658735357032, 7623.4663573549315, 7698.422618303762, 7712.421568382506, 7733.38132949374], "value": 6658.744003882467, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.32139094309093, 515.4433212130901, 539.6114797345911, 543.2530270877544, 551.2365373957068, 6092.725827337769, 6118.694065296734, 6150.939435289178, 6172.567920245585, 6211.906821397672, 6057.879292330922, 6067.150598916155, 6088.3302836688035, 6127.3872612738705, 6142.385761423855, 5008.974955125228, 5113.284140220372, 5127.512886275797, 5156.278121063054, 5169.612279079065, 5128.743562821863, 5133.328670909473, 5155.7422128893595, 5158.664686795365, 5185.870353241162, 4701.9059618807605, 4844.07962487128, 4874.65877388583, 4907.877303067434, 5023.271625614284, 4881.194602890519, 4943.258511223309, 4950.653454258203, 4951.504849515056, 4971.701697898137, 4429.844955426577, 4437.378766972626, 4458.420405347284, 4473.776311184428, 4480.731156130642, 5932.3474417814, 6031.849203769941, 6032.849178770522, 6045.6674882881425, 6063.18147050147, 5675.914861277084, 5684.317881854163, 5691.743871525791, 5728.484436400702, 5734.741936612862, 6414.679266036726, 6444.065610486453, 6465.288818229991, 6504.902426463606, 6515.348465153446, 4616.8614941551805, 4833.516648335173, 4869.561739443467, 4888.73111978841, 4889.633277504166, 6392.264889537716, 6551.148350714424, 6639.86720265592, 6702.932970670328, 6709.865802683968, 4911.87720306988, 4951.480094590088, 4960.727160006162, 4970.627202959792, 5028.145215313405, 4994.875128121754, 5021.347224860818, 5024.874378140576, 5037.168867136881, 5077.416097148801, 5572.164175373727, 5573.303337082864, 5587.944120558743, 5592.944070559242, 5655.1517272409465, 6409.359064093646, 6421.325760795143, 6463.15978922737, 6494.0, 6499.562664001, 1723.284836792742, 1740.6779745746824, 1741.2164525963367, 1752.5706201980342, 1758.7977382601164, 1167.3521195736448, 1204.3556697166973, 1231.593574120538, 1232.5377983256278, 1242.6396345059832, 5539.845116162878, 5560.555155587544, 5606.794539174097, 5655.745491452894, 5664.150377443417, 6392.328805475451, 6412.012276194395, 6524.478041756648, 6539.574927629689, 6558.639274839834, 5466.644668096561, 5655.86882623477, 5656.8020119295625, 5679.545636349082, 5716.171155182558, 3363.5291059251726, 3435.7251419886347, 3469.548958635361, 3492.6158122606494, 3532.604621174673, 6274.341194174546, 6290.748370065146, 6301.4328710416285, 6309.3690630936535, 6372.36276372359, 6669.599824010575, 6679.298673639295, 6738.157730283715, 6754.6284954327, 6792.218894827158, 5888.617239879394, 6163.630182189083, 6323.747050118036, 6351.777687780906, 6361.459275961595, 6435.358983459375, 6515.153030106246, 6520.608763474207, 6522.608643481406, 6568.014797780371, 6437.903431448438, 6467.159269294962, 6495.382938620824, 6501.139498620591, 6656.30108838565, 6354.4598709108395, 6375.2149398168585, 6404.743810247538, 6416.882428798392, 6420.807375778734, 4903.215485522299, 4979.751012449291, 4994.225894986283, 4996.15065438869, 5027.095122877917, 6298.84252893663, 6322.841928951813, 6351.490441786384, 6584.604923704593, 6584.934150658432, 6224.968875155595, 6352.333005034589, 6379.808605741649, 6394.616323020634, 6418.839529011812, 6360.809175724736, 6389.329120442472, 6439.7424103035355, 6441.874181520181, 6459.967700161469, 6296.905546416899, 6308.369163083655, 6323.114763932961, 6324.3991820776955, 6374.171357723466, 6376.840578985562, 6393.264774550986, 6462.159919210472, 6468.8706225874275, 6488.286288508166, 6247.718852651551, 6257.655828929453, 6260.436560709558, 6265.932242359919, 6284.497240220863], "value": 4983.011205463001, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [831.7920519870032, 860.6686425726093, 875.6191056890253, 915.3318077803203, 927.4481683398376, 19094.331698390542, 19477.220911163535, 19491.805081949176, 19492.415227543177, 19540.413787586396, 18513.907430462863, 18778.718319225223, 18787.0, 18793.90603046983, 18812.341568045085, 958.6932181701858, 978.7259567321162, 986.2750878104623, 988.3575675810745, 992.910638042576, 962.1725316228038, 972.8443449048153, 975.5366201054478, 977.218225419665, 978.432509144698, 981.7938232971085, 1022.3610243597751, 1027.3014350241833, 1032.5611615063606, 1032.901874321942, 1019.9847002294972, 1023.8566600675912, 1025.9794804103951, 1027.3168343051861, 1029.711680729397, 717.3292971072065, 742.3949481172826, 743.1490942870437, 746.0301607909696, 751.1024325930515, 20368.490787730276, 20660.793392066036, 20696.0, 20816.47958801027, 20944.685829712565, 21597.24409645655, 21699.891500542395, 22446.887765561067, 22503.437414064614, 22662.0, 17291.56771080733, 17332.740008899877, 17457.610213445067, 17490.0, 17589.472315830546, 3578.7315951303494, 3617.0595645132194, 3637.963620363789, 3643.344198044351, 3643.362411577963, 110594.0, 141616.0, 142032.0, 143415.0, 144651.0, 17735.201915913814, 17861.821381786274, 17978.280868765363, 18078.0, 18118.275268989357, 32690.182745431553, 33178.00465986024, 33183.668163318056, 33237.3352532948, 33272.3345533088, 8641.30869530436, 8811.295096392274, 8824.382293239412, 8894.421862579042, 8957.641694332153, 4113.362428823537, 4231.344141658048, 4317.287647538184, 4341.630961368256, 4364.454443194601, 5331.86670333237, 5341.5459685927135, 5425.891482170335, 5429.755660995264, 5445.264889239902, 1075.6665433715573, 1090.1333439915286, 1093.3603841752504, 1101.686019484443, 1101.8402331661864, 676.509530590318, 696.7874798186601, 698.2284575544053, 701.0815831261065, 713.0516413170575, 11800.0, 11959.461824217931, 12151.939240303742, 12197.250382722419, 12573.99408047354, 671.4460570029745, 672.5191488086042, 679.6533767778468, 683.064202043204, 696.0498918975562, 5575.665460072409, 5670.06443936754, 5700.116481945305, 5702.7718891243885, 5810.738516766755, 119074.0, 119108.0, 120152.0, 122518.0, 122564.77435225713, 98208.0, 99146.50426747964, 99271.50364248133, 100088.49955750321, 101824.49087754662, 95424.52287738655, 95630.5218473917, 97364.0, 98097.50951245199, 99224.0, 10405.531751071372, 10554.947225263824, 10720.571177152826, 10738.516766745672, 10784.406857622593, 42855.785721071195, 44196.55803441924, 44417.333739994574, 44621.77689111534, 44845.55154448544, 56898.43101568931, 57103.0, 59251.40748592459, 59252.111218330894, 59513.0, 15170.848291516944, 15399.692006160038, 15486.148261845707, 15488.380464781283, 15614.063156210665, 18478.63042739111, 18599.256029758657, 18609.44171674852, 18703.719444208076, 19040.428787136407, 16569.33722651129, 16587.25357358946, 16922.0, 16959.576010599834, 17157.056361899715, 17093.658126837145, 17267.82732172662, 17278.827211728225, 17371.478855634352, 17419.129043548026, 16253.918730406745, 16508.25712842901, 16552.42066527666, 16592.004479731255, 16763.832361676228, 9399.295052870926, 9733.221342292458, 9782.951085244527, 9996.300258982092, 10035.648752293635, 9497.382670126419, 9522.0, 9654.855177172207, 9752.756181095529, 9832.26258030665], "value": 8933.516005832202, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1456.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 108.0, 115.0, 113.0, 110.0, 107.0, 111.0, 119.0, 116.0, 108.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 125.0, 117.0, 115.0, 116.0, 123.0, 125.0, 118.0, 127.0, 125.0], "value": 121.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 174.0, 172.0, 169.0, 166.0, 182.0, 165.0, 169.0, 165.0, 165.0], "value": 169.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 82.0, 76.0, 84.0, 81.0, 81.0, 85.0, 81.0, 74.0, 83.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 80.0, 83.0, 85.0, 82.0, 81.0, 86.0, 78.0, 83.0, 89.0], "value": 83.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 113.0, 117.0, 124.0, 113.0, 120.0, 113.0, 121.0, 113.0, 115.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 148.0, 148.0, 157.0, 152.0, 151.0, 152.0, 153.0, 151.0, 148.0], "value": 152.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 96.0, 97.0, 95.0, 96.0, 94.0, 93.0, 93.0, 97.0, 94.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 55.0, 51.0, 52.0, 52.0, 60.0, 51.0, 67.0, 55.0, 51.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 48.0, 48.0, 38.0, 42.0, 43.0, 45.0, 41.0, 38.0, 45.0], "value": 43.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 81.0, 77.0, 72.0, 79.0, 80.0, 73.0, 79.0, 75.0], "value": 77.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 118.0, 116.0, 114.0, 127.0, 110.0, 117.0, 113.0, 116.0, 115.0], "value": 115.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 171.0, 177.0, 172.0, 168.0, 169.0, 159.0, 164.0, 174.0, 168.0], "value": 168.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 62.0, 65.0, 65.0, 68.0, 65.0, 62.0, 71.0, 65.0, 66.0], "value": 66.2, "unit": "score"}]}]} 16:09:08 INFO - Return code: 0 16:09:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:09:08 INFO - # TBPL SUCCESS # 16:09:08 INFO - Running post-action listener: _package_coverage_data 16:09:08 INFO - Running post-action listener: _resource_record_post_action 16:09:08 INFO - [mozharness: 2017-09-29 23:09:08.521702Z] Finished run-tests step (success) 16:09:08 INFO - Running post-run listener: _resource_record_post_run 16:09:08 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 6537216; Write bytes: 334471168; Read time: 5712; Write time: 217720 16:09:08 INFO - TinderboxPrint: CPU usage
13.3% 16:09:08 INFO - TinderboxPrint: I/O read bytes / time
6,537,216 / 5,712 16:09:08 INFO - TinderboxPrint: I/O write bytes / time
334,471,168 / 217,720 16:09:08 INFO - TinderboxPrint: CPU idle
8,266.7 (86.7%) 16:09:08 INFO - TinderboxPrint: CPU user
1,198.9 (12.6%) 16:09:08 INFO - TinderboxPrint: Swap in / out
0 / 0 16:09:08 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13180928; Read time: 0; Write time: 3472 16:09:08 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:09:08 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 6533120; Write bytes: 321290240; Read time: 5696; Write time: 214248 16:09:09 INFO - Running post-run listener: _upload_blobber_files 16:09:09 INFO - Blob upload gear active. 16:09:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:09:09 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:09:09 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:09:09 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:09:09 INFO - (blobuploader) - INFO - Open directory for files ... 16:09:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:09:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:09:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:10 INFO - (blobuploader) - INFO - Done attempting. 16:09:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:09:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:09:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:11 INFO - (blobuploader) - INFO - Done attempting. 16:09:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:09:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:09:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:15 INFO - (blobuploader) - INFO - Done attempting. 16:09:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:09:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:09:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:09:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:09:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:09:16 INFO - (blobuploader) - INFO - Done attempting. 16:09:16 INFO - (blobuploader) - INFO - Iteration through files over. 16:09:16 INFO - Return code: 0 16:09:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:09:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:09:16 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f10f8d7e66c13bbf8d81ccf0b04cd2957d4de3141051b88beacd265091d7ac4e4a1e074f4c348a269341809a1a5aa8a87e9a1d98485fb6eeea4cd2a22a1f4a4a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd5ebf179da6ea02ab3f0b34ee9048f75b5bf48f9c0fcdfe5d1006c49b29beb4dd5ee9e50c344fcde956f36b4e9b626783ebd0d79622bf5c1e4e7406b3a8e40c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4db437ffecf88c625998d2ead255b1782e4a79c1b610bdda708898ff78c536f8dbd83c2f584c289a422ae209b1e82a57d87454df655ed745a07d9a92baf48bf2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52a954cda2c1ddba32efe7f9d852e955a40a1125fe760b58f7a343f643a1d547a2a49fa5d0aaa97029b005d4231ad95bcf9d682b881e2c78faedd16d46ab9a7a"} 16:09:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:09:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:09:16 INFO - Contents: 16:09:16 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f10f8d7e66c13bbf8d81ccf0b04cd2957d4de3141051b88beacd265091d7ac4e4a1e074f4c348a269341809a1a5aa8a87e9a1d98485fb6eeea4cd2a22a1f4a4a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd5ebf179da6ea02ab3f0b34ee9048f75b5bf48f9c0fcdfe5d1006c49b29beb4dd5ee9e50c344fcde956f36b4e9b626783ebd0d79622bf5c1e4e7406b3a8e40c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4db437ffecf88c625998d2ead255b1782e4a79c1b610bdda708898ff78c536f8dbd83c2f584c289a422ae209b1e82a57d87454df655ed745a07d9a92baf48bf2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52a954cda2c1ddba32efe7f9d852e955a40a1125fe760b58f7a343f643a1d547a2a49fa5d0aaa97029b005d4231ad95bcf9d682b881e2c78faedd16d46ab9a7a"} 16:09:16 INFO - Running post-run listener: copy_logs_to_upload_dir 16:09:16 INFO - Copying logs to upload dir... 16:09:16 INFO - mkdir: /builds/slave/test/build/upload/logs 16:09:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.468691 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-29 16:09:16.151327) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-29 16:09:16.154734) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f10f8d7e66c13bbf8d81ccf0b04cd2957d4de3141051b88beacd265091d7ac4e4a1e074f4c348a269341809a1a5aa8a87e9a1d98485fb6eeea4cd2a22a1f4a4a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd5ebf179da6ea02ab3f0b34ee9048f75b5bf48f9c0fcdfe5d1006c49b29beb4dd5ee9e50c344fcde956f36b4e9b626783ebd0d79622bf5c1e4e7406b3a8e40c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4db437ffecf88c625998d2ead255b1782e4a79c1b610bdda708898ff78c536f8dbd83c2f584c289a422ae209b1e82a57d87454df655ed745a07d9a92baf48bf2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52a954cda2c1ddba32efe7f9d852e955a40a1125fe760b58f7a343f643a1d547a2a49fa5d0aaa97029b005d4231ad95bcf9d682b881e2c78faedd16d46ab9a7a"} build_url:https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011503 build_url: 'https://queue.taskcluster.net/v1/task/fp7g7_mhRC22F8P10CUW5Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f10f8d7e66c13bbf8d81ccf0b04cd2957d4de3141051b88beacd265091d7ac4e4a1e074f4c348a269341809a1a5aa8a87e9a1d98485fb6eeea4cd2a22a1f4a4a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd5ebf179da6ea02ab3f0b34ee9048f75b5bf48f9c0fcdfe5d1006c49b29beb4dd5ee9e50c344fcde956f36b4e9b626783ebd0d79622bf5c1e4e7406b3a8e40c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4db437ffecf88c625998d2ead255b1782e4a79c1b610bdda708898ff78c536f8dbd83c2f584c289a422ae209b1e82a57d87454df655ed745a07d9a92baf48bf2", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52a954cda2c1ddba32efe7f9d852e955a40a1125fe760b58f7a343f643a1d547a2a49fa5d0aaa97029b005d4231ad95bcf9d682b881e2c78faedd16d46ab9a7a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-29 16:09:16.183665) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 16:09:16.184083) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1506717110.74628-12582817 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004496 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-29 16:09:16.267994) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-29 16:09:16.272258) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-29 16:09:16.272616) ========= ========= Total master_lag: 0.21 =========