builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-004 starttime: 1505212164.37 results: success (0) revision: b0e945eed81db8bf076daf64e381c514f70144f0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-12 03:29:24.369138) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-12 03:29:24.369803) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-12 03:29:24.370103) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-12 03:29:24.403908) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 03:29:24.404222) ========= 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=c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-12 03:29:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.5M=0s 2017-09-12 03:29:24 (40.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151979 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 03:29:24.576109) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 03:29:24.576426) ========= 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=c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015800 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 03:29:24.632651) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-12 03:29:24.633010) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b0e945eed81db8bf076daf64e381c514f70144f0 --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 b0e945eed81db8bf076daf64e381c514f70144f0 --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=c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-12 03:29:24,568 truncating revision to first 12 chars 2017-09-12 03:29:24,568 Setting DEBUG logging. 2017-09-12 03:29:24,568 attempt 1/10 2017-09-12 03:29:24,568 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b0e945eed81d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-12 03:29:25,678 unpacking tar archive at: mozilla-central-b0e945eed81d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.873266 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-12 03:29:26.526943) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-12 03:29:26.527343) ========= script_repo_revision: b0e945eed81db8bf076daf64e381c514f70144f0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-12 03:29:26.527803) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-12 03:29:26.528082) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-12 03:29:26.541858) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-12 03:29:26.542383) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:29:26 INFO - MultiFileLogger online at 20170912 03:29:26 in /builds/slave/test 03:29:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:29:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:29:26 INFO - {'append_to_log': False, 03:29:26 INFO - 'base_work_dir': '/builds/slave/test', 03:29:26 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:29:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:29:26 INFO - 'branch': 'Firefox-Non-PGO', 03:29:26 INFO - 'buildbot_json_path': 'buildprops.json', 03:29:26 INFO - 'code_coverage': False, 03:29:26 INFO - 'config_files': ('talos/linux_config.py',), 03:29:26 INFO - 'default_actions': ('clobber', 03:29:26 INFO - 'read-buildbot-config', 03:29:26 INFO - 'download-and-extract', 03:29:26 INFO - 'populate-webroot', 03:29:26 INFO - 'create-virtualenv', 03:29:26 INFO - 'install', 03:29:26 INFO - 'setup-mitmproxy', 03:29:26 INFO - 'run-tests'), 03:29:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:29:26 INFO - 'disable_ccov_upload': False, 03:29:26 INFO - 'disable_stylo': False, 03:29:26 INFO - 'download_minidump_stackwalk': True, 03:29:26 INFO - 'download_symbols': 'ondemand', 03:29:26 INFO - 'enable_stylo': False, 03:29:26 INFO - 'enable_webrender': False, 03:29:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:29:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:29:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:29:26 INFO - 'gecko_profile': False, 03:29:26 INFO - 'gecko_profile_interval': 0, 03:29:26 INFO - 'installer_path': 'installer.exe', 03:29:26 INFO - 'log_level': 'info', 03:29:26 INFO - 'log_name': 'talos', 03:29:26 INFO - 'log_to_console': True, 03:29:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:29:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:29:26 INFO - 'opt_config_files': (), 03:29:26 INFO - 'pip_index': False, 03:29:26 INFO - 'suite': 'dromaeojs-e10s', 03:29:26 INFO - 'system_bits': '32', 03:29:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:29:26 INFO - 'title': 'talos-linux64-ix-004', 03:29:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:29:26 INFO - 'use_talos_json': True, 03:29:26 INFO - 'verify': 'False', 03:29:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:29:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:29:26 INFO - 'work_dir': 'build'} 03:29:26 INFO - [mozharness: 2017-09-12 10:29:26.614293Z] Running clobber step. 03:29:26 INFO - Running pre-action listener: _resource_record_pre_action 03:29:26 INFO - Running main action method: clobber 03:29:26 INFO - rmtree: /builds/slave/test/build 03:29:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:29:27 INFO - Running post-action listener: _resource_record_post_action 03:29:27 INFO - [mozharness: 2017-09-12 10:29:27.620530Z] Finished clobber step (success) 03:29:27 INFO - [mozharness: 2017-09-12 10:29:27.620627Z] Running read-buildbot-config step. 03:29:27 INFO - Running pre-action listener: _resource_record_pre_action 03:29:27 INFO - Running main action method: read_buildbot_config 03:29:27 INFO - Using buildbot properties: 03:29:27 INFO - { 03:29:27 INFO - "project": "", 03:29:27 INFO - "product": "firefox", 03:29:27 INFO - "who": "archaeopteryx@coole-files.de", 03:29:27 INFO - "installer_path": "public/build/target.tar.bz2", 03:29:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:29:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:29:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:29:27 INFO - "stage_platform": "linux64", 03:29:27 INFO - "basedir": "/builds/slave/test", 03:29:27 INFO - "buildnumber": 813, 03:29:27 INFO - "platform": "ubuntu64_hw", 03:29:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:29:27 INFO - "slavebuilddir": "test", 03:29:27 INFO - "taskId": "QpgomtEUSnCdQoNr_d0N2A", 03:29:27 INFO - "branch": "mozilla-central", 03:29:27 INFO - "script_repo_revision": "production", 03:29:27 INFO - "revision": "b0e945eed81db8bf076daf64e381c514f70144f0", 03:29:27 INFO - "slavename": "talos-linux64-ix-004", 03:29:27 INFO - "repo_path": "mozilla-central" 03:29:27 INFO - } 03:29:27 INFO - Finding installer, test and symbols from parent task. 03:29:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QpgomtEUSnCdQoNr_d0N2A'}, attempt #1 03:29:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QpgomtEUSnCdQoNr_d0N2A'}, attempt #1 03:29:28 INFO - Task dependencies: Z7UnFtVmTZqJN21MF8D_IQ 03:29:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ'}, attempt #1 03:29:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.tar.bz2 03:29:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.test_packages.json 03:29:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.crashreporter-symbols.zip 03:29:29 INFO - Running post-action listener: _resource_record_post_action 03:29:29 INFO - [mozharness: 2017-09-12 10:29:29.004427Z] Finished read-buildbot-config step (success) 03:29:29 INFO - [mozharness: 2017-09-12 10:29:29.004642Z] Running download-and-extract step. 03:29:29 INFO - Running pre-action listener: _resource_record_pre_action 03:29:29 INFO - Running main action method: download_and_extract 03:29:29 INFO - mkdir: /builds/slave/test/build/tests 03:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:29 INFO - trying https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.test_packages.json 03:29:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:29:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:29:29 INFO - Downloaded 989 bytes. 03:29:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:29:29 INFO - Using the following test package requirements: 03:29:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:29:29 INFO - u'common': [u'target.common.tests.zip'], 03:29:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:29:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:29:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:29:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:29:29 INFO - u'mozbase': [u'target.common.tests.zip'], 03:29:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:29:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:29:29 INFO - u'web-platform': [u'target.common.tests.zip', 03:29:29 INFO - u'target.web-platform.tests.tar.gz'], 03:29:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:29:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:29:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.common.tests.zip 03:29:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:29:29 INFO - Fetch https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.common.tests.zip into memory 03:29:30 INFO - Content-Length response header: 55043940 03:29:30 INFO - Bytes received: 55043940 03:29:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.talos.tests.zip 03:29:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:29:41 INFO - Fetch https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.talos.tests.zip into memory 03:29:42 INFO - Content-Length response header: 14499231 03:29:42 INFO - Bytes received: 14499231 03:29:42 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')]} 03:29:42 INFO - trying https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.tar.bz2 03:29:42 INFO - Downloading https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:29:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:29:43 INFO - Downloaded 62978229 bytes. 03:29:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.tar.bz2 03:29:43 INFO - mkdir: /builds/slave/test/properties 03:29:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:29:43 INFO - Writing to file /builds/slave/test/properties/build_url 03:29:43 INFO - Contents: 03:29:43 INFO - build_url:https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.tar.bz2 03:29:43 INFO - Running post-action listener: _resource_record_post_action 03:29:43 INFO - Running post-action listener: find_tests_for_verification 03:29:43 INFO - Running post-action listener: set_extra_try_arguments 03:29:43 INFO - [mozharness: 2017-09-12 10:29:43.878584Z] Finished download-and-extract step (success) 03:29:43 INFO - [mozharness: 2017-09-12 10:29:43.878682Z] Running populate-webroot step. 03:29:43 INFO - Running pre-action listener: _resource_record_pre_action 03:29:43 INFO - Running main action method: populate_webroot 03:29:43 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:29:43 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['tresize', 03:29:43 INFO - 'tcanvasmark']}, 03:29:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:29:43 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['dromaeo_css', 03:29:43 INFO - 'kraken']}, 03:29:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:43 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:29:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'tests': ['damp', 'tps']}, 03:29:43 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['damp', 'tps']}, 03:29:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:29:43 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['dromaeo_dom']}, 03:29:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:29:43 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['basic_compositor_video', 03:29:43 INFO - 'glvideo']}, 03:29:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:29:43 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['ts_paint_webext', 03:29:43 INFO - 'tp5o_webext']}, 03:29:43 INFO - 'other-e10s': {'tests': ['a11yr', 03:29:43 INFO - 'ts_paint', 03:29:43 INFO - 'tpaint', 03:29:43 INFO - 'sessionrestore', 03:29:43 INFO - 'sessionrestore_many_windows', 03:29:43 INFO - 'sessionrestore_no_auto_restore', 03:29:43 INFO - 'tabpaint']}, 03:29:43 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['a11yr', 03:29:43 INFO - 'ts_paint', 03:29:43 INFO - 'tpaint', 03:29:43 INFO - 'sessionrestore', 03:29:43 INFO - 'sessionrestore_many_windows', 03:29:43 INFO - 'sessionrestore_no_auto_restore', 03:29:43 INFO - 'tabpaint']}, 03:29:43 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:29:43 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:29:43 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['bloom_basic_singleton']}, 03:29:43 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['bloom_basic']}, 03:29:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:29:43 INFO - 'tsvgr_opacity', 03:29:43 INFO - 'tart', 03:29:43 INFO - 'tscrollx', 03:29:43 INFO - 'tsvg_static']}, 03:29:43 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['tsvgx', 03:29:43 INFO - 'tsvgr_opacity', 03:29:43 INFO - 'tart', 03:29:43 INFO - 'tscrollx', 03:29:43 INFO - 'tsvg_static']}, 03:29:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:29:43 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo'], 03:29:43 INFO - 'tests': ['tp5o']}, 03:29:43 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:29:43 INFO - 'talos_options': ['--mitmproxy', 03:29:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:29:43 INFO - '--firstNonBlankPaint'], 03:29:43 INFO - 'tests': ['tp6_google', 03:29:43 INFO - 'tp6_youtube', 03:29:43 INFO - 'tp6_amazon', 03:29:43 INFO - 'tp6_facebook']}, 03:29:43 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo', 03:29:43 INFO - '--mitmproxy', 03:29:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:29:43 INFO - '--firstNonBlankPaint'], 03:29:43 INFO - 'tests': ['tp6_google', 03:29:43 INFO - 'tp6_youtube', 03:29:43 INFO - 'tp6_amazon', 03:29:43 INFO - 'tp6_facebook']}, 03:29:43 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:29:43 INFO - 'talos_options': ['--stylo-threads=1', 03:29:43 INFO - '--mitmproxy', 03:29:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:29:43 INFO - '--firstNonBlankPaint'], 03:29:43 INFO - 'tests': ['tp6_google', 03:29:43 INFO - 'tp6_youtube', 03:29:43 INFO - 'tp6_amazon', 03:29:43 INFO - 'tp6_facebook']}, 03:29:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--xperf_path', 03:29:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:43 INFO - 'tests': ['tp5n']}, 03:29:43 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:29:43 INFO - 'talos_options': ['--disable-stylo', 03:29:43 INFO - '--xperf_path', 03:29:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:29:43 INFO - 'tests': ['tp5n']}}} 03:29:43 INFO - Running post-action listener: _resource_record_post_action 03:29:43 INFO - [mozharness: 2017-09-12 10:29:43.893671Z] Finished populate-webroot step (success) 03:29:43 INFO - [mozharness: 2017-09-12 10:29:43.893760Z] Running create-virtualenv step. 03:29:43 INFO - Running pre-action listener: _resource_record_pre_action 03:29:43 INFO - Running main action method: create_virtualenv 03:29:43 INFO - Creating virtualenv /builds/slave/test/build/venv 03:29:43 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:29:43 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:29:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:29:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:29:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:29:46 INFO - Installing distribute.............................................................................................................................................................................................done. 03:29:46 INFO - Installing pip...............done. 03:29:46 INFO - Return code: 0 03:29:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:29:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:29:46 INFO - Reading from file tmpfile_stdout 03:29:46 INFO - Output received: 03:29:46 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:29:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:29:46 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')]} 03:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:46 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')]} 03:29:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:46 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 0x22ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265a090>, '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 0x265ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', '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 03:29:46 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 03:29:46 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 03:29:46 INFO - Using env: {'DISPLAY': ':0', 03:29:46 INFO - 'HOME': '/home/cltbld', 03:29:46 INFO - 'LANG': 'en_US.UTF-8', 03:29:46 INFO - 'LANGUAGE': 'en_US:en', 03:29:46 INFO - 'LOGNAME': 'cltbld', 03:29:46 INFO - 'MAIL': '/var/mail/cltbld', 03:29:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:46 INFO - 'MOZ_NO_REMOTE': '1', 03:29:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:46 INFO - 'NO_EM_RESTART': '1', 03:29:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:46 INFO - 'PWD': '/builds/slave/test', 03:29:46 INFO - 'SHELL': '/bin/bash', 03:29:46 INFO - 'SHLVL': '1', 03:29:46 INFO - 'TERM': 'linux', 03:29:46 INFO - 'TMOUT': '86400', 03:29:46 INFO - 'USER': 'cltbld', 03:29:46 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', 03:29:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:46 INFO - '_': '/tools/buildbot/bin/python'} 03:29:47 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:29:47 INFO - Downloading/unpacking pip>=1.5 03:29:47 INFO - Running setup.py egg_info for package pip 03:29:47 INFO - warning: no files found matching 'pip/cacert.pem' 03:29:47 INFO - warning: no files found matching '*.html' under directory 'docs' 03:29:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:29:47 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:29:47 INFO - Installing collected packages: pip 03:29:47 INFO - Found existing installation: pip 1.1 03:29:47 INFO - Uninstalling pip: 03:29:47 INFO - Successfully uninstalled pip 03:29:47 INFO - Running setup.py install for pip 03:29:47 INFO - warning: no files found matching 'pip/cacert.pem' 03:29:47 INFO - warning: no files found matching '*.html' under directory 'docs' 03:29:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:29:47 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:29:47 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:29:47 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:29:47 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:29:47 INFO - Successfully installed pip 03:29:47 INFO - Cleaning up... 03:29:47 INFO - Return code: 0 03:29:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:29:47 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')]} 03:29:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:47 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')]} 03:29:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:47 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 0x22ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265a090>, '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 0x265ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', '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 03:29:47 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 03:29:47 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 03:29:47 INFO - Using env: {'DISPLAY': ':0', 03:29:47 INFO - 'HOME': '/home/cltbld', 03:29:47 INFO - 'LANG': 'en_US.UTF-8', 03:29:47 INFO - 'LANGUAGE': 'en_US:en', 03:29:47 INFO - 'LOGNAME': 'cltbld', 03:29:47 INFO - 'MAIL': '/var/mail/cltbld', 03:29:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:47 INFO - 'MOZ_NO_REMOTE': '1', 03:29:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:47 INFO - 'NO_EM_RESTART': '1', 03:29:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:47 INFO - 'PWD': '/builds/slave/test', 03:29:47 INFO - 'SHELL': '/bin/bash', 03:29:47 INFO - 'SHLVL': '1', 03:29:47 INFO - 'TERM': 'linux', 03:29:47 INFO - 'TMOUT': '86400', 03:29:47 INFO - 'USER': 'cltbld', 03:29:47 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', 03:29:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:47 INFO - '_': '/tools/buildbot/bin/python'} 03:29:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:47 INFO - Downloading/unpacking psutil>=3.1.1 03:29:47 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 03:29:47 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 03:29:47 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 03:29:47 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 03:29:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:29:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:49 INFO - Installing collected packages: psutil 03:29:49 INFO - Running setup.py install for psutil 03:29:49 INFO - building 'psutil._psutil_linux' extension 03:29:49 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 03:29:49 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 03:29:49 INFO - building 'psutil._psutil_posix' extension 03:29:49 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 03:29:49 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 03:29:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:29:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:29:49 INFO - Successfully installed psutil 03:29:49 INFO - Cleaning up... 03:29:49 INFO - Return code: 0 03:29:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265a090>, '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 0x265ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', '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 03:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:29:49 INFO - Using env: {'DISPLAY': ':0', 03:29:49 INFO - 'HOME': '/home/cltbld', 03:29:49 INFO - 'LANG': 'en_US.UTF-8', 03:29:49 INFO - 'LANGUAGE': 'en_US:en', 03:29:49 INFO - 'LOGNAME': 'cltbld', 03:29:49 INFO - 'MAIL': '/var/mail/cltbld', 03:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:49 INFO - 'MOZ_NO_REMOTE': '1', 03:29:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:49 INFO - 'NO_EM_RESTART': '1', 03:29:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:49 INFO - 'PWD': '/builds/slave/test', 03:29:49 INFO - 'SHELL': '/bin/bash', 03:29:49 INFO - 'SHLVL': '1', 03:29:49 INFO - 'TERM': 'linux', 03:29:49 INFO - 'TMOUT': '86400', 03:29:49 INFO - 'USER': 'cltbld', 03:29:49 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', 03:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:49 INFO - '_': '/tools/buildbot/bin/python'} 03:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:29:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:29:51 INFO - Installing collected packages: mozsystemmonitor 03:29:51 INFO - Running setup.py install for mozsystemmonitor 03:29:51 INFO - Successfully installed mozsystemmonitor 03:29:51 INFO - Cleaning up... 03:29:51 INFO - Return code: 0 03:29:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:29: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')]} 03:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29: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')]} 03:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29: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', '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 0x22ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265a090>, '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 0x265ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', '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 03:29: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:29: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 jsonschema==2.5.1 03:29:51 INFO - Using env: {'DISPLAY': ':0', 03:29:51 INFO - 'HOME': '/home/cltbld', 03:29:51 INFO - 'LANG': 'en_US.UTF-8', 03:29:51 INFO - 'LANGUAGE': 'en_US:en', 03:29:51 INFO - 'LOGNAME': 'cltbld', 03:29:51 INFO - 'MAIL': '/var/mail/cltbld', 03:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:51 INFO - 'MOZ_NO_REMOTE': '1', 03:29:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:51 INFO - 'NO_EM_RESTART': '1', 03:29:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:51 INFO - 'PWD': '/builds/slave/test', 03:29:51 INFO - 'SHELL': '/bin/bash', 03:29:51 INFO - 'SHLVL': '1', 03:29:51 INFO - 'TERM': 'linux', 03:29:51 INFO - 'TMOUT': '86400', 03:29:51 INFO - 'USER': 'cltbld', 03:29:51 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', 03:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:51 INFO - '_': '/tools/buildbot/bin/python'} 03:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:52 INFO - Downloading/unpacking jsonschema==2.5.1 03:29: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 03:29: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 03:29: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 03:29: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 03:29:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:29:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:53 INFO - Downloading functools32-3.2.3-2.tar.gz 03:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:29:53 INFO - warning: no files found matching '*.txt' 03:29:53 INFO - no previously-included directories found matching 'build' 03:29:53 INFO - no previously-included directories found matching 'dist' 03:29:53 INFO - no previously-included directories found matching '.git*' 03:29:53 INFO - Installing collected packages: jsonschema, functools32 03:29:53 INFO - Running setup.py install for functools32 03:29:53 INFO - warning: no files found matching '*.txt' 03:29:53 INFO - no previously-included directories found matching 'build' 03:29:53 INFO - no previously-included directories found matching 'dist' 03:29:53 INFO - no previously-included directories found matching '.git*' 03:29:53 INFO - Successfully installed jsonschema functools32 03:29:53 INFO - Cleaning up... 03:29:53 INFO - Return code: 0 03:29:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265a090>, '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 0x265ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', '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 03:29:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:29:53 INFO - Using env: {'DISPLAY': ':0', 03:29:53 INFO - 'HOME': '/home/cltbld', 03:29:53 INFO - 'LANG': 'en_US.UTF-8', 03:29:53 INFO - 'LANGUAGE': 'en_US:en', 03:29:53 INFO - 'LOGNAME': 'cltbld', 03:29:53 INFO - 'MAIL': '/var/mail/cltbld', 03:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:53 INFO - 'MOZ_NO_REMOTE': '1', 03:29:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:53 INFO - 'NO_EM_RESTART': '1', 03:29:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:53 INFO - 'PWD': '/builds/slave/test', 03:29:53 INFO - 'SHELL': '/bin/bash', 03:29:53 INFO - 'SHLVL': '1', 03:29:53 INFO - 'TERM': 'linux', 03:29:53 INFO - 'TMOUT': '86400', 03:29:53 INFO - 'USER': 'cltbld', 03:29:53 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', 03:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:53 INFO - '_': '/tools/buildbot/bin/python'} 03:29:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:29:53 INFO - Cleaning up... 03:29:53 INFO - Return code: 0 03:29:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265a090>, '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 0x265ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', '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 03:29:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:29:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:29:53 INFO - Using env: {'DISPLAY': ':0', 03:29:53 INFO - 'HOME': '/home/cltbld', 03:29:53 INFO - 'LANG': 'en_US.UTF-8', 03:29:53 INFO - 'LANGUAGE': 'en_US:en', 03:29:53 INFO - 'LOGNAME': 'cltbld', 03:29:53 INFO - 'MAIL': '/var/mail/cltbld', 03:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:53 INFO - 'MOZ_NO_REMOTE': '1', 03:29:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:53 INFO - 'NO_EM_RESTART': '1', 03:29:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:53 INFO - 'PWD': '/builds/slave/test', 03:29:53 INFO - 'SHELL': '/bin/bash', 03:29:53 INFO - 'SHLVL': '1', 03:29:53 INFO - 'TERM': 'linux', 03:29:53 INFO - 'TMOUT': '86400', 03:29:53 INFO - 'USER': 'cltbld', 03:29:53 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', 03:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:53 INFO - '_': '/tools/buildbot/bin/python'} 03:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:54 INFO - Downloading/unpacking blobuploader==1.2.4 03:29: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 03:29: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 03:29: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 03:29: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 03:29:55 INFO - Downloading blobuploader-1.2.4.tar.gz 03:29:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:29:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:29:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:29:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:29:55 INFO - Downloading docopt-0.6.1.tar.gz 03:29:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:29:55 INFO - Installing collected packages: blobuploader, requests, docopt 03:29:55 INFO - Running setup.py install for blobuploader 03:29:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:29:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:29:55 INFO - Running setup.py install for requests 03:29:56 INFO - Running setup.py install for docopt 03:29:56 INFO - Successfully installed blobuploader requests docopt 03:29:56 INFO - Cleaning up... 03:29:56 INFO - Return code: 0 03:29:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:29:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:29:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:56 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 0x22ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265a090>, '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 0x265ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', '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 03:29:56 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 03:29:56 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 03:29:56 INFO - Using env: {'DISPLAY': ':0', 03:29:56 INFO - 'HOME': '/home/cltbld', 03:29:56 INFO - 'LANG': 'en_US.UTF-8', 03:29:56 INFO - 'LANGUAGE': 'en_US:en', 03:29:56 INFO - 'LOGNAME': 'cltbld', 03:29:56 INFO - 'MAIL': '/var/mail/cltbld', 03:29:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:56 INFO - 'MOZ_NO_REMOTE': '1', 03:29:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:56 INFO - 'NO_EM_RESTART': '1', 03:29:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:56 INFO - 'PWD': '/builds/slave/test', 03:29:56 INFO - 'SHELL': '/bin/bash', 03:29:56 INFO - 'SHLVL': '1', 03:29:56 INFO - 'TERM': 'linux', 03:29:56 INFO - 'TMOUT': '86400', 03:29:56 INFO - 'USER': 'cltbld', 03:29:56 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', 03:29:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:56 INFO - '_': '/tools/buildbot/bin/python'} 03:29:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:29:56 INFO - Running setup.py (path:/tmp/pip-PhAM6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:29:56 INFO - Running setup.py (path:/tmp/pip-CDMZwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:29:56 INFO - Running setup.py (path:/tmp/pip-3q3gY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:29:56 INFO - Running setup.py (path:/tmp/pip-_hLLpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:29:56 INFO - Running setup.py (path:/tmp/pip-TECOUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:29:56 INFO - Running setup.py (path:/tmp/pip-HL6k84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:29:56 INFO - Running setup.py (path:/tmp/pip-yyddHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:29:57 INFO - Running setup.py (path:/tmp/pip-iGYDUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:29:57 INFO - Running setup.py (path:/tmp/pip-hsRuc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:29:57 INFO - Running setup.py (path:/tmp/pip-FWrXQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:29:57 INFO - Running setup.py (path:/tmp/pip-3Tryd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:29:57 INFO - Running setup.py (path:/tmp/pip-SmQ9MG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:29:57 INFO - Running setup.py (path:/tmp/pip-ksOTAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:29:57 INFO - Running setup.py (path:/tmp/pip-k6G9Hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:57 INFO - Running setup.py (path:/tmp/pip-82vVeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:29:57 INFO - Running setup.py (path:/tmp/pip-t92z7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:29:57 INFO - Running setup.py (path:/tmp/pip-COJEF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:29:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:29:57 INFO - Running setup.py install for manifestparser 03:29:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:29:57 INFO - Running setup.py install for mozcrash 03:29:57 INFO - Running setup.py install for mozdebug 03:29:58 INFO - Running setup.py install for mozdevice 03:29:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Running setup.py install for mozfile 03:29:58 INFO - Running setup.py install for mozhttpd 03:29:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Running setup.py install for mozinfo 03:29:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Running setup.py install for mozInstall 03:29:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Running setup.py install for mozleak 03:29:58 INFO - Running setup.py install for mozlog 03:29:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Running setup.py install for moznetwork 03:29:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:29:58 INFO - Running setup.py install for mozprocess 03:29:58 INFO - Running setup.py install for mozprofile 03:29:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:29:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:29:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:29:59 INFO - Running setup.py install for mozrunner 03:29:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:29:59 INFO - Running setup.py install for mozscreenshot 03:29:59 INFO - Running setup.py install for moztest 03:29:59 INFO - Running setup.py install for mozversion 03:29:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:29:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:29:59 INFO - Cleaning up... 03:29:59 INFO - Return code: 0 03:29:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:29:59 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')]} 03:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:29:59 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')]} 03:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:29:59 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 0x22ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265a090>, '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 0x265ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', '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 03:29:59 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 03:29:59 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 03:29:59 INFO - Using env: {'DISPLAY': ':0', 03:29:59 INFO - 'HOME': '/home/cltbld', 03:29:59 INFO - 'LANG': 'en_US.UTF-8', 03:29:59 INFO - 'LANGUAGE': 'en_US:en', 03:29:59 INFO - 'LOGNAME': 'cltbld', 03:29:59 INFO - 'MAIL': '/var/mail/cltbld', 03:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:29:59 INFO - 'MOZ_NO_REMOTE': '1', 03:29:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:29:59 INFO - 'NO_EM_RESTART': '1', 03:29:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:29:59 INFO - 'PWD': '/builds/slave/test', 03:29:59 INFO - 'SHELL': '/bin/bash', 03:29:59 INFO - 'SHLVL': '1', 03:29:59 INFO - 'TERM': 'linux', 03:29:59 INFO - 'TMOUT': '86400', 03:29:59 INFO - 'USER': 'cltbld', 03:29:59 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', 03:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:29:59 INFO - '_': '/tools/buildbot/bin/python'} 03:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:29:59 INFO - Running setup.py (path:/tmp/pip-7IbYon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:29:59 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)) 03:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:29:59 INFO - Running setup.py (path:/tmp/pip-nuM5HK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:29:59 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)) 03:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:29:59 INFO - Running setup.py (path:/tmp/pip-lmq8re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:29:59 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)) 03:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:29:59 INFO - Running setup.py (path:/tmp/pip-mxYcdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:30:00 INFO - Running setup.py (path:/tmp/pip-wZulzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:30:00 INFO - Running setup.py (path:/tmp/pip-4kx3G9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:30:00 INFO - Running setup.py (path:/tmp/pip-V9Qths-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:30:00 INFO - Running setup.py (path:/tmp/pip-jSLvBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:30:00 INFO - Running setup.py (path:/tmp/pip-kQBl52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:30:00 INFO - Running setup.py (path:/tmp/pip-Zyo34t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:30:00 INFO - Running setup.py (path:/tmp/pip-VDSzNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:30:00 INFO - Running setup.py (path:/tmp/pip-pKDlRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:30:00 INFO - Running setup.py (path:/tmp/pip-roem6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:30:00 INFO - Running setup.py (path:/tmp/pip-RDnFcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:00 INFO - Running setup.py (path:/tmp/pip-FpuDPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:30:00 INFO - Running setup.py (path:/tmp/pip-zldzyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:30:00 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)) 03:30:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:30:00 INFO - Running setup.py (path:/tmp/pip-6YXLI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:30:00 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)) 03:30:00 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)) 03:30:00 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)) 03:30:00 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)) 03:30:00 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)) 03:30:00 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)) 03:30:00 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)) 03:30:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:30: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 03:30: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 03:30: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 03:30: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 03:30:02 INFO - Downloading blessings-1.6.tar.gz 03:30:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:30:02 INFO - Installing collected packages: blessings 03:30:02 INFO - Running setup.py install for blessings 03:30:02 INFO - Successfully installed blessings 03:30:02 INFO - Cleaning up... 03:30:02 INFO - Return code: 0 03:30:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:30:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:30:02 INFO - Reading from file tmpfile_stdout 03:30:02 INFO - Current package versions: 03:30:02 INFO - argparse == 1.2.1 03:30:02 INFO - blessings == 1.6 03:30:02 INFO - blobuploader == 1.2.4 03:30:02 INFO - docopt == 0.6.1 03:30:02 INFO - functools32 == 3.2.3-2 03:30:02 INFO - jsonschema == 2.5.1 03:30:02 INFO - manifestparser == 1.1 03:30:02 INFO - mozInstall == 1.13 03:30:02 INFO - mozcrash == 1.0 03:30:02 INFO - mozdebug == 0.1 03:30:02 INFO - mozdevice == 0.51 03:30:02 INFO - mozfile == 1.2 03:30:02 INFO - mozhttpd == 0.7 03:30:02 INFO - mozinfo == 0.10 03:30:02 INFO - mozleak == 0.1 03:30:02 INFO - mozlog == 3.5 03:30:02 INFO - moznetwork == 0.27 03:30:02 INFO - mozprocess == 0.25 03:30:02 INFO - mozprofile == 0.28 03:30:02 INFO - mozrunner == 6.13 03:30:02 INFO - mozscreenshot == 0.1 03:30:02 INFO - mozsystemmonitor == 0.3 03:30:02 INFO - moztest == 0.8 03:30:02 INFO - mozversion == 1.4 03:30:02 INFO - psutil == 3.1.1 03:30:02 INFO - requests == 1.2.3 03:30:02 INFO - wsgiref == 0.1.2 03:30:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:30:02 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')]} 03:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:02 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')]} 03:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:02 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 0x22ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265a090>, '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 0x265ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', '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 03:30:02 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 03:30:02 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 03:30:02 INFO - Using env: {'DISPLAY': ':0', 03:30:02 INFO - 'HOME': '/home/cltbld', 03:30:02 INFO - 'LANG': 'en_US.UTF-8', 03:30:02 INFO - 'LANGUAGE': 'en_US:en', 03:30:02 INFO - 'LOGNAME': 'cltbld', 03:30:02 INFO - 'MAIL': '/var/mail/cltbld', 03:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:02 INFO - 'MOZ_NO_REMOTE': '1', 03:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:02 INFO - 'NO_EM_RESTART': '1', 03:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:02 INFO - 'PWD': '/builds/slave/test', 03:30:02 INFO - 'SHELL': '/bin/bash', 03:30:02 INFO - 'SHLVL': '1', 03:30:02 INFO - 'TERM': 'linux', 03:30:02 INFO - 'TMOUT': '86400', 03:30:02 INFO - 'USER': 'cltbld', 03:30:02 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', 03:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:02 INFO - '_': '/tools/buildbot/bin/python'} 03:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:02 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)) 03:30:02 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)) 03:30:02 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)) 03:30:02 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)) 03:30:02 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)) 03:30:02 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)) 03:30:02 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)) 03:30:02 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)) 03:30:02 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)) 03:30:02 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:30: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 03:30: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 03:30: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 03:30: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 03:30:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:30:03 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)) 03:30:03 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)) 03:30:03 INFO - Installing collected packages: simplejson 03:30:03 INFO - Running setup.py install for simplejson 03:30:03 INFO - building 'simplejson._speedups' extension 03:30:03 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 03:30:04 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 03:30:04 INFO - Successfully installed simplejson 03:30:04 INFO - Cleaning up... 03:30:04 INFO - Return code: 0 03:30:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:30:04 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 0x22ea1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2656a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265a090>, '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 0x265ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654f90>, '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': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', '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 03:30:04 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 03:30:04 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 03:30:04 INFO - Using env: {'DISPLAY': ':0', 03:30:04 INFO - 'HOME': '/home/cltbld', 03:30:04 INFO - 'LANG': 'en_US.UTF-8', 03:30:04 INFO - 'LANGUAGE': 'en_US:en', 03:30:04 INFO - 'LOGNAME': 'cltbld', 03:30:04 INFO - 'MAIL': '/var/mail/cltbld', 03:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:04 INFO - 'MOZ_NO_REMOTE': '1', 03:30:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:04 INFO - 'NO_EM_RESTART': '1', 03:30:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:04 INFO - 'PWD': '/builds/slave/test', 03:30:04 INFO - 'SHELL': '/bin/bash', 03:30:04 INFO - 'SHLVL': '1', 03:30:04 INFO - 'TERM': 'linux', 03:30:04 INFO - 'TMOUT': '86400', 03:30:04 INFO - 'USER': 'cltbld', 03:30:04 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', 03:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:04 INFO - '_': '/tools/buildbot/bin/python'} 03:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:30:05 INFO - Cleaning up... 03:30:05 INFO - Return code: 0 03:30:05 INFO - Running post-action listener: _resource_record_post_action 03:30:05 INFO - Running post-action listener: _start_resource_monitoring 03:30:05 INFO - Starting resource monitoring. 03:30:05 INFO - [mozharness: 2017-09-12 10:30:05.182074Z] Finished create-virtualenv step (success) 03:30:05 INFO - [mozharness: 2017-09-12 10:30:05.182589Z] Running install step. 03:30:05 INFO - Running pre-action listener: _resource_record_pre_action 03:30:05 INFO - Running main action method: install 03:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:30:05 INFO - Reading from file tmpfile_stdout 03:30:05 INFO - Detecting whether we're running mozinstall >=1.0... 03:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:30:05 INFO - Reading from file tmpfile_stdout 03:30:05 INFO - Output received: 03:30:05 INFO - Usage: mozinstall [options] installer 03:30:05 INFO - Options: 03:30:05 INFO - -h, --help show this help message and exit 03:30:05 INFO - -d DEST, --destination=DEST 03:30:05 INFO - Directory to install application into. [default: 03:30:05 INFO - "/builds/slave/test"] 03:30:05 INFO - --app=APP Application being installed. [default: firefox] 03:30:05 INFO - mkdir: /builds/slave/test/build/application 03:30:05 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'] 03:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:30:22 INFO - Reading from file tmpfile_stdout 03:30:22 INFO - Output received: 03:30:22 INFO - /builds/slave/test/build/application/firefox/firefox 03:30:22 INFO - Running post-action listener: _resource_record_post_action 03:30:22 INFO - [mozharness: 2017-09-12 10:30:22.118809Z] Finished install step (success) 03:30:22 INFO - [mozharness: 2017-09-12 10:30:22.119057Z] Running setup-mitmproxy step. 03:30:22 INFO - Running pre-action listener: _resource_record_pre_action 03:30:22 INFO - Running main action method: setup_mitmproxy 03:30:22 INFO - Skipping: mitmproxy is not required 03:30:22 INFO - Running post-action listener: _resource_record_post_action 03:30:22 INFO - [mozharness: 2017-09-12 10:30:22.120156Z] Finished setup-mitmproxy step (success) 03:30:22 INFO - [mozharness: 2017-09-12 10:30:22.120371Z] Running run-tests step. 03:30:22 INFO - Running pre-action listener: _resource_record_pre_action 03:30:22 INFO - Running pre-action listener: _set_gcov_prefix 03:30:22 INFO - Running main action method: run_tests 03:30:22 WARNING - Try message not found. 03:30:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:30:22 INFO - Python 2.7.3 03:30:22 INFO - Return code: 0 03:30:22 INFO - grabbing minidump binary from tooltool 03:30:22 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')]} 03:30:22 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 0x265a090>, '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 0x265ad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2654f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:30:22 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 03:30:22 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 03:30:22 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 03:30:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:30:22 INFO - Return code: 0 03:30:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:30:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:30:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:30:22 INFO - ENV: RUST_BACKTRACE is now full 03:30:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:30:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:30:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 03:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 03:30:22 INFO - Using env: {'DISPLAY': ':0', 03:30:22 INFO - 'HOME': '/home/cltbld', 03:30:22 INFO - 'LANG': 'en_US.UTF-8', 03:30:22 INFO - 'LANGUAGE': 'en_US:en', 03:30:22 INFO - 'LOGNAME': 'cltbld', 03:30:22 INFO - 'MAIL': '/var/mail/cltbld', 03:30:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:30:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:22 INFO - 'MOZ_NO_REMOTE': '1', 03:30:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:30:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:22 INFO - 'NO_EM_RESTART': '1', 03:30:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:22 INFO - 'PWD': '/builds/slave/test', 03:30:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:30:22 INFO - 'RUST_BACKTRACE': 'full', 03:30:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:30:22 INFO - 'SHELL': '/bin/bash', 03:30:22 INFO - 'SHLVL': '1', 03:30:22 INFO - 'TERM': 'linux', 03:30:22 INFO - 'TMOUT': '86400', 03:30:22 INFO - 'USER': 'cltbld', 03:30:22 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', 03:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:22 INFO - '_': '/tools/buildbot/bin/python'} 03:30:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 03:30:22 INFO - mozversion application_buildid: 20170912093540 03:30:22 INFO - mozversion application_changeset: b0e945eed81db8bf076daf64e381c514f70144f0 03:30:22 INFO - mozversion application_display_name: Nightly 03:30:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:30:22 INFO - mozversion application_name: Firefox 03:30:22 INFO - mozversion application_remotingname: firefox 03:30:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:30:22 INFO - mozversion application_vendor: Mozilla 03:30:22 INFO - mozversion application_version: 57.0a1 03:30:22 INFO - mozversion platform_buildid: 20170912093540 03:30:22 INFO - mozversion platform_changeset: b0e945eed81db8bf076daf64e381c514f70144f0 03:30:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:30:22 INFO - mozversion platform_version: 57.0a1 03:30:22 INFO - using testdate: 1505212222 03:30:22 INFO - actual date: 1505212222 03:30:22 INFO - starting webserver on 'localhost:47952' 03:30:22 INFO - SUITE-START | Running 2 tests 03:30:22 INFO - TEST-START | dromaeo_css 03:30:22 INFO - Initialising browser for dromaeo_css test... 03:30:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47952/getInfo.html -profile /tmp/tmpXwmn9W/profile 03:30:22 INFO - TEST-INFO | started process 14946 (/builds/slave/test/build/application/firefox/firefox http://localhost:47952/getInfo.html) 03:30:27 INFO - TEST-INFO | 14946: exit 0 03:30:27 INFO - Browser initialized. 03:30:27 INFO - Running cycle 1/1 for dromaeo_css test... 03:30:27 INFO - Using env: {'DISPLAY': ':0', 03:30:27 INFO - 'HOME': '/home/cltbld', 03:30:27 INFO - 'JSGC_DISABLE_POISONING': '1', 03:30:27 INFO - 'LANG': 'en_US.UTF-8', 03:30:27 INFO - 'LANGUAGE': 'en_US:en', 03:30:27 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:30:27 INFO - 'LOGNAME': 'cltbld', 03:30:27 INFO - 'MAIL': '/var/mail/cltbld', 03:30:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:30:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:30:27 INFO - 'MOZ_CRASHREPORTER': '1', 03:30:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:30:27 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:30:27 INFO - 'MOZ_NO_REMOTE': '1', 03:30:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:30:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:30:27 INFO - 'NO_EM_RESTART': '1', 03:30:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:30:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:30:27 INFO - 'PWD': '/builds/slave/test', 03:30:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:30:27 INFO - 'RUST_BACKTRACE': 'full', 03:30:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:30:27 INFO - 'SHELL': '/bin/bash', 03:30:27 INFO - 'SHLVL': '1', 03:30:27 INFO - 'TERM': 'linux', 03:30:27 INFO - 'TMOUT': '86400', 03:30:27 INFO - 'USER': 'cltbld', 03:30:27 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', 03:30:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:30:27 INFO - '_': '/tools/buildbot/bin/python'} 03:30:27 INFO - TEST-INFO | started process 15142 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXwmn9W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:30:28 INFO - PID 15142 | 03:30:28 INFO - PID 15142 | (/builds/slave/test/build/application/firefox/firefox:15193): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:30:28 INFO - PID 15142 | 03:30:39 INFO - PID 15142 | RSS: Main: 173342720 03:30:39 INFO - PID 15142 | 03:33:36 INFO - PID 15142 | Cycle 1(1): loaded http://localhost:47952/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47952/tests/dromaeo/cssquery-ext.html) 03:33:37 INFO - PID 15142 | RSS: Main: 175558656 03:33:37 INFO - PID 15142 | 03:36:39 INFO - PID 15142 | Cycle 1(1): loaded http://localhost:47952/tests/dromaeo/cssquery-ext.html (next: http://localhost:47952/tests/dromaeo/cssquery-jquery.html) 03:36:40 INFO - PID 15142 | RSS: Main: 172019712 03:36:40 INFO - PID 15142 | 03:39:41 INFO - PID 15142 | Cycle 1(1): loaded http://localhost:47952/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47952/tests/dromaeo/cssquery-mootools.html) 03:39:42 INFO - PID 15142 | RSS: Main: 173768704 03:39:42 INFO - PID 15142 | 03:42:44 INFO - PID 15142 | Cycle 1(1): loaded http://localhost:47952/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47952/tests/dromaeo/cssquery-prototype.html) 03:42:44 INFO - PID 15142 | RSS: Main: 172740608 03:42:44 INFO - PID 15142 | 03:45:47 INFO - PID 15142 | Cycle 1(1): loaded http://localhost:47952/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47952/tests/dromaeo/cssquery-yui.html) 03:45:48 INFO - PID 15142 | RSS: Main: 172163072 03:45:48 INFO - PID 15142 | 03:48:50 INFO - PID 15142 | Cycle 1(1): loaded http://localhost:47952/tests/dromaeo/cssquery-yui.html (next: http://localhost:47952/tests/dromaeo/cssquery-dojo.html) 03:48:50 INFO - PID 15142 | RSS: Main: 176914432 03:48:50 INFO - PID 15142 | 03:48:50 INFO - PID 15142 | __start_tp_report 03:48:50 INFO - PID 15142 | _x_x_mozilla_page_load 03:48:50 INFO - PID 15142 | _x_x_mozilla_page_load_details 03:48:50 INFO - PID 15142 | |i|pagename|runs| 03:48:50 INFO - PID 15142 | |0;dojo.html;3126.312211313511;3126.953095703565;3175;3178.4914413693814;3186.649468558458;6180.907286390708;6275.497960163188;6332.240131184259;6363.649999250051;6411.390917862807;6270.216222972129;6281.842953926142;6341.873162536746;6439.323870993549;6453.4837213022965;1343.295418107595;5854.912176317359;5882.3529411764675;5921.289445266552;5943.3462319144855;5724.542036637059;5800.47795698387;5886.587938844283;5886.6762328072155;5899.321578018518;5788.218590490272;5817.214676018725;5863.325717542473;5864.003119469679;5865.35481097079;5774.942250577525;5775.27809023872;5835.824925252249;5859.121131830217;5880.147378630075;5749.022666146765;5780.826575202708;5809.622374545684;5827.562932780016;5868.559858010666;6051.878962420727;6262.6868656566985;6351.841203969938;6355.650439225841;6383.521235907326;6634.966825165843;6650.634215118166;6718.3953444189765;6747.257801641816;6757.729690812362;6744.8313792155095;6807.4894382921475;6813.454923606099;6823.351781580742;6825.283345248727;6177.567570270083;6232.25212974446;6310.305866354744;6314.684265786692;6318.083877837734;6857.691403886837;6864.347886950732;6874.175098988154;6874.725010999604;6879.036934829128;6558.8360290993105;6568.408843204134;6570.638614876132;6573.046908198285;6589.670516474157;6758.83102922421;6793.796186114326;6865.0732151159955;6882.070920425678;6892.483063770137;6731.091302674175;6742.494312926452;6786.8642627148165;6814.284500127515;6824;6615.338466153443;6767.661616919135;6779.15260592426;6783.966080169666;6856.485763567753;5276.34045744282;5369.382521010134;5372.597055220874;5376.892462150813;5389.622726409192;4669;4708.81164753406;4713.811447542129;4716.151092803259;4729.62163026957;5803.47768700819;5812.476877081083;5891.911621325684;5892.616979896293;5903.085021821625;6656.201255849329;6717.395434410829;6744.393004629509;6793.286704896013;6802.727890884309;5852.531797456279;5875.9118613220835;5877.23595932526;5881.647101173943;5897.67562784051;5811.302643682785;5867.559933005042;5879.941200588025;5881.382454842265;5884.440978107073;6500.349965003462;6590.275069742324;6594.934050659528;6684.131062961881;6697.631630260383;6558.114654521579;6758.155230596176;6768.255491895986;6860.656967151723;6878.071460352789;6572.342765723581;6662.500312476681;6663.666816659051;6680.933190668031;6690.063391125149;6634.469242460688;6636.568623039487;6640.734370625121;6727.529072964846;6739.191297044386;6652.135222421054;6743.291954344723;6753.425958793558;6754.493412994143;6792.66036698173;6530.118434011348;6541.313162117909;6556.0493728410065;6572.868542629216;6574.737010519526;6500.804975850732;6530.902036469552;6553.115329430657;6604.3725846044545;6617.503687223381;6489.3510648934725;6503.544751867324;6553.672316384066;6584.341565843377;6584.670766461753;6548;6578;6635.734570617121;6681.899771503334;6746.021826835179;6140.5701600887505;6229.657368844757;6324.1146239527425;6384.904226436514;6394.23269207698 03:48:50 INFO - PID 15142 | |1;ext.html;858.579296144889;872.8167084912172;875.7372788163553;881.7134431309823;890.2032680750724;41032.794836025816;41608.37587436191;41716.16567668648;41758;41773.7911310443;39014.60985390145;39179.80410097953;39254;39254;42066;7711.074671039469;7848.68605255789;7854;7855.489393189425;7858.371330293563;7717.112532058801;7864.646090925922;7865.8820117698;7868.095169055546;7879.212078792104;7817.765467035998;7850.057993040843;7869.173736757644;7870.370370370385;7908.406869484812;7812.492188007818;7831.177726338738;7850.528968261894;7859.253370929752;7867.055953285642;7655.961720191363;7705.653245603905;7710.845783084363;7711.807204819925;7713.961430192813;37595.81202093999;37721.62278377209;37753;37761;37973.62026379728;3432.5194472773833;3458.948115778265;3462.4979377990303;3464.116650254194;3467.809270490122;31089.53365699516;31350.37299254025;31364.37271254585;31368;31373.686263137304;5052.570531504826;5234.581233501311;5269.710165940872;5283.471652834724;5294.152935530296;188554;188658;189081;190813;191361.04319478452;42455;43518.12963740707;50165;50822.49177508178;51170.74414627903;51292.23061654078;51330.74334628303;51611;51682.48317516852;51908.48091519112;13133.212007279593;13190.076694631476;13286;13371.866281337258;13394.732105357838;10647.361158330525;10652.36085834837;10712.678619641423;10752.462376881282;10844.72888177786;13377.531786387624;13507.662308442172;13560;13583.932080339535;13698.52055178064;5353.330833645794;5424.705899953572;5425.267588875451;5427.864303392446;5464.3442786865835;1013.1793247469574;1013.7566983923953;1024.7847951930178;1031.3090229546222;1033.7312298802365;22521;22599.54800903973;22962.540749184922;23056.7694323058;23217.3034808956;1123.185690374472;1126.2341607706674;1131.6265632341472;1135.5117299561102;1140.7718456308771;12378.566750163882;12418.565350212699;12432.191907525981;12488.750224995449;12528.060395470377;205427;207568;208063;208676;209220;53701.462985370425;53724;53761;53786.193207101926;53929.46070539323;1430.2979454449173;1463.8536146385488;1465.296657604356;1469.80157678712;1485.3390241342715;22494.32517024492;22697.04938358069;22858.314250572508;22919.312420627408;23192.4201894954;38373;38471.422928655535;38718.032049199;38774;38795.2240955185;189404;189556;189661;190255;190885;32763.672363276062;33221.33557328888;34448;34632.480512792834;34801.47797783086;41473.585264148176;45512.77243613761;46710;47256.054878901545;47347;34032.48951265783;34057.65942340545;34265.31469370649;34528.30943381068;34862.82568587141;33219.50170747392;33535.496967545994;33765.83117084399;33793.49309760405;34407.483887741204;38072.42891356577;38097.61902381052;38424.23151536898;38658;38726.80636596799;33021.33957320792;33391.33217335591;33605.83197083999;34010.489842652874;34143.82928085344;24173.63739543873;24196.274111776675;24555.877220613784;24744;25040.49919001646 03:48:50 INFO - PID 15142 | |2;jquery.html;560.5851669764376;563.8533981164896;567.7566130174914;579.959402841801;583.6906439587019;22285.44286392841;23086.88456557717;23087.191948281812;23116;23143.884280578575;23690.76309236907;24284.514309713795;24710.876445617745;24781;25309.367265818364;6579.078928949939;6776.390124888759;6777.390034896858;6777.4578033757425;6778.661066946657;6595.505337099713;6635.402813746762;6659.200895892475;6665.866682666344;6708.899366509506;6362.9045564316575;6578.079068930354;6647.368499992517;6656.70044847983;6656.733730650768;6602.66986650668;6627;6639.668016599151;6646.302138275484;6649.301823308531;5758.596898217126;5981.820545383645;6025.276966763972;6031.155638210654;6034.064719968412;27883.86058069717;27901.302467438272;28094.438111237865;28216.576751348744;28476;2089.916403343864;2112.41908475169;2117.2483768262355;2120.109553987329;2122.108714339981;18685.252589896387;19332.323368682028;19407.320743774042;19577;19588.902055489772;2440.7437219091917;2442.255112190781;2509.736477669837;2512.560301947152;2514.069794176148;121793;127398.36300818529;127491;130474.34762826125;140353.2982335092;14449.585265238375;14736.21133055363;14779.187144707144;14866.331015104346;15265.007774494623;23171.53656926852;23185.188518402112;23236.53526929452;23311.41721456977;23342.183023594433;9631.518424078768;9779.804403912025;9781.510924453749;9841.114299712917;9906.702798916043;8844.159804818531;8901.465912045298;8928.955355223312;8935.28517718581;8974.461532308082;8941.955290223506;8968.910310896938;9018.954905225562;9115.498647574446;9256.676016339396;2714.6470958775626;2736.38431352944;2753.132763179592;2766.101017169428;2799.7340252675963;944.2587568758491;969.8361965641259;970.1365784451702;991.1723710701501;992.3252188511881;18229.7265541017;18538.536536586693;18903.527411814815;19018;19155.233790648497;1004.5579944824254;1012.3520946594185;1023.9641612543526;1034.6016783538391;1036.486938965217;10262.486875656188;10297.9485102574;10578.206634502443;10666.626668066736;10709.036186743075;458283;462088;477180;497002;497491;108121.45939270253;109495;111451;112035;113774.43112784383;1056.2870062707796;1102.239454776202;1119.9944000279947;1167.0838391862346;1186.6558697977662;19521;19610.50973725668;19678.803211967697;19854.10656520489;20235.089420976386;53699.194512081565;53992.73003635114;54464;54496;57164;69421.65289173521;69566;70087.64956175187;70741;71009;13792.793108103588;13974.720505589628;14018;14103.576892693236;14125;21652;21706.131754729635;21819.127234910426;21895.45261368415;22066.117355305607;17348.913255433643;17451.912740436215;17497.737533937256;17793.110344482982;17906.5523361917;15102;15966.521004369888;15998.360065597246;16317.510474685778;16328.918355408146;17626.29494820244;17675.204615792063;17884.731729024334;17966.101694915462;17999.460016199537;13904.860951390761;13933.860661393257;13935.233562154179;13947.30263486842;13968.092074015156;17320.220590073226;17815.19831607607;17878.731819022465;17939.641207175522;17949.37177198844 03:48:50 INFO - PID 15142 | |3;mootools.html;1267.125683278538;1367.111377604557;1374.628850210444;1379.2896658221018;1399.3493025743028;15062.698746025088;15682.215889205538;15710.371585136581;15720.213989300531;15727.60680982976;15174.924125379357;15244.237788110579;15265.61835954099;15279.770803437958;15287.082775033507;2740.9177724668293;2769.8892044318254;2803.1450407625666;2806.0739955814533;2817.718228177176;2734.576140698185;2766.14249582629;2784.1508339956304;2811.7188281171925;2813.395120049189;2732.3715545424566;2841.5169421198448;2842.2325971987652;2842.7725781937397;2843.616111824898;2764.8202866813594;2781.902633407831;2809.7330753578376;2813.3529288263812;2827.759640430566;2781.8191817531797;2787.414642924988;2803;2803.2991752061985;2806.1441260415563;14931.552053438414;15147.394104235816;15158.31787569551;15158.545243642708;15221;7680.807979800494;7917.722879699183;7965;7971.402144839102;7974.920250797476;5197.792088316501;5370.758315875756;5468.179773034037;5469.644473109275;5487.122060470306;1953.433504283756;1956.5988972260652;1974.6445639784833;2008.1967213114724;2040.2858999350258;26511;26743;26887.596686049725;26892.05877794268;27640.723592764218;6507.967460162668;6534.57525260866;6582.703778329987;6680.766173183915;6784.76253331131;16465.176741163134;16541.751873721903;16595.50213493597;16608.750868736977;16610.418635347705;7704.9614751927;7902.881456778153;7934.920650793418;7937.087234967938;7968.721094761656;7228.783136505913;7335.633218339061;7354.632268386559;7360.263973602704;7373.520721153108;5185.377754669388;5243.554297884646;5273.525382715574;5463.672179669233;5500.972495137498;1515.7347464193722;1515.909045457276;1517.499225255663;1522.695460907823;1535.669830986321;900.7207765592688;929.344811907617;939.1406862720671;939.7274790310734;951.2627713521938;7870.409719271078;7937.285644292041;7941.563214023284;7956.283934445928;8048.959755201186;701.4703898556659;728.4682182007165;731.5720303622334;731.6963460164079;734.474850481908;4616.146012987608;4721.362616046789;4794.976025119852;4875.146849301358;5191.117510023305;20730.792692073188;21144;21196.364109076752;21962.780372196394;22075.779242207373;20688.172473101207;21649.45876353072;21889.562208756055;21927.67108493374;22166;10278.22913281507;10392.324498907547;10582.947085264525;10603.628872989408;10621.575136994588;8394.680480151368;8645.438046526955;8648.221660050625;8674.045854955824;8776.605052772513;18325.175367108244;18709.71935420997;18721;18876.24495020184;18956;19318.4204473866;19344.709829352854;19469.610607788047;19491.317803876795;19588;10973.50619222121;11084.113270938467;11086.61196858106;11109.111271098454;11170.441477926233;13729.656758581115;14013.369398377301;14078.155310681392;14087;14112.29438528049;11089.944550277198;11195.72010699739;11274.830877536679;11422.771544568895;11728.41357932083;11085.889141108808;11236.325820450798;11328.263662861887;11388.544458221579;11436.542538298707;9746.415215087118;9850.162736167505;9962;10000.399976001463;10011.099001089937;7328.120625524971;7334.3032411920785;7362.484626076123;7378.1515125761225;7442.106947166375;6514.185726784152;6597.637129957899;6667.299933506912;6698.095757072733;6717.899231511629 03:48:50 INFO - PID 15142 | |4;prototype.html;510.32127270729956;523.6779380680878;525.2856115682672;527.282895262443;529.7827890564866;6333.873322533546;6349.7460101596;6360.300366959631;6387.872242555146;6414.454771344442;6269.714708484761;6283.151774510439;6304.369563043693;6306.527010474221;6355.841103972403;5240.9213861792105;5248.422673505911;5249.238860365244;5262.0791361511865;5270.710110943896;4891.266310053503;5050.419201791786;5053.938672878699;5215.921761173567;5251.921221181686;4991.226359914201;4998.075356059123;4999.500049995007;5004.449510553854;5023.271625614284;4988.251762235657;5013.799448022075;5019.42276638184;5024.598032157437;5032.748362581856;4381.583749543788;4481.193385190664;4503.572160644733;4508.120916421299;4514.887127821798;6134.539909506805;6161.661108639068;6172.814815555541;6175.536834737412;6206.689665516707;5647.458643610392;5682.971585142048;5757.884842303172;5759.827205183851;5763.567732420085;6538.607683539003;6538.771143009972;6557.803265902031;6600.976848588429;6613.437857782094;4805.327254184417;4854.975725121351;4876.000419913909;4899.681520701179;4914.754262286871;6469.320721324288;6666.566673166229;6750;6858.485613579001;6864.553804002724;4940.258961155783;5000.599952003831;5008.223725322581;5020.297158397827;5033.7734801934;5015.570562389701;5032.899342013213;5042.621803364689;5052.646314758005;5055.418626857959;5659.6321239119325;5667.574931880125;5688.260526131577;5704.543636509069;5729.109393900087;6600.207975043026;6603;6604.702788374535;6636.303188165173;6666.200055993312;1739.4346837277935;1740.4778566430273;1761.9030953297436;1772.450540332501;1776.546980519959;1137.8577677790277;1196.2284326609288;1206.6561030106377;1209.2502648358075;1224.1393082514705;5622.100463925834;5657.830265092054;5689.601727879009;5703.287089113861;5729;6625.238097618739;6700.363465470772;6714.194296684429;6756.290589488131;6758.256591774901;5625.353084395265;5695.775408287238;5750.159948816422;5769.221155144001;5792.768289268466;3487.494313324554;3553.3248682750195;3556.324298383299;3571.964280357215;3586.1751797086818;6416.486681065504;6433.163688720437;6452.741890324334;6465.64438955862;6472.255690595548;6586;6816.977453381934;6819.624920629314;6880.2431732509385;6930.826729331807;6411.647359395185;6418.935810641834;6420.775272865428;6460.095586617877;6492.448141907897;6101.29835068955;6633.402993730588;6639.933600664125;6642.667866606747;6654.201495820533;6573.1783527059115;6585.80242592723;6592.7033283503315;6641.734330626721;6643.56816806906;6413.903791443226;6445.2265728112925;6450.612963222222;6476.190476190476;6479.967600161969;4941.382327209099;4969.83812041763;4979.153543897473;5024.472430394756;5029.74851257443;6485.254195767548;6550.803475895731;6726.562773419712;6749.898751518633;6770.796876093725;6366.458850997532;6424.807255782334;6502.154719886385;6513.111197752788;6533.18335208099;6346.238451385863;6457.675236914613;6458.25627080695;6481.481481481376;6501.2446639408045;6354.714037868214;6400.775972840928;6452.612688272044;6492.74029038852;6518.120053792867;6561.540692151503;6606.471482281502;6662.200535935719;6674.499412544175;6685.933140668531;6166.383361663798;6194.349593292639;6306.621602703853;6318.4313411793155;6374.681265936591 03:48:50 INFO - PID 15142 | |5;yui.html;892.5537231384308;912.3431129586697;921.0881227584694;936.3445588088335;944.5607792376545;15660.608484787886;16408.34366625335;16474.093924834146;16499.257533410997;16517.256723447445;14956.700865982673;15763.605909852231;15949.760753588676;15964.84035159648;15965;918.2883265469264;933.3466573398614;935.1910597333292;935.7894473743421;935.957881895313;956.8086382723483;961.4087336288202;961.8990006049369;962.0427674463883;962.1148543340144;973.3964941736134;1000.4047591682973;1004.573056451009;1013.9746506337327;1020.505055048301;985.8866230383526;994.607130183577;1000.0249756487403;1000.6597756762662;1001.7044971733357;729.784713509516;730.0837449001522;735.1288722863424;737.306931484403;737.7086051009821;16195.757063644054;16202.75695864563;16381.83618163815;16391.42630007944;16414.26135823891;20966.475838104016;21951.890240548695;21992;22078.88960555203;22090.226842060612;17252.13739313029;17896.28414863404;18026;18028.098595070194;18051.458456246335;3688.907777305562;3720.981395093007;3738.140227747633;3746.194568167857;3750.2124553843723;127938.3603081988;129522.3523882384;130132;130140;132239.33880330634;16209.918950405408;16752.329906803592;16835.915820421065;16842.15789210559;16884.493465196065;30518;31500.36999260002;31683.84158079195;31706.365872682418;31766.523502147025;7987.281144697005;8122.634481448349;8196.34429245659;8215.917840821634;8217;4037.6971727120585;4137.2552940470405;4140.648044916155;4144.44050053245;4145.937810932838;5297.417284098746;5369.812056578001;5380.166074258496;5410.269613602192;5415.160650099241;948.1656142594472;971.4948226922103;990.5344488090586;992.846108853129;995.7012896131132;639.3861892583172;652.3411354531862;664.8171752768028;667.9198496180393;673.2056173715064;11425.31448113116;11735.4132293385;11810.173287869939;11826.113041521749;11934.28394296345;625.8560531077786;628.5474458389951;630.8170630517099;631.0691729698658;639.0605809460086;5742.655440673573;5748.310202775694;5751.769929202785;5832.795852145155;5893;115726;116378;117943.41028294976;119585;121750.39124804319;106379.468102659;107807;110031.44984275027;110496.35633180801;110914.44542777234;88739;89721;91846.08153918509;94455.52772236094;96551.51724241333;10530.526126324487;10691.358518488605;10731.356118632906;10774.922507749163;10920.563177472812;42495;42654;43124.35313470363;43923.56076439195;44041.77979110084;54181;54279.45720542744;54719.45280547144;54726.452735472136;56087.71956140193;13297.601071967856;13459;13500;13514.39185236647;13529.661758456117;15755.212239388215;15915.204239788196;15941.840581594035;16000.279987400827;16502.587435314214;16191.514254572381;16204.513864584082;16294.592635184215;16475.01149931008;16476;15234.695306094038;15518.612034699223;15576.221188940734;15664.765028524353;15673.451429200379;14360.497382591559;14538.200398978157;14597.12417254968;14698.41206351734;14718.26408679583;11678.941605291919;11847.407629618312;11947.581834635746;12308.50765969387;12369.505219791108;11776;12019.75960480803;12215.69460763488;12424.813627795409;12562.246265224116 03:48:50 INFO - PID 15142 | __end_tp_report 03:48:50 INFO - PID 15142 | __start_cc_report 03:48:50 INFO - PID 15142 | _x_x_mozilla_cycle_collect,386 03:48:50 INFO - PID 15142 | __end_cc_report 03:48:50 INFO - PID 15142 | __startTimestamp1505213330407__endTimestamp 03:48:50 INFO - PID 15142 | 03:48:50 INFO - PID 15142 | ------- Summary: start ------- 03:48:50 INFO - PID 15142 | Number of tests: 6 03:48:50 INFO - PID 15142 | 03:48:50 INFO - PID 15142 | [#0] dojo.html Cycles:165 Average:6195.61 Median:6500.80 stddev:825.66 (12.7%) stddev-sans-first:792.31 03:48:50 INFO - PID 15142 | Values: 3126.3 3127.0 3175.0 3178.5 3186.6 6180.9 6275.5 6332.2 6363.6 6411.4 6270.2 6281.8 6341.9 6439.3 6453.5 1343.3 5854.9 5882.4 5921.3 5943.3 5724.5 5800.5 5886.6 5886.7 5899.3 5788.2 5817.2 5863.3 5864.0 5865.4 5774.9 5775.3 5835.8 5859.1 5880.1 5749.0 5780.8 5809.6 5827.6 5868.6 6051.9 6262.7 6351.8 6355.7 6383.5 6635.0 6650.6 6718.4 6747.3 6757.7 6744.8 6807.5 6813.5 6823.4 6825.3 6177.6 6232.3 6310.3 6314.7 6318.1 6857.7 6864.3 6874.2 6874.7 6879.0 6558.8 6568.4 6570.6 6573.0 6589.7 6758.8 6793.8 6865.1 6882.1 6892.5 6731.1 6742.5 6786.9 6814.3 6824.0 6615.3 6767.7 6779.2 6784.0 6856.5 5276.3 5369.4 5372.6 5376.9 5389.6 4669.0 4708.8 4713.8 4716.2 4729.6 5803.5 5812.5 5891.9 5892.6 5903.1 6656.2 6717.4 6744.4 6793.3 6802.7 5852.5 5875.9 5877.2 5881.6 5897.7 5811.3 5867.6 5879.9 5881.4 5884.4 6500.3 6590.3 6594.9 6684.1 6697.6 6558.1 6758.2 6768.3 6860.7 6878.1 6572.3 6662.5 6663.7 6680.9 6690.1 6634.5 6636.6 6640.7 6727.5 6739.2 6652.1 6743.3 6753.4 6754.5 6792.7 6530.1 6541.3 6556.0 6572.9 6574.7 6500.8 6530.9 6553.1 6604.4 6617.5 6489.4 6503.5 6553.7 6584.3 6584.7 6548.0 6578.0 6635.7 6681.9 6746.0 6140.6 6229.7 6324.1 6384.9 6394.2 03:48:50 INFO - PID 15142 | 03:48:50 INFO - PID 15142 | [#1] ext.html Cycles:180 Average:36863.87 Median:19048.30 stddev:50746.28 (266.4%) stddev-sans-first:50816.62 03:48:50 INFO - PID 15142 | Values: 858.6 872.8 875.7 881.7 890.2 41032.8 41608.4 41716.2 41758.0 41773.8 39014.6 39179.8 39254.0 39254.0 42066.0 7711.1 7848.7 7854.0 7855.5 7858.4 7717.1 7864.6 7865.9 7868.1 7879.2 7817.8 7850.1 7869.2 7870.4 7908.4 7812.5 7831.2 7850.5 7859.3 7867.1 7656.0 7705.7 7710.8 7711.8 7714.0 37595.8 37721.6 37753.0 37761.0 37973.6 3432.5 3458.9 3462.5 3464.1 3467.8 31089.5 31350.4 31364.4 31368.0 31373.7 5052.6 5234.6 5269.7 5283.5 5294.2 188554.0 188658.0 189081.0 190813.0 191361.0 42455.0 43518.1 50165.0 50822.5 51170.7 51292.2 51330.7 51611.0 51682.5 51908.5 13133.2 13190.1 13286.0 13371.9 13394.7 10647.4 10652.4 10712.7 10752.5 10844.7 13377.5 13507.7 13560.0 13583.9 13698.5 5353.3 5424.7 5425.3 5427.9 5464.3 1013.2 1013.8 1024.8 1031.3 1033.7 22521.0 22599.5 22962.5 23056.8 23217.3 1123.2 1126.2 1131.6 1135.5 1140.8 12378.6 12418.6 12432.2 12488.8 12528.1 205427.0 207568.0 208063.0 208676.0 209220.0 53701.5 53724.0 53761.0 53786.2 53929.5 1430.3 1463.9 1465.3 1469.8 1485.3 22494.3 22697.0 22858.3 22919.3 23192.4 38373.0 38471.4 38718.0 38774.0 38795.2 189404.0 189556.0 189661.0 190255.0 190885.0 32763.7 33221.3 34448.0 34632.5 34801.5 41473.6 45512.8 46710.0 47256.1 47347.0 34032.5 34057.7 34265.3 34528.3 34862.8 33219.5 33535.5 33765.8 33793.5 34407.5 38072.4 38097.6 38424.2 38658.0 38726.8 33021.3 33391.3 33605.8 34010.5 34143.8 24173.6 24196.3 24555.9 24744.0 25040.5 03:48:50 INFO - PID 15142 | 03:48:50 INFO - PID 15142 | [#2] jquery.html Cycles:180 Average:33782.44 Median:23178.36 stddev:80428.81 (347.0%) stddev-sans-first:80615.75 03:48:50 INFO - PID 15142 | Values: 560.6 563.9 567.8 580.0 583.7 22285.4 23086.9 23087.2 23116.0 23143.9 23690.8 24284.5 24710.9 24781.0 25309.4 6579.1 6776.4 6777.4 6777.5 6778.7 6595.5 6635.4 6659.2 6665.9 6708.9 6362.9 6578.1 6647.4 6656.7 6656.7 6602.7 6627.0 6639.7 6646.3 6649.3 5758.6 5981.8 6025.3 6031.2 6034.1 27883.9 27901.3 28094.4 28216.6 28476.0 2089.9 2112.4 2117.2 2120.1 2122.1 18685.3 19332.3 19407.3 19577.0 19588.9 2440.7 2442.3 2509.7 2512.6 2514.1 121793.0 127398.4 127491.0 130474.3 140353.3 14449.6 14736.2 14779.2 14866.3 15265.0 23171.5 23185.2 23236.5 23311.4 23342.2 9631.5 9779.8 9781.5 9841.1 9906.7 8844.2 8901.5 8929.0 8935.3 8974.5 8942.0 8968.9 9019.0 9115.5 9256.7 2714.6 2736.4 2753.1 2766.1 2799.7 944.3 969.8 970.1 991.2 992.3 18229.7 18538.5 18903.5 19018.0 19155.2 1004.6 1012.4 1024.0 1034.6 1036.5 10262.5 10297.9 10578.2 10666.6 10709.0 458283.0 462088.0 477180.0 497002.0 497491.0 108121.5 109495.0 111451.0 112035.0 113774.4 1056.3 1102.2 1120.0 1167.1 1186.7 19521.0 19610.5 19678.8 19854.1 20235.1 53699.2 53992.7 54464.0 54496.0 57164.0 69421.7 69566.0 70087.6 70741.0 71009.0 13792.8 13974.7 14018.0 14103.6 14125.0 21652.0 21706.1 21819.1 21895.5 22066.1 17348.9 17451.9 17497.7 17793.1 17906.6 15102.0 15966.5 15998.4 16317.5 16328.9 17626.3 17675.2 17884.7 17966.1 17999.5 13904.9 13933.9 13935.2 13947.3 13968.1 17320.2 17815.2 17878.7 17939.6 17949.4 03:48:50 INFO - PID 15142 | 03:48:50 INFO - PID 15142 | [#3] mootools.html Cycles:180 Average:9312.33 Median:15203.43 stddev:6708.67 (44.1%) stddev-sans-first:6700.26 03:48:50 INFO - PID 15142 | Values: 1267.1 1367.1 1374.6 1379.3 1399.3 15062.7 15682.2 15710.4 15720.2 15727.6 15174.9 15244.2 15265.6 15279.8 15287.1 2740.9 2769.9 2803.1 2806.1 2817.7 2734.6 2766.1 2784.2 2811.7 2813.4 2732.4 2841.5 2842.2 2842.8 2843.6 2764.8 2781.9 2809.7 2813.4 2827.8 2781.8 2787.4 2803.0 2803.3 2806.1 14931.6 15147.4 15158.3 15158.5 15221.0 7680.8 7917.7 7965.0 7971.4 7974.9 5197.8 5370.8 5468.2 5469.6 5487.1 1953.4 1956.6 1974.6 2008.2 2040.3 26511.0 26743.0 26887.6 26892.1 27640.7 6508.0 6534.6 6582.7 6680.8 6784.8 16465.2 16541.8 16595.5 16608.8 16610.4 7705.0 7902.9 7934.9 7937.1 7968.7 7228.8 7335.6 7354.6 7360.3 7373.5 5185.4 5243.6 5273.5 5463.7 5501.0 1515.7 1515.9 1517.5 1522.7 1535.7 900.7 929.3 939.1 939.7 951.3 7870.4 7937.3 7941.6 7956.3 8049.0 701.5 728.5 731.6 731.7 734.5 4616.1 4721.4 4795.0 4875.1 5191.1 20730.8 21144.0 21196.4 21962.8 22075.8 20688.2 21649.5 21889.6 21927.7 22166.0 10278.2 10392.3 10582.9 10603.6 10621.6 8394.7 8645.4 8648.2 8674.0 8776.6 18325.2 18709.7 18721.0 18876.2 18956.0 19318.4 19344.7 19469.6 19491.3 19588.0 10973.5 11084.1 11086.6 11109.1 11170.4 13729.7 14013.4 14078.2 14087.0 14112.3 11089.9 11195.7 11274.8 11422.8 11728.4 11085.9 11236.3 11328.3 11388.5 11436.5 9746.4 9850.2 9962.0 10000.4 10011.1 7328.1 7334.3 7362.5 7378.2 7442.1 6514.2 6597.6 6667.3 6698.1 6717.9 03:48:50 INFO - PID 15142 | 03:48:50 INFO - PID 15142 | [#4] prototype.html Cycles:180 Average:5507.67 Median:6184.94 stddev:1533.29 (24.8%) stddev-sans-first:1491.01 03:48:50 INFO - PID 15142 | Values: 510.3 523.7 525.3 527.3 529.8 6333.9 6349.7 6360.3 6387.9 6414.5 6269.7 6283.2 6304.4 6306.5 6355.8 5240.9 5248.4 5249.2 5262.1 5270.7 4891.3 5050.4 5053.9 5215.9 5251.9 4991.2 4998.1 4999.5 5004.4 5023.3 4988.3 5013.8 5019.4 5024.6 5032.7 4381.6 4481.2 4503.6 4508.1 4514.9 6134.5 6161.7 6172.8 6175.5 6206.7 5647.5 5683.0 5757.9 5759.8 5763.6 6538.6 6538.8 6557.8 6601.0 6613.4 4805.3 4855.0 4876.0 4899.7 4914.8 6469.3 6666.6 6750.0 6858.5 6864.6 4940.3 5000.6 5008.2 5020.3 5033.8 5015.6 5032.9 5042.6 5052.6 5055.4 5659.6 5667.6 5688.3 5704.5 5729.1 6600.2 6603.0 6604.7 6636.3 6666.2 1739.4 1740.5 1761.9 1772.5 1776.5 1137.9 1196.2 1206.7 1209.3 1224.1 5622.1 5657.8 5689.6 5703.3 5729.0 6625.2 6700.4 6714.2 6756.3 6758.3 5625.4 5695.8 5750.2 5769.2 5792.8 3487.5 3553.3 3556.3 3572.0 3586.2 6416.5 6433.2 6452.7 6465.6 6472.3 6586.0 6817.0 6819.6 6880.2 6930.8 6411.6 6418.9 6420.8 6460.1 6492.4 6101.3 6633.4 6639.9 6642.7 6654.2 6573.2 6585.8 6592.7 6641.7 6643.6 6413.9 6445.2 6450.6 6476.2 6480.0 4941.4 4969.8 4979.2 5024.5 5029.7 6485.3 6550.8 6726.6 6749.9 6770.8 6366.5 6424.8 6502.2 6513.1 6533.2 6346.2 6457.7 6458.3 6481.5 6501.2 6354.7 6400.8 6452.6 6492.7 6518.1 6561.5 6606.5 6662.2 6674.5 6685.9 6166.4 6194.3 6306.6 6318.4 6374.7 03:48:50 INFO - PID 15142 | 03:48:50 INFO - PID 15142 | [#5] yui.html Cycles:180 Average:23374.41 Median:18039.78 stddev:33943.03 (188.2%) stddev-sans-first:33996.27 03:48:50 INFO - PID 15142 | Values: 892.6 912.3 921.1 936.3 944.6 15660.6 16408.3 16474.1 16499.3 16517.3 14956.7 15763.6 15949.8 15964.8 15965.0 918.3 933.3 935.2 935.8 936.0 956.8 961.4 961.9 962.0 962.1 973.4 1000.4 1004.6 1014.0 1020.5 985.9 994.6 1000.0 1000.7 1001.7 729.8 730.1 735.1 737.3 737.7 16195.8 16202.8 16381.8 16391.4 16414.3 20966.5 21951.9 21992.0 22078.9 22090.2 17252.1 17896.3 18026.0 18028.1 18051.5 3688.9 3721.0 3738.1 3746.2 3750.2 127938.4 129522.4 130132.0 130140.0 132239.3 16209.9 16752.3 16835.9 16842.2 16884.5 30518.0 31500.4 31683.8 31706.4 31766.5 7987.3 8122.6 8196.3 8215.9 8217.0 4037.7 4137.3 4140.6 4144.4 4145.9 5297.4 5369.8 5380.2 5410.3 5415.2 948.2 971.5 990.5 992.8 995.7 639.4 652.3 664.8 667.9 673.2 11425.3 11735.4 11810.2 11826.1 11934.3 625.9 628.5 630.8 631.1 639.1 5742.7 5748.3 5751.8 5832.8 5893.0 115726.0 116378.0 117943.4 119585.0 121750.4 106379.5 107807.0 110031.4 110496.4 110914.4 88739.0 89721.0 91846.1 94455.5 96551.5 10530.5 10691.4 10731.4 10774.9 10920.6 42495.0 42654.0 43124.4 43923.6 44041.8 54181.0 54279.5 54719.5 54726.5 56087.7 13297.6 13459.0 13500.0 13514.4 13529.7 15755.2 15915.2 15941.8 16000.3 16502.6 16191.5 16204.5 16294.6 16475.0 16476.0 15234.7 15518.6 15576.2 15664.8 15673.5 14360.5 14538.2 14597.1 14698.4 14718.3 11678.9 11847.4 11947.6 12308.5 12369.5 11776.0 12019.8 12215.7 12424.8 12562.2 03:48:50 INFO - PID 15142 | -------- Summary: end -------- 03:48:50 INFO - PID 15142 | 03:48:50 INFO - PID 15142 | 03:48:50 INFO - PID 15142 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 03:48:50 INFO - PID 15142 | 03:48:51 INFO - TEST-INFO | 15142: exit 0 03:48:51 INFO - TEST-OK | dromaeo_css | took 1108680ms 03:48:51 INFO - TEST-START | kraken 03:48:51 INFO - Initialising browser for kraken test... 03:48:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47952/getInfo.html -profile /tmp/tmpeERUEG/profile 03:48:51 INFO - TEST-INFO | started process 15525 (/builds/slave/test/build/application/firefox/firefox http://localhost:47952/getInfo.html) 03:48:56 INFO - TEST-INFO | 15525: exit 0 03:48:56 INFO - Browser initialized. 03:48:56 INFO - Running cycle 1/1 for kraken test... 03:48:56 INFO - Using env: {'DISPLAY': ':0', 03:48:56 INFO - 'HOME': '/home/cltbld', 03:48:56 INFO - 'JSGC_DISABLE_POISONING': '1', 03:48:56 INFO - 'LANG': 'en_US.UTF-8', 03:48:56 INFO - 'LANGUAGE': 'en_US:en', 03:48:56 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:48:56 INFO - 'LOGNAME': 'cltbld', 03:48:56 INFO - 'MAIL': '/var/mail/cltbld', 03:48:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:48:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:48:56 INFO - 'MOZ_CRASHREPORTER': '1', 03:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:48:56 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:48:56 INFO - 'MOZ_NO_REMOTE': '1', 03:48:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:48:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:48:56 INFO - 'NO_EM_RESTART': '1', 03:48:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:48:56 INFO - 'PWD': '/builds/slave/test', 03:48:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:48:56 INFO - 'RUST_BACKTRACE': 'full', 03:48:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:48:56 INFO - 'SHELL': '/bin/bash', 03:48:56 INFO - 'SHLVL': '1', 03:48:56 INFO - 'TERM': 'linux', 03:48:56 INFO - 'TMOUT': '86400', 03:48:56 INFO - 'USER': 'cltbld', 03:48:56 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829', 03:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:48:56 INFO - '_': '/tools/buildbot/bin/python'} 03:48:56 INFO - TEST-INFO | started process 15722 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeERUEG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:48:57 INFO - PID 15722 | 03:48:57 INFO - PID 15722 | (/builds/slave/test/build/application/firefox/firefox:15773): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:48:57 INFO - PID 15722 | 03:49:08 INFO - PID 15722 | RSS: Main: 172888064 03:49:08 INFO - PID 15722 | 03:49:10 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47952/tests/kraken/driver.html?testName=audio-beat-detection) 03:49:11 INFO - PID 15722 | RSS: Main: 177647616 03:49:11 INFO - PID 15722 | 03:49:14 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47952/tests/kraken/driver.html?testName=audio-dft) 03:49:14 INFO - PID 15722 | RSS: Main: 178130944 03:49:14 INFO - PID 15722 | 03:49:17 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47952/tests/kraken/driver.html?testName=audio-fft) 03:49:18 INFO - PID 15722 | RSS: Main: 174125056 03:49:18 INFO - PID 15722 | 03:49:20 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47952/tests/kraken/driver.html?testName=audio-oscillator) 03:49:21 INFO - PID 15722 | RSS: Main: 174845952 03:49:21 INFO - PID 15722 | 03:49:23 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47952/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:49:24 INFO - PID 15722 | RSS: Main: 174960640 03:49:24 INFO - PID 15722 | 03:49:27 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47952/tests/kraken/driver.html?testName=imaging-darkroom) 03:49:27 INFO - PID 15722 | RSS: Main: 177373184 03:49:27 INFO - PID 15722 | 03:49:31 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47952/tests/kraken/driver.html?testName=imaging-desaturate) 03:49:32 INFO - PID 15722 | RSS: Main: 177184768 03:49:32 INFO - PID 15722 | 03:49:35 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47952/tests/kraken/driver.html?testName=json-parse-financial) 03:49:35 INFO - PID 15722 | RSS: Main: 177074176 03:49:35 INFO - PID 15722 | 03:49:37 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47952/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:49:38 INFO - PID 15722 | RSS: Main: 177299456 03:49:38 INFO - PID 15722 | 03:49:39 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47952/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:49:40 INFO - PID 15722 | RSS: Main: 177209344 03:49:40 INFO - PID 15722 | 03:49:42 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47952/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:49:43 INFO - PID 15722 | RSS: Main: 174231552 03:49:43 INFO - PID 15722 | 03:49:45 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47952/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:49:46 INFO - PID 15722 | RSS: Main: 174678016 03:49:46 INFO - PID 15722 | 03:49:49 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47952/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:49:49 INFO - PID 15722 | RSS: Main: 174510080 03:49:49 INFO - PID 15722 | 03:49:51 INFO - PID 15722 | Cycle 1(1): loaded http://localhost:47952/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47952/tests/kraken/driver.html?testName=ai-astar) 03:49:52 INFO - PID 15722 | RSS: Main: 175538176 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | __start_tp_report 03:49:52 INFO - PID 15722 | _x_x_mozilla_page_load 03:49:52 INFO - PID 15722 | _x_x_mozilla_page_load_details 03:49:52 INFO - PID 15722 | |i|pagename|runs| 03:49:52 INFO - PID 15722 | |0;ai-astar;106;107;106;106;106;108;109;106;108;106 03:49:52 INFO - PID 15722 | |1;audio-beat-detection;120;119;115;119;115;118;140;115;118;116 03:49:52 INFO - PID 15722 | |2;audio-dft;165;164;164;164;164;162;170;172;163;165 03:49:52 INFO - PID 15722 | |3;audio-fft;74;74;73;77;73;76;74;75;74;74 03:49:52 INFO - PID 15722 | |4;audio-oscillator;80;80;79;79;79;80;78;79;80;80 03:49:52 INFO - PID 15722 | |5;imaging-gaussian-blur;114;115;110;115;112;114;114;114;114;116 03:49:52 INFO - PID 15722 | |6;imaging-darkroom;148;149;148;156;150;148;148;149;147;148 03:49:52 INFO - PID 15722 | |7;imaging-desaturate;95;94;93;94;94;94;95;94;94;94 03:49:52 INFO - PID 15722 | |8;json-parse-financial;54;53;53;57;55;54;55;55;53;55 03:49:52 INFO - PID 15722 | |9;json-stringify-tinderbox;39;40;39;40;38;41;39;38;39;39 03:49:52 INFO - PID 15722 | |10;stanford-crypto-aes;78;78;80;81;78;74;74;74;78;74 03:49:52 INFO - PID 15722 | |11;stanford-crypto-ccm;115;110;121;113;113;109;113;113;116;121 03:49:52 INFO - PID 15722 | |12;stanford-crypto-pbkdf2;175;171;169;170;171;166;166;173;169;167 03:49:52 INFO - PID 15722 | |13;stanford-crypto-sha256-iterative;70;65;66;67;76;63;65;65;71;64 03:49:52 INFO - PID 15722 | __end_tp_report 03:49:52 INFO - PID 15722 | __start_cc_report 03:49:52 INFO - PID 15722 | _x_x_mozilla_cycle_collect,929 03:49:52 INFO - PID 15722 | __end_cc_report 03:49:52 INFO - PID 15722 | __startTimestamp1505213392039__endTimestamp 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | ------- Summary: start ------- 03:49:52 INFO - PID 15722 | Number of tests: 14 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#0] ai-astar Cycles:10 Average:106.80 Median:106.50 stddev:1.14 (1.1%) stddev-sans-first:1.17 03:49:52 INFO - PID 15722 | Values: 106.0 107.0 106.0 106.0 106.0 108.0 109.0 106.0 108.0 106.0 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#1] audio-beat-detection Cycles:10 Average:119.50 Median:118.50 stddev:7.44 (6.3%) stddev-sans-first:7.89 03:49:52 INFO - PID 15722 | Values: 120.0 119.0 115.0 119.0 115.0 118.0 140.0 115.0 118.0 116.0 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#2] audio-dft Cycles:10 Average:165.30 Median:164.50 stddev:3.16 (1.9%) stddev-sans-first:3.35 03:49:52 INFO - PID 15722 | Values: 165.0 164.0 164.0 164.0 164.0 162.0 170.0 172.0 163.0 165.0 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#3] audio-fft Cycles:10 Average:74.40 Median:74.00 stddev:1.26 (1.7%) stddev-sans-first:1.33 03:49:52 INFO - PID 15722 | Values: 74.0 74.0 73.0 77.0 73.0 76.0 74.0 75.0 74.0 74.0 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#4] audio-oscillator Cycles:10 Average:79.40 Median:80.00 stddev:0.70 (0.9%) stddev-sans-first:0.71 03:49:52 INFO - PID 15722 | Values: 80.0 80.0 79.0 79.0 79.0 80.0 78.0 79.0 80.0 80.0 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:1.69 (1.5%) stddev-sans-first:1.79 03:49:52 INFO - PID 15722 | Values: 114.0 115.0 110.0 115.0 112.0 114.0 114.0 114.0 114.0 116.0 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:148.50 stddev:2.56 (1.7%) stddev-sans-first:2.68 03:49:52 INFO - PID 15722 | Values: 148.0 149.0 148.0 156.0 150.0 148.0 148.0 149.0 147.0 148.0 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 03:49:52 INFO - PID 15722 | Values: 95.0 94.0 93.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:1.26 (2.3%) stddev-sans-first:1.33 03:49:52 INFO - PID 15722 | Values: 54.0 53.0 53.0 57.0 55.0 54.0 55.0 55.0 53.0 55.0 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#9] json-stringify-tinderbox Cycles:10 Average:39.20 Median:39.00 stddev:0.92 (2.4%) stddev-sans-first:0.97 03:49:52 INFO - PID 15722 | Values: 39.0 40.0 39.0 40.0 38.0 41.0 39.0 38.0 39.0 39.0 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:78.00 stddev:2.69 (3.4%) stddev-sans-first:2.82 03:49:52 INFO - PID 15722 | Values: 78.0 78.0 80.0 81.0 78.0 74.0 74.0 74.0 78.0 74.0 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#11] stanford-crypto-ccm Cycles:10 Average:114.40 Median:114.00 stddev:4.03 (3.5%) stddev-sans-first:4.27 03:49:52 INFO - PID 15722 | Values: 115.0 110.0 121.0 113.0 113.0 109.0 113.0 113.0 116.0 121.0 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:170.50 stddev:2.95 (1.7%) stddev-sans-first:2.42 03:49:52 INFO - PID 15722 | Values: 175.0 171.0 169.0 170.0 171.0 166.0 166.0 173.0 169.0 167.0 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:66.50 stddev:3.99 (6.0%) stddev-sans-first:4.11 03:49:52 INFO - PID 15722 | Values: 70.0 65.0 66.0 67.0 76.0 63.0 65.0 65.0 71.0 64.0 03:49:52 INFO - PID 15722 | -------- Summary: end -------- 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 03:49:52 INFO - PID 15722 | 03:49:52 INFO - PID 15722 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:49:52 INFO - TEST-INFO | 15722: exit 0 03:49:52 INFO - TEST-OK | kraken | took 61592ms 03:49:52 INFO - SUITE-END | took 1170s 03:49:53 INFO - Completed test suite (00:19:30) 03:49:53 INFO - javascript benchmark 03:49:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8417.228888839096, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3126.312211313511, 3126.953095703565, 3175.0, 3178.4914413693814, 3186.649468558458, 6180.907286390708, 6275.497960163188, 6332.240131184259, 6363.649999250051, 6411.390917862807, 6270.216222972129, 6281.842953926142, 6341.873162536746, 6439.323870993549, 6453.4837213022965, 1343.295418107595, 5854.912176317359, 5882.3529411764675, 5921.289445266552, 5943.3462319144855, 5724.542036637059, 5800.47795698387, 5886.587938844283, 5886.6762328072155, 5899.321578018518, 5788.218590490272, 5817.214676018725, 5863.325717542473, 5864.003119469679, 5865.35481097079, 5774.942250577525, 5775.27809023872, 5835.824925252249, 5859.121131830217, 5880.147378630075, 5749.022666146765, 5780.826575202708, 5809.622374545684, 5827.562932780016, 5868.559858010666, 6051.878962420727, 6262.6868656566985, 6351.841203969938, 6355.650439225841, 6383.521235907326, 6634.966825165843, 6650.634215118166, 6718.3953444189765, 6747.257801641816, 6757.729690812362, 6744.8313792155095, 6807.4894382921475, 6813.454923606099, 6823.351781580742, 6825.283345248727, 6177.567570270083, 6232.25212974446, 6310.305866354744, 6314.684265786692, 6318.083877837734, 6857.691403886837, 6864.347886950732, 6874.175098988154, 6874.725010999604, 6879.036934829128, 6558.8360290993105, 6568.408843204134, 6570.638614876132, 6573.046908198285, 6589.670516474157, 6758.83102922421, 6793.796186114326, 6865.0732151159955, 6882.070920425678, 6892.483063770137, 6731.091302674175, 6742.494312926452, 6786.8642627148165, 6814.284500127515, 6824.0, 6615.338466153443, 6767.661616919135, 6779.15260592426, 6783.966080169666, 6856.485763567753, 5276.34045744282, 5369.382521010134, 5372.597055220874, 5376.892462150813, 5389.622726409192, 4669.0, 4708.81164753406, 4713.811447542129, 4716.151092803259, 4729.62163026957, 5803.47768700819, 5812.476877081083, 5891.911621325684, 5892.616979896293, 5903.085021821625, 6656.201255849329, 6717.395434410829, 6744.393004629509, 6793.286704896013, 6802.727890884309, 5852.531797456279, 5875.9118613220835, 5877.23595932526, 5881.647101173943, 5897.67562784051, 5811.302643682785, 5867.559933005042, 5879.941200588025, 5881.382454842265, 5884.440978107073, 6500.349965003462, 6590.275069742324, 6594.934050659528, 6684.131062961881, 6697.631630260383, 6558.114654521579, 6758.155230596176, 6768.255491895986, 6860.656967151723, 6878.071460352789, 6572.342765723581, 6662.500312476681, 6663.666816659051, 6680.933190668031, 6690.063391125149, 6634.469242460688, 6636.568623039487, 6640.734370625121, 6727.529072964846, 6739.191297044386, 6652.135222421054, 6743.291954344723, 6753.425958793558, 6754.493412994143, 6792.66036698173, 6530.118434011348, 6541.313162117909, 6556.0493728410065, 6572.868542629216, 6574.737010519526, 6500.804975850732, 6530.902036469552, 6553.115329430657, 6604.3725846044545, 6617.503687223381, 6489.3510648934725, 6503.544751867324, 6553.672316384066, 6584.341565843377, 6584.670766461753, 6548.0, 6578.0, 6635.734570617121, 6681.899771503334, 6746.021826835179, 6140.5701600887505, 6229.657368844757, 6324.1146239527425, 6384.904226436514, 6394.23269207698], "value": 6136.439297482196, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [858.579296144889, 872.8167084912172, 875.7372788163553, 881.7134431309823, 890.2032680750724, 41032.794836025816, 41608.37587436191, 41716.16567668648, 41758.0, 41773.7911310443, 39014.60985390145, 39179.80410097953, 39254.0, 39254.0, 42066.0, 7711.074671039469, 7848.68605255789, 7854.0, 7855.489393189425, 7858.371330293563, 7717.112532058801, 7864.646090925922, 7865.8820117698, 7868.095169055546, 7879.212078792104, 7817.765467035998, 7850.057993040843, 7869.173736757644, 7870.370370370385, 7908.406869484812, 7812.492188007818, 7831.177726338738, 7850.528968261894, 7859.253370929752, 7867.055953285642, 7655.961720191363, 7705.653245603905, 7710.845783084363, 7711.807204819925, 7713.961430192813, 37595.81202093999, 37721.62278377209, 37753.0, 37761.0, 37973.62026379728, 3432.5194472773833, 3458.948115778265, 3462.4979377990303, 3464.116650254194, 3467.809270490122, 31089.53365699516, 31350.37299254025, 31364.37271254585, 31368.0, 31373.686263137304, 5052.570531504826, 5234.581233501311, 5269.710165940872, 5283.471652834724, 5294.152935530296, 188554.0, 188658.0, 189081.0, 190813.0, 191361.04319478452, 42455.0, 43518.12963740707, 50165.0, 50822.49177508178, 51170.74414627903, 51292.23061654078, 51330.74334628303, 51611.0, 51682.48317516852, 51908.48091519112, 13133.212007279593, 13190.076694631476, 13286.0, 13371.866281337258, 13394.732105357838, 10647.361158330525, 10652.36085834837, 10712.678619641423, 10752.462376881282, 10844.72888177786, 13377.531786387624, 13507.662308442172, 13560.0, 13583.932080339535, 13698.52055178064, 5353.330833645794, 5424.705899953572, 5425.267588875451, 5427.864303392446, 5464.3442786865835, 1013.1793247469574, 1013.7566983923953, 1024.7847951930178, 1031.3090229546222, 1033.7312298802365, 22521.0, 22599.54800903973, 22962.540749184922, 23056.7694323058, 23217.3034808956, 1123.185690374472, 1126.2341607706674, 1131.6265632341472, 1135.5117299561102, 1140.7718456308771, 12378.566750163882, 12418.565350212699, 12432.191907525981, 12488.750224995449, 12528.060395470377, 205427.0, 207568.0, 208063.0, 208676.0, 209220.0, 53701.462985370425, 53724.0, 53761.0, 53786.193207101926, 53929.46070539323, 1430.2979454449173, 1463.8536146385488, 1465.296657604356, 1469.80157678712, 1485.3390241342715, 22494.32517024492, 22697.04938358069, 22858.314250572508, 22919.312420627408, 23192.4201894954, 38373.0, 38471.422928655535, 38718.032049199, 38774.0, 38795.2240955185, 189404.0, 189556.0, 189661.0, 190255.0, 190885.0, 32763.672363276062, 33221.33557328888, 34448.0, 34632.480512792834, 34801.47797783086, 41473.585264148176, 45512.77243613761, 46710.0, 47256.054878901545, 47347.0, 34032.48951265783, 34057.65942340545, 34265.31469370649, 34528.30943381068, 34862.82568587141, 33219.50170747392, 33535.496967545994, 33765.83117084399, 33793.49309760405, 34407.483887741204, 38072.42891356577, 38097.61902381052, 38424.23151536898, 38658.0, 38726.80636596799, 33021.33957320792, 33391.33217335591, 33605.83197083999, 34010.489842652874, 34143.82928085344, 24173.63739543873, 24196.274111776675, 24555.877220613784, 24744.0, 25040.49919001646], "value": 17064.13848018528, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.5851669764376, 563.8533981164896, 567.7566130174914, 579.959402841801, 583.6906439587019, 22285.44286392841, 23086.88456557717, 23087.191948281812, 23116.0, 23143.884280578575, 23690.76309236907, 24284.514309713795, 24710.876445617745, 24781.0, 25309.367265818364, 6579.078928949939, 6776.390124888759, 6777.390034896858, 6777.4578033757425, 6778.661066946657, 6595.505337099713, 6635.402813746762, 6659.200895892475, 6665.866682666344, 6708.899366509506, 6362.9045564316575, 6578.079068930354, 6647.368499992517, 6656.70044847983, 6656.733730650768, 6602.66986650668, 6627.0, 6639.668016599151, 6646.302138275484, 6649.301823308531, 5758.596898217126, 5981.820545383645, 6025.276966763972, 6031.155638210654, 6034.064719968412, 27883.86058069717, 27901.302467438272, 28094.438111237865, 28216.576751348744, 28476.0, 2089.916403343864, 2112.41908475169, 2117.2483768262355, 2120.109553987329, 2122.108714339981, 18685.252589896387, 19332.323368682028, 19407.320743774042, 19577.0, 19588.902055489772, 2440.7437219091917, 2442.255112190781, 2509.736477669837, 2512.560301947152, 2514.069794176148, 121793.0, 127398.36300818529, 127491.0, 130474.34762826125, 140353.2982335092, 14449.585265238375, 14736.21133055363, 14779.187144707144, 14866.331015104346, 15265.007774494623, 23171.53656926852, 23185.188518402112, 23236.53526929452, 23311.41721456977, 23342.183023594433, 9631.518424078768, 9779.804403912025, 9781.510924453749, 9841.114299712917, 9906.702798916043, 8844.159804818531, 8901.465912045298, 8928.955355223312, 8935.28517718581, 8974.461532308082, 8941.955290223506, 8968.910310896938, 9018.954905225562, 9115.498647574446, 9256.676016339396, 2714.6470958775626, 2736.38431352944, 2753.132763179592, 2766.101017169428, 2799.7340252675963, 944.2587568758491, 969.8361965641259, 970.1365784451702, 991.1723710701501, 992.3252188511881, 18229.7265541017, 18538.536536586693, 18903.527411814815, 19018.0, 19155.233790648497, 1004.5579944824254, 1012.3520946594185, 1023.9641612543526, 1034.6016783538391, 1036.486938965217, 10262.486875656188, 10297.9485102574, 10578.206634502443, 10666.626668066736, 10709.036186743075, 458283.0, 462088.0, 477180.0, 497002.0, 497491.0, 108121.45939270253, 109495.0, 111451.0, 112035.0, 113774.43112784383, 1056.2870062707796, 1102.239454776202, 1119.9944000279947, 1167.0838391862346, 1186.6558697977662, 19521.0, 19610.50973725668, 19678.803211967697, 19854.10656520489, 20235.089420976386, 53699.194512081565, 53992.73003635114, 54464.0, 54496.0, 57164.0, 69421.65289173521, 69566.0, 70087.64956175187, 70741.0, 71009.0, 13792.793108103588, 13974.720505589628, 14018.0, 14103.576892693236, 14125.0, 21652.0, 21706.131754729635, 21819.127234910426, 21895.45261368415, 22066.117355305607, 17348.913255433643, 17451.912740436215, 17497.737533937256, 17793.110344482982, 17906.5523361917, 15102.0, 15966.521004369888, 15998.360065597246, 16317.510474685778, 16328.918355408146, 17626.29494820244, 17675.204615792063, 17884.731729024334, 17966.101694915462, 17999.460016199537, 13904.860951390761, 13933.860661393257, 13935.233562154179, 13947.30263486842, 13968.092074015156, 17320.220590073226, 17815.19831607607, 17878.731819022465, 17939.641207175522, 17949.37177198844], "value": 11770.109871096038, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1267.125683278538, 1367.111377604557, 1374.628850210444, 1379.2896658221018, 1399.3493025743028, 15062.698746025088, 15682.215889205538, 15710.371585136581, 15720.213989300531, 15727.60680982976, 15174.924125379357, 15244.237788110579, 15265.61835954099, 15279.770803437958, 15287.082775033507, 2740.9177724668293, 2769.8892044318254, 2803.1450407625666, 2806.0739955814533, 2817.718228177176, 2734.576140698185, 2766.14249582629, 2784.1508339956304, 2811.7188281171925, 2813.395120049189, 2732.3715545424566, 2841.5169421198448, 2842.2325971987652, 2842.7725781937397, 2843.616111824898, 2764.8202866813594, 2781.902633407831, 2809.7330753578376, 2813.3529288263812, 2827.759640430566, 2781.8191817531797, 2787.414642924988, 2803.0, 2803.2991752061985, 2806.1441260415563, 14931.552053438414, 15147.394104235816, 15158.31787569551, 15158.545243642708, 15221.0, 7680.807979800494, 7917.722879699183, 7965.0, 7971.402144839102, 7974.920250797476, 5197.792088316501, 5370.758315875756, 5468.179773034037, 5469.644473109275, 5487.122060470306, 1953.433504283756, 1956.5988972260652, 1974.6445639784833, 2008.1967213114724, 2040.2858999350258, 26511.0, 26743.0, 26887.596686049725, 26892.05877794268, 27640.723592764218, 6507.967460162668, 6534.57525260866, 6582.703778329987, 6680.766173183915, 6784.76253331131, 16465.176741163134, 16541.751873721903, 16595.50213493597, 16608.750868736977, 16610.418635347705, 7704.9614751927, 7902.881456778153, 7934.920650793418, 7937.087234967938, 7968.721094761656, 7228.783136505913, 7335.633218339061, 7354.632268386559, 7360.263973602704, 7373.520721153108, 5185.377754669388, 5243.554297884646, 5273.525382715574, 5463.672179669233, 5500.972495137498, 1515.7347464193722, 1515.909045457276, 1517.499225255663, 1522.695460907823, 1535.669830986321, 900.7207765592688, 929.344811907617, 939.1406862720671, 939.7274790310734, 951.2627713521938, 7870.409719271078, 7937.285644292041, 7941.563214023284, 7956.283934445928, 8048.959755201186, 701.4703898556659, 728.4682182007165, 731.5720303622334, 731.6963460164079, 734.474850481908, 4616.146012987608, 4721.362616046789, 4794.976025119852, 4875.146849301358, 5191.117510023305, 20730.792692073188, 21144.0, 21196.364109076752, 21962.780372196394, 22075.779242207373, 20688.172473101207, 21649.45876353072, 21889.562208756055, 21927.67108493374, 22166.0, 10278.22913281507, 10392.324498907547, 10582.947085264525, 10603.628872989408, 10621.575136994588, 8394.680480151368, 8645.438046526955, 8648.221660050625, 8674.045854955824, 8776.605052772513, 18325.175367108244, 18709.71935420997, 18721.0, 18876.24495020184, 18956.0, 19318.4204473866, 19344.709829352854, 19469.610607788047, 19491.317803876795, 19588.0, 10973.50619222121, 11084.113270938467, 11086.61196858106, 11109.111271098454, 11170.441477926233, 13729.656758581115, 14013.369398377301, 14078.155310681392, 14087.0, 14112.29438528049, 11089.944550277198, 11195.72010699739, 11274.830877536679, 11422.771544568895, 11728.41357932083, 11085.889141108808, 11236.325820450798, 11328.263662861887, 11388.544458221579, 11436.542538298707, 9746.415215087118, 9850.162736167505, 9962.0, 10000.399976001463, 10011.099001089937, 7328.120625524971, 7334.3032411920785, 7362.484626076123, 7378.1515125761225, 7442.106947166375, 6514.185726784152, 6597.637129957899, 6667.299933506912, 6698.095757072733, 6717.899231511629], "value": 6631.204838185255, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.32127270729956, 523.6779380680878, 525.2856115682672, 527.282895262443, 529.7827890564866, 6333.873322533546, 6349.7460101596, 6360.300366959631, 6387.872242555146, 6414.454771344442, 6269.714708484761, 6283.151774510439, 6304.369563043693, 6306.527010474221, 6355.841103972403, 5240.9213861792105, 5248.422673505911, 5249.238860365244, 5262.0791361511865, 5270.710110943896, 4891.266310053503, 5050.419201791786, 5053.938672878699, 5215.921761173567, 5251.921221181686, 4991.226359914201, 4998.075356059123, 4999.500049995007, 5004.449510553854, 5023.271625614284, 4988.251762235657, 5013.799448022075, 5019.42276638184, 5024.598032157437, 5032.748362581856, 4381.583749543788, 4481.193385190664, 4503.572160644733, 4508.120916421299, 4514.887127821798, 6134.539909506805, 6161.661108639068, 6172.814815555541, 6175.536834737412, 6206.689665516707, 5647.458643610392, 5682.971585142048, 5757.884842303172, 5759.827205183851, 5763.567732420085, 6538.607683539003, 6538.771143009972, 6557.803265902031, 6600.976848588429, 6613.437857782094, 4805.327254184417, 4854.975725121351, 4876.000419913909, 4899.681520701179, 4914.754262286871, 6469.320721324288, 6666.566673166229, 6750.0, 6858.485613579001, 6864.553804002724, 4940.258961155783, 5000.599952003831, 5008.223725322581, 5020.297158397827, 5033.7734801934, 5015.570562389701, 5032.899342013213, 5042.621803364689, 5052.646314758005, 5055.418626857959, 5659.6321239119325, 5667.574931880125, 5688.260526131577, 5704.543636509069, 5729.109393900087, 6600.207975043026, 6603.0, 6604.702788374535, 6636.303188165173, 6666.200055993312, 1739.4346837277935, 1740.4778566430273, 1761.9030953297436, 1772.450540332501, 1776.546980519959, 1137.8577677790277, 1196.2284326609288, 1206.6561030106377, 1209.2502648358075, 1224.1393082514705, 5622.100463925834, 5657.830265092054, 5689.601727879009, 5703.287089113861, 5729.0, 6625.238097618739, 6700.363465470772, 6714.194296684429, 6756.290589488131, 6758.256591774901, 5625.353084395265, 5695.775408287238, 5750.159948816422, 5769.221155144001, 5792.768289268466, 3487.494313324554, 3553.3248682750195, 3556.324298383299, 3571.964280357215, 3586.1751797086818, 6416.486681065504, 6433.163688720437, 6452.741890324334, 6465.64438955862, 6472.255690595548, 6586.0, 6816.977453381934, 6819.624920629314, 6880.2431732509385, 6930.826729331807, 6411.647359395185, 6418.935810641834, 6420.775272865428, 6460.095586617877, 6492.448141907897, 6101.29835068955, 6633.402993730588, 6639.933600664125, 6642.667866606747, 6654.201495820533, 6573.1783527059115, 6585.80242592723, 6592.7033283503315, 6641.734330626721, 6643.56816806906, 6413.903791443226, 6445.2265728112925, 6450.612963222222, 6476.190476190476, 6479.967600161969, 4941.382327209099, 4969.83812041763, 4979.153543897473, 5024.472430394756, 5029.74851257443, 6485.254195767548, 6550.803475895731, 6726.562773419712, 6749.898751518633, 6770.796876093725, 6366.458850997532, 6424.807255782334, 6502.154719886385, 6513.111197752788, 6533.18335208099, 6346.238451385863, 6457.675236914613, 6458.25627080695, 6481.481481481376, 6501.2446639408045, 6354.714037868214, 6400.775972840928, 6452.612688272044, 6492.74029038852, 6518.120053792867, 6561.540692151503, 6606.471482281502, 6662.200535935719, 6674.499412544175, 6685.933140668531, 6166.383361663798, 6194.349593292639, 6306.621602703853, 6318.4313411793155, 6374.681265936591], "value": 5056.171749228578, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [892.5537231384308, 912.3431129586697, 921.0881227584694, 936.3445588088335, 944.5607792376545, 15660.608484787886, 16408.34366625335, 16474.093924834146, 16499.257533410997, 16517.256723447445, 14956.700865982673, 15763.605909852231, 15949.760753588676, 15964.84035159648, 15965.0, 918.2883265469264, 933.3466573398614, 935.1910597333292, 935.7894473743421, 935.957881895313, 956.8086382723483, 961.4087336288202, 961.8990006049369, 962.0427674463883, 962.1148543340144, 973.3964941736134, 1000.4047591682973, 1004.573056451009, 1013.9746506337327, 1020.505055048301, 985.8866230383526, 994.607130183577, 1000.0249756487403, 1000.6597756762662, 1001.7044971733357, 729.784713509516, 730.0837449001522, 735.1288722863424, 737.306931484403, 737.7086051009821, 16195.757063644054, 16202.75695864563, 16381.83618163815, 16391.42630007944, 16414.26135823891, 20966.475838104016, 21951.890240548695, 21992.0, 22078.88960555203, 22090.226842060612, 17252.13739313029, 17896.28414863404, 18026.0, 18028.098595070194, 18051.458456246335, 3688.907777305562, 3720.981395093007, 3738.140227747633, 3746.194568167857, 3750.2124553843723, 127938.3603081988, 129522.3523882384, 130132.0, 130140.0, 132239.33880330634, 16209.918950405408, 16752.329906803592, 16835.915820421065, 16842.15789210559, 16884.493465196065, 30518.0, 31500.36999260002, 31683.84158079195, 31706.365872682418, 31766.523502147025, 7987.281144697005, 8122.634481448349, 8196.34429245659, 8215.917840821634, 8217.0, 4037.6971727120585, 4137.2552940470405, 4140.648044916155, 4144.44050053245, 4145.937810932838, 5297.417284098746, 5369.812056578001, 5380.166074258496, 5410.269613602192, 5415.160650099241, 948.1656142594472, 971.4948226922103, 990.5344488090586, 992.846108853129, 995.7012896131132, 639.3861892583172, 652.3411354531862, 664.8171752768028, 667.9198496180393, 673.2056173715064, 11425.31448113116, 11735.4132293385, 11810.173287869939, 11826.113041521749, 11934.28394296345, 625.8560531077786, 628.5474458389951, 630.8170630517099, 631.0691729698658, 639.0605809460086, 5742.655440673573, 5748.310202775694, 5751.769929202785, 5832.795852145155, 5893.0, 115726.0, 116378.0, 117943.41028294976, 119585.0, 121750.39124804319, 106379.468102659, 107807.0, 110031.44984275027, 110496.35633180801, 110914.44542777234, 88739.0, 89721.0, 91846.08153918509, 94455.52772236094, 96551.51724241333, 10530.526126324487, 10691.358518488605, 10731.356118632906, 10774.922507749163, 10920.563177472812, 42495.0, 42654.0, 43124.35313470363, 43923.56076439195, 44041.77979110084, 54181.0, 54279.45720542744, 54719.45280547144, 54726.452735472136, 56087.71956140193, 13297.601071967856, 13459.0, 13500.0, 13514.39185236647, 13529.661758456117, 15755.212239388215, 15915.204239788196, 15941.840581594035, 16000.279987400827, 16502.587435314214, 16191.514254572381, 16204.513864584082, 16294.592635184215, 16475.01149931008, 16476.0, 15234.695306094038, 15518.612034699223, 15576.221188940734, 15664.765028524353, 15673.451429200379, 14360.497382591559, 14538.200398978157, 14597.12417254968, 14698.41206351734, 14718.26408679583, 11678.941605291919, 11847.407629618312, 11947.581834635746, 12308.50765969387, 12369.505219791108, 11776.0, 12019.75960480803, 12215.69460763488, 12424.813627795409, 12562.246265224116], "value": 8605.85140562744, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1424.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 107.0, 106.0, 106.0, 106.0, 108.0, 109.0, 106.0, 108.0, 106.0], "value": 106.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 119.0, 115.0, 119.0, 115.0, 118.0, 140.0, 115.0, 118.0, 116.0], "value": 119.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 164.0, 164.0, 164.0, 162.0, 170.0, 172.0, 163.0, 165.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 77.0, 73.0, 76.0, 74.0, 75.0, 74.0, 74.0], "value": 74.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 79.0, 79.0, 80.0, 78.0, 79.0, 80.0, 80.0], "value": 79.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 110.0, 115.0, 112.0, 114.0, 114.0, 114.0, 114.0, 116.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 148.0, 156.0, 150.0, 148.0, 148.0, 149.0, 147.0, 148.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 93.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 57.0, 55.0, 54.0, 55.0, 55.0, 53.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 40.0, 39.0, 40.0, 38.0, 41.0, 39.0, 38.0, 39.0, 39.0], "value": 39.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 80.0, 81.0, 78.0, 74.0, 74.0, 74.0, 78.0, 74.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 110.0, 121.0, 113.0, 113.0, 109.0, 113.0, 113.0, 116.0, 121.0], "value": 114.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 171.0, 169.0, 170.0, 171.0, 166.0, 166.0, 173.0, 169.0, 167.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 65.0, 66.0, 67.0, 76.0, 63.0, 65.0, 65.0, 71.0, 64.0], "value": 67.2, "unit": "score"}]}]} 03:49:53 INFO - Return code: 0 03:49:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:49:53 INFO - # TBPL SUCCESS # 03:49:53 INFO - Running post-action listener: _package_coverage_data 03:49:53 INFO - Running post-action listener: _resource_record_post_action 03:49:53 INFO - [mozharness: 2017-09-12 10:49:53.316060Z] Finished run-tests step (success) 03:49:53 INFO - Running post-run listener: _resource_record_post_run 03:49:53 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 151552; Write bytes: 302841856; Read time: 72; Write time: 288928 03:49:53 INFO - TinderboxPrint: CPU usage
13.1% 03:49:53 INFO - TinderboxPrint: I/O read bytes / time
151,552 / 72 03:49:53 INFO - TinderboxPrint: I/O write bytes / time
302,841,856 / 288,928 03:49:53 INFO - TinderboxPrint: CPU idle
8,229.0 (86.9%) 03:49:53 INFO - TinderboxPrint: CPU user
1,192.0 (12.6%) 03:49:53 INFO - TinderboxPrint: Swap in / out
0 / 0 03:49:53 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 18300928; Read time: 0; Write time: 10956 03:49:53 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:49:53 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 151552; Write bytes: 284540928; Read time: 72; Write time: 277972 03:49:53 INFO - Running post-run listener: _upload_blobber_files 03:49:53 INFO - Blob upload gear active. 03:49:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:49:53 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 03:49:53 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:49:53 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:49:54 INFO - (blobuploader) - INFO - Open directory for files ... 03:49:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:49:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:49:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:55 INFO - (blobuploader) - INFO - Done attempting. 03:49:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:49:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:49:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:56 INFO - (blobuploader) - INFO - Done attempting. 03:49:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:49:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:49:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:49:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:49:57 INFO - (blobuploader) - INFO - Done attempting. 03:49:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:49:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:49:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:50:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:50:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:50:04 INFO - (blobuploader) - INFO - Done attempting. 03:50:04 INFO - (blobuploader) - INFO - Iteration through files over. 03:50:04 INFO - Return code: 0 03:50:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:50:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:50:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c907483c9cb501f00015b951cb8fc807f95a9f00b310c3c14966a514bbe2a2876eb63221195b23f6b08cb6d52ce32f4fe1927c7891e6a6dba909bb95a28daa2c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/767a29fef0af8e0df007bd46d49d3d79fe94473f36c627f2df8d3ee822e57f3ddeadcbff5fe358622ec1ca2801b61b28f7a3ac095b2cf44174ec173fb63a32e6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/356500c4a2939017fd16ed0dfdefbd49b3ce608359b1e91ca1e9e6974b425a755a120b0f59f2aca40cb9b8289532557a9b562f88881b47394904fa18778ec53e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9998502e9193f9f40d9a9c8571fdb6cac7b16e4c874b9e38ac90789d3f01750d7839c06f9ebc3933e131932056d01241160ade60a4b4c62bda24688351970aaa"} 03:50:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:50:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:50:04 INFO - Contents: 03:50:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c907483c9cb501f00015b951cb8fc807f95a9f00b310c3c14966a514bbe2a2876eb63221195b23f6b08cb6d52ce32f4fe1927c7891e6a6dba909bb95a28daa2c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/767a29fef0af8e0df007bd46d49d3d79fe94473f36c627f2df8d3ee822e57f3ddeadcbff5fe358622ec1ca2801b61b28f7a3ac095b2cf44174ec173fb63a32e6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/356500c4a2939017fd16ed0dfdefbd49b3ce608359b1e91ca1e9e6974b425a755a120b0f59f2aca40cb9b8289532557a9b562f88881b47394904fa18778ec53e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9998502e9193f9f40d9a9c8571fdb6cac7b16e4c874b9e38ac90789d3f01750d7839c06f9ebc3933e131932056d01241160ade60a4b4c62bda24688351970aaa"} 03:50:04 INFO - Running post-run listener: copy_logs_to_upload_dir 03:50:04 INFO - Copying logs to upload dir... 03:50:04 INFO - mkdir: /builds/slave/test/build/upload/logs 03:50:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.255377 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-12 03:50:04.854720) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-12 03:50:04.858967) ========= 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=c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c907483c9cb501f00015b951cb8fc807f95a9f00b310c3c14966a514bbe2a2876eb63221195b23f6b08cb6d52ce32f4fe1927c7891e6a6dba909bb95a28daa2c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/767a29fef0af8e0df007bd46d49d3d79fe94473f36c627f2df8d3ee822e57f3ddeadcbff5fe358622ec1ca2801b61b28f7a3ac095b2cf44174ec173fb63a32e6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/356500c4a2939017fd16ed0dfdefbd49b3ce608359b1e91ca1e9e6974b425a755a120b0f59f2aca40cb9b8289532557a9b562f88881b47394904fa18778ec53e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9998502e9193f9f40d9a9c8571fdb6cac7b16e4c874b9e38ac90789d3f01750d7839c06f9ebc3933e131932056d01241160ade60a4b4c62bda24688351970aaa"} build_url:https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/Z7UnFtVmTZqJN21MF8D_IQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c907483c9cb501f00015b951cb8fc807f95a9f00b310c3c14966a514bbe2a2876eb63221195b23f6b08cb6d52ce32f4fe1927c7891e6a6dba909bb95a28daa2c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/767a29fef0af8e0df007bd46d49d3d79fe94473f36c627f2df8d3ee822e57f3ddeadcbff5fe358622ec1ca2801b61b28f7a3ac095b2cf44174ec173fb63a32e6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/356500c4a2939017fd16ed0dfdefbd49b3ce608359b1e91ca1e9e6974b425a755a120b0f59f2aca40cb9b8289532557a9b562f88881b47394904fa18778ec53e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9998502e9193f9f40d9a9c8571fdb6cac7b16e4c874b9e38ac90789d3f01750d7839c06f9ebc3933e131932056d01241160ade60a4b4c62bda24688351970aaa"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-12 03:50:04.890086) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 03:50:04.890468) ========= 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=c6927cf182745ad25b1d38b9000001fe-1505212117.657002-634702829 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004579 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 03:50:04.972475) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-12 03:50:04.972796) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-12 03:50:04.973085) ========= ========= Total master_lag: 0.24 =========