builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-040 starttime: 1506295055.63 results: success (0) revision: b44e80e0590e6eed86a1902365424673c8c2eaec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-24 16:17:35.629878) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-24 16:17:35.630332) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-24 16:17:35.630627) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-24 16:17:35.648073) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:17:35.648354) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-24 16:17:35-- 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% 46.4M=0s 2017-09-24 16:17:35 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082010 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:17:35.747306) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:17:35.750659) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015995 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:17:35.800376) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-24 16:17:35.803725) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b44e80e0590e6eed86a1902365424673c8c2eaec --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 b44e80e0590e6eed86a1902365424673c8c2eaec --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=9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-24 16:17:35,723 truncating revision to first 12 chars 2017-09-24 16:17:35,723 Setting DEBUG logging. 2017-09-24 16:17:35,723 attempt 1/10 2017-09-24 16:17:35,723 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b44e80e0590e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-24 16:17:36,933 unpacking tar archive at: mozilla-central-b44e80e0590e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.532921 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-24 16:17:37.355601) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-24 16:17:37.355922) ========= script_repo_revision: b44e80e0590e6eed86a1902365424673c8c2eaec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-24 16:17:37.356284) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-24 16:17:37.356542) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-24 16:17:37.369978) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-24 16:17:37.370260) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:17:37 INFO - MultiFileLogger online at 20170924 16:17:37 in /builds/slave/test 16:17:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:17:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:17:37 INFO - {'append_to_log': False, 16:17:37 INFO - 'base_work_dir': '/builds/slave/test', 16:17:37 INFO - 'blob_upload_branch': 'Firefox', 16:17:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:17:37 INFO - 'branch': 'Firefox', 16:17:37 INFO - 'buildbot_json_path': 'buildprops.json', 16:17:37 INFO - 'code_coverage': False, 16:17:37 INFO - 'config_files': ('talos/linux_config.py',), 16:17:37 INFO - 'default_actions': ('clobber', 16:17:37 INFO - 'read-buildbot-config', 16:17:37 INFO - 'download-and-extract', 16:17:37 INFO - 'populate-webroot', 16:17:37 INFO - 'create-virtualenv', 16:17:37 INFO - 'install', 16:17:37 INFO - 'setup-mitmproxy', 16:17:37 INFO - 'run-tests'), 16:17:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:17:37 INFO - 'disable_ccov_upload': False, 16:17:37 INFO - 'disable_stylo': False, 16:17:37 INFO - 'download_minidump_stackwalk': True, 16:17:37 INFO - 'download_symbols': 'ondemand', 16:17:37 INFO - 'enable_stylo': False, 16:17:37 INFO - 'enable_webrender': False, 16:17:37 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:17:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:17:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:17:37 INFO - 'gecko_profile': False, 16:17:37 INFO - 'gecko_profile_interval': 0, 16:17:37 INFO - 'installer_path': 'installer.exe', 16:17:37 INFO - 'log_level': 'info', 16:17:37 INFO - 'log_name': 'talos', 16:17:37 INFO - 'log_to_console': True, 16:17:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:17:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:17:37 INFO - 'opt_config_files': (), 16:17:37 INFO - 'pip_index': False, 16:17:37 INFO - 'suite': 'dromaeojs-e10s', 16:17:37 INFO - 'system_bits': '32', 16:17:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:17:37 INFO - 'title': 'talos-linux64-ix-040', 16:17:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:17:37 INFO - 'use_talos_json': True, 16:17:37 INFO - 'verify': 'False', 16:17:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:17:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:17:37 INFO - 'work_dir': 'build'} 16:17:37 INFO - [mozharness: 2017-09-24 23:17:37.435120Z] Running clobber step. 16:17:37 INFO - Running pre-action listener: _resource_record_pre_action 16:17:37 INFO - Running main action method: clobber 16:17:37 INFO - rmtree: /builds/slave/test/build 16:17:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:17:38 INFO - Running post-action listener: _resource_record_post_action 16:17:38 INFO - [mozharness: 2017-09-24 23:17:38.953637Z] Finished clobber step (success) 16:17:38 INFO - [mozharness: 2017-09-24 23:17:38.953736Z] Running read-buildbot-config step. 16:17:38 INFO - Running pre-action listener: _resource_record_pre_action 16:17:38 INFO - Running main action method: read_buildbot_config 16:17:38 INFO - Using buildbot properties: 16:17:38 INFO - { 16:17:38 INFO - "project": "", 16:17:38 INFO - "product": "firefox", 16:17:38 INFO - "who": "archaeopteryx@coole-files.de", 16:17:38 INFO - "installer_path": "public/build/target.tar.bz2", 16:17:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:17:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:17:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 16:17:38 INFO - "stage_platform": "linux64-pgo", 16:17:38 INFO - "basedir": "/builds/slave/test", 16:17:38 INFO - "buildnumber": 206, 16:17:38 INFO - "platform": "ubuntu64_hw", 16:17:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:17:38 INFO - "slavebuilddir": "test", 16:17:38 INFO - "taskId": "KyMWZHfkQuesn_CG1UmuFA", 16:17:38 INFO - "branch": "mozilla-central", 16:17:38 INFO - "script_repo_revision": "production", 16:17:38 INFO - "revision": "b44e80e0590e6eed86a1902365424673c8c2eaec", 16:17:38 INFO - "slavename": "talos-linux64-ix-040", 16:17:38 INFO - "repo_path": "mozilla-central" 16:17:38 INFO - } 16:17:38 INFO - Finding installer, test and symbols from parent task. 16:17:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KyMWZHfkQuesn_CG1UmuFA'}, attempt #1 16:17:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KyMWZHfkQuesn_CG1UmuFA'}, attempt #1 16:17:39 INFO - Task dependencies: ep1GVcu_QMWkzKOeJ-WQbg 16:17:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg'}, attempt #1 16:17:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.tar.bz2 16:17:40 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.test_packages.json 16:17:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.crashreporter-symbols.zip 16:17:40 INFO - Running post-action listener: _resource_record_post_action 16:17:40 INFO - [mozharness: 2017-09-24 23:17:40.292524Z] Finished read-buildbot-config step (success) 16:17:40 INFO - [mozharness: 2017-09-24 23:17:40.292740Z] Running download-and-extract step. 16:17:40 INFO - Running pre-action listener: _resource_record_pre_action 16:17:40 INFO - Running main action method: download_and_extract 16:17:40 INFO - mkdir: /builds/slave/test/build/tests 16:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:40 INFO - trying https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.test_packages.json 16:17:40 INFO - Downloading https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:17:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:17:40 INFO - Downloaded 989 bytes. 16:17:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:17:40 INFO - Using the following test package requirements: 16:17:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:17:40 INFO - u'common': [u'target.common.tests.zip'], 16:17:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:17:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:17:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:17:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:17:40 INFO - u'mozbase': [u'target.common.tests.zip'], 16:17:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:17:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:17:40 INFO - u'web-platform': [u'target.common.tests.zip', 16:17:40 INFO - u'target.web-platform.tests.tar.gz'], 16:17:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:17:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:17:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.common.tests.zip 16:17:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:17:40 INFO - Fetch https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.common.tests.zip into memory 16:17:43 INFO - Content-Length response header: 55344618 16:17:43 INFO - Bytes received: 55344618 16:17:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.talos.tests.zip 16:17:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:17:54 INFO - Fetch https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.talos.tests.zip into memory 16:17:56 INFO - Content-Length response header: 14513823 16:17:56 INFO - Bytes received: 14513823 16:17: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')]} 16:17:56 INFO - trying https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.tar.bz2 16:17:56 INFO - Downloading https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:17:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:17:59 INFO - Downloaded 62363455 bytes. 16:17:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.tar.bz2 16:17:59 INFO - mkdir: /builds/slave/test/properties 16:17:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:17:59 INFO - Writing to file /builds/slave/test/properties/build_url 16:17:59 INFO - Contents: 16:17:59 INFO - build_url:https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.tar.bz2 16:17:59 INFO - Running post-action listener: _resource_record_post_action 16:17:59 INFO - Running post-action listener: find_tests_for_verification 16:17:59 INFO - Running post-action listener: set_extra_try_arguments 16:17:59 INFO - [mozharness: 2017-09-24 23:17:59.381499Z] Finished download-and-extract step (success) 16:17:59 INFO - [mozharness: 2017-09-24 23:17:59.381600Z] Running populate-webroot step. 16:17:59 INFO - Running pre-action listener: _resource_record_pre_action 16:17:59 INFO - Running main action method: populate_webroot 16:17:59 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:17:59 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:17:59 INFO - 'tests': ['tresize', 16:17:59 INFO - 'tcanvasmark']}, 16:17:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:17:59 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:17:59 INFO - 'tests': ['dromaeo_css', 16:17:59 INFO - 'kraken']}, 16:17:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:17:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:59 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:17:59 INFO - 'talos_options': ['--disable-stylo'], 16:17:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:17:59 INFO - 'tests': ['damp', 'tps']}, 16:17:59 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:17:59 INFO - 'talos_options': ['--disable-stylo'], 16:17:59 INFO - 'tests': ['damp', 'tps']}, 16:17:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:17:59 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:17:59 INFO - 'tests': ['dromaeo_dom']}, 16:17:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:17:59 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:17:59 INFO - 'tests': ['basic_compositor_video', 16:17:59 INFO - 'glvideo']}, 16:17:59 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:17:59 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:17:59 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:17:59 INFO - 'talos_options': ['--disable-stylo'], 16:17:59 INFO - 'tests': ['ts_paint_webext', 16:17:59 INFO - 'tp5o_webext']}, 16:17:59 INFO - 'other-e10s': {'tests': ['a11yr', 16:17:59 INFO - 'ts_paint', 16:17:59 INFO - 'tpaint', 16:17:59 INFO - 'sessionrestore', 16:17:59 INFO - 'sessionrestore_many_windows', 16:17:59 INFO - 'sessionrestore_no_auto_restore', 16:17:59 INFO - 'tabpaint']}, 16:17:59 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:17:59 INFO - 'tests': ['a11yr', 16:17:59 INFO - 'ts_paint', 16:17:59 INFO - 'tpaint', 16:17:59 INFO - 'sessionrestore', 16:17:59 INFO - 'sessionrestore_many_windows', 16:17:59 INFO - 'sessionrestore_no_auto_restore', 16:17:59 INFO - 'tabpaint']}, 16:17:59 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 16:17:59 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 16:17:59 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:17:59 INFO - 'tests': ['perf_reftest_singletons']}, 16:17:59 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:17:59 INFO - 'tests': ['perf_reftest']}, 16:17:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:17:59 INFO - 'tsvgr_opacity', 16:17:59 INFO - 'tart', 16:17:59 INFO - 'tscrollx', 16:17:59 INFO - 'tsvg_static']}, 16:17:59 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:17:59 INFO - 'tests': ['tsvgx', 16:17:59 INFO - 'tsvgr_opacity', 16:17:59 INFO - 'tart', 16:17:59 INFO - 'tscrollx', 16:17:59 INFO - 'tsvg_static']}, 16:17:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:17:59 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:17:59 INFO - 'talos_options': ['--disable-stylo'], 16:17:59 INFO - 'tests': ['tp5o']}, 16:17:59 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:17:59 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:17:59 INFO - 'talos_options': ['--mitmproxy', 16:17:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:17:59 INFO - '--firstNonBlankPaint'], 16:17:59 INFO - 'tests': ['tp6_google', 16:17:59 INFO - 'tp6_youtube', 16:17:59 INFO - 'tp6_amazon', 16:17:59 INFO - 'tp6_facebook']}, 16:17:59 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:17:59 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:17:59 INFO - 'talos_options': ['--disable-stylo', 16:17:59 INFO - '--mitmproxy', 16:17:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:17:59 INFO - '--firstNonBlankPaint'], 16:17:59 INFO - 'tests': ['tp6_google', 16:17:59 INFO - 'tp6_youtube', 16:17:59 INFO - 'tp6_amazon', 16:17:59 INFO - 'tp6_facebook']}, 16:17:59 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:17:59 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:17:59 INFO - 'talos_options': ['--stylo-threads=1', 16:17:59 INFO - '--mitmproxy', 16:17:59 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:17:59 INFO - '--firstNonBlankPaint'], 16:17:59 INFO - 'tests': ['tp6_google', 16:17:59 INFO - 'tp6_youtube', 16:17:59 INFO - 'tp6_amazon', 16:17:59 INFO - 'tp6_facebook']}, 16:17:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:17:59 INFO - 'talos_options': ['--xperf_path', 16:17:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:59 INFO - 'tests': ['tp5n']}, 16:17:59 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:17:59 INFO - 'talos_options': ['--disable-stylo', 16:17:59 INFO - '--xperf_path', 16:17:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:59 INFO - 'tests': ['tp5n']}}} 16:17:59 INFO - Running post-action listener: _resource_record_post_action 16:17:59 INFO - [mozharness: 2017-09-24 23:17:59.400373Z] Finished populate-webroot step (success) 16:17:59 INFO - [mozharness: 2017-09-24 23:17:59.400462Z] Running create-virtualenv step. 16:17:59 INFO - Running pre-action listener: _resource_record_pre_action 16:17:59 INFO - Running main action method: create_virtualenv 16:17:59 INFO - Creating virtualenv /builds/slave/test/build/venv 16:17:59 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:17:59 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:17:59 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:17:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:17:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:18:00 INFO - Installing distribute.............................................................................................................................................................................................done. 16:18:00 INFO - Installing pip...............done. 16:18:00 INFO - Return code: 0 16:18:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:18:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:18:00 INFO - Reading from file tmpfile_stdout 16:18:00 INFO - Output received: 16:18:00 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:18:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:18:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21059c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2102d60>, '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 0x2103a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2103ef0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', '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 16:18:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:18:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:18:00 INFO - Using env: {'DISPLAY': ':0', 16:18:00 INFO - 'HOME': '/home/cltbld', 16:18:00 INFO - 'LANG': 'en_US.UTF-8', 16:18:00 INFO - 'LANGUAGE': 'en_US:en', 16:18:00 INFO - 'LOGNAME': 'cltbld', 16:18:00 INFO - 'MAIL': '/var/mail/cltbld', 16:18:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:00 INFO - 'MOZ_NO_REMOTE': '1', 16:18:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:00 INFO - 'NO_EM_RESTART': '1', 16:18:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:00 INFO - 'PWD': '/builds/slave/test', 16:18:00 INFO - 'SHELL': '/bin/bash', 16:18:00 INFO - 'SHLVL': '1', 16:18:00 INFO - 'TERM': 'linux', 16:18:00 INFO - 'TMOUT': '86400', 16:18:00 INFO - 'USER': 'cltbld', 16:18:00 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', 16:18:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:00 INFO - '_': '/tools/buildbot/bin/python'} 16:18:01 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:18:01 INFO - Downloading/unpacking pip>=1.5 16:18:01 INFO - Running setup.py egg_info for package pip 16:18:01 INFO - warning: no files found matching 'pip/cacert.pem' 16:18:01 INFO - warning: no files found matching '*.html' under directory 'docs' 16:18:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:18:01 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:18:01 INFO - Installing collected packages: pip 16:18:01 INFO - Found existing installation: pip 1.1 16:18:01 INFO - Uninstalling pip: 16:18:01 INFO - Successfully uninstalled pip 16:18:01 INFO - Running setup.py install for pip 16:18:01 INFO - warning: no files found matching 'pip/cacert.pem' 16:18:01 INFO - warning: no files found matching '*.html' under directory 'docs' 16:18:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:18:01 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:18:01 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Successfully installed pip 16:18:01 INFO - Cleaning up... 16:18:01 INFO - Return code: 0 16:18:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:18:01 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')]} 16:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:01 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')]} 16:18:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:01 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 0x1d981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21059c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2102d60>, '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 0x2103a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2103ef0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', '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 16:18:01 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 16:18:01 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 16:18:01 INFO - Using env: {'DISPLAY': ':0', 16:18:01 INFO - 'HOME': '/home/cltbld', 16:18:01 INFO - 'LANG': 'en_US.UTF-8', 16:18:01 INFO - 'LANGUAGE': 'en_US:en', 16:18:01 INFO - 'LOGNAME': 'cltbld', 16:18:01 INFO - 'MAIL': '/var/mail/cltbld', 16:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:01 INFO - 'MOZ_NO_REMOTE': '1', 16:18:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:01 INFO - 'NO_EM_RESTART': '1', 16:18:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:01 INFO - 'PWD': '/builds/slave/test', 16:18:01 INFO - 'SHELL': '/bin/bash', 16:18:01 INFO - 'SHLVL': '1', 16:18:01 INFO - 'TERM': 'linux', 16:18:01 INFO - 'TMOUT': '86400', 16:18:01 INFO - 'USER': 'cltbld', 16:18:01 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', 16:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:01 INFO - '_': '/tools/buildbot/bin/python'} 16:18:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:01 INFO - Downloading/unpacking psutil>=3.1.1 16:18:01 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 16:18:01 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 16:18:01 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 16:18:01 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 16:18:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:18:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:03 INFO - Installing collected packages: psutil 16:18:03 INFO - Running setup.py install for psutil 16:18:03 INFO - building 'psutil._psutil_linux' extension 16:18:03 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 16:18:03 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 16:18:03 INFO - building 'psutil._psutil_posix' extension 16:18:03 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 16:18:03 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 16:18:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:03 INFO - Successfully installed psutil 16:18:03 INFO - Cleaning up... 16:18:03 INFO - Return code: 0 16:18:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:03 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 0x1d981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21059c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2102d60>, '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 0x2103a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2103ef0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', '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 16:18:03 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 16:18:03 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 16:18:03 INFO - Using env: {'DISPLAY': ':0', 16:18:03 INFO - 'HOME': '/home/cltbld', 16:18:03 INFO - 'LANG': 'en_US.UTF-8', 16:18:03 INFO - 'LANGUAGE': 'en_US:en', 16:18:03 INFO - 'LOGNAME': 'cltbld', 16:18:03 INFO - 'MAIL': '/var/mail/cltbld', 16:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:03 INFO - 'MOZ_NO_REMOTE': '1', 16:18:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:03 INFO - 'NO_EM_RESTART': '1', 16:18:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:03 INFO - 'PWD': '/builds/slave/test', 16:18:03 INFO - 'SHELL': '/bin/bash', 16:18:03 INFO - 'SHLVL': '1', 16:18:03 INFO - 'TERM': 'linux', 16:18:03 INFO - 'TMOUT': '86400', 16:18:03 INFO - 'USER': 'cltbld', 16:18:03 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', 16:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:03 INFO - '_': '/tools/buildbot/bin/python'} 16:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:18:03 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 16:18:03 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 16:18:03 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 16:18:03 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 16:18:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:18:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:18:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:18:05 INFO - Installing collected packages: mozsystemmonitor 16:18:05 INFO - Running setup.py install for mozsystemmonitor 16:18:05 INFO - Successfully installed mozsystemmonitor 16:18:05 INFO - Cleaning up... 16:18:05 INFO - Return code: 0 16:18:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21059c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2102d60>, '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 0x2103a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2103ef0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', '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 16:18:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:18:05 INFO - Using env: {'DISPLAY': ':0', 16:18:05 INFO - 'HOME': '/home/cltbld', 16:18:05 INFO - 'LANG': 'en_US.UTF-8', 16:18:05 INFO - 'LANGUAGE': 'en_US:en', 16:18:05 INFO - 'LOGNAME': 'cltbld', 16:18:05 INFO - 'MAIL': '/var/mail/cltbld', 16:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:05 INFO - 'MOZ_NO_REMOTE': '1', 16:18:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:05 INFO - 'NO_EM_RESTART': '1', 16:18:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:05 INFO - 'PWD': '/builds/slave/test', 16:18:05 INFO - 'SHELL': '/bin/bash', 16:18:05 INFO - 'SHLVL': '1', 16:18:05 INFO - 'TERM': 'linux', 16:18:05 INFO - 'TMOUT': '86400', 16:18:05 INFO - 'USER': 'cltbld', 16:18:05 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', 16:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:05 INFO - '_': '/tools/buildbot/bin/python'} 16:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:05 INFO - Downloading/unpacking jsonschema==2.5.1 16:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:18:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:07 INFO - Downloading functools32-3.2.3-2.tar.gz 16:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:18:07 INFO - warning: no files found matching '*.txt' 16:18:07 INFO - no previously-included directories found matching 'build' 16:18:07 INFO - no previously-included directories found matching 'dist' 16:18:07 INFO - no previously-included directories found matching '.git*' 16:18:07 INFO - Installing collected packages: jsonschema, functools32 16:18:08 INFO - Running setup.py install for functools32 16:18:08 INFO - warning: no files found matching '*.txt' 16:18:08 INFO - no previously-included directories found matching 'build' 16:18:08 INFO - no previously-included directories found matching 'dist' 16:18:08 INFO - no previously-included directories found matching '.git*' 16:18:08 INFO - Successfully installed jsonschema functools32 16:18:08 INFO - Cleaning up... 16:18:08 INFO - Return code: 0 16:18:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:18:08 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')]} 16:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:08 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')]} 16:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:08 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 0x1d981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21059c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2102d60>, '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 0x2103a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2103ef0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', '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 16:18:08 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 16:18:08 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 16:18:08 INFO - Using env: {'DISPLAY': ':0', 16:18:08 INFO - 'HOME': '/home/cltbld', 16:18:08 INFO - 'LANG': 'en_US.UTF-8', 16:18:08 INFO - 'LANGUAGE': 'en_US:en', 16:18:08 INFO - 'LOGNAME': 'cltbld', 16:18:08 INFO - 'MAIL': '/var/mail/cltbld', 16:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:08 INFO - 'MOZ_NO_REMOTE': '1', 16:18:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:08 INFO - 'NO_EM_RESTART': '1', 16:18:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:08 INFO - 'PWD': '/builds/slave/test', 16:18:08 INFO - 'SHELL': '/bin/bash', 16:18:08 INFO - 'SHLVL': '1', 16:18:08 INFO - 'TERM': 'linux', 16:18:08 INFO - 'TMOUT': '86400', 16:18:08 INFO - 'USER': 'cltbld', 16:18:08 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', 16:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:08 INFO - '_': '/tools/buildbot/bin/python'} 16:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:18:08 INFO - Cleaning up... 16:18:08 INFO - Return code: 0 16:18:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:18:08 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')]} 16:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:08 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')]} 16:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:08 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 0x1d981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21059c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2102d60>, '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 0x2103a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2103ef0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', '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 16:18:08 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 16:18:08 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 16:18:08 INFO - Using env: {'DISPLAY': ':0', 16:18:08 INFO - 'HOME': '/home/cltbld', 16:18:08 INFO - 'LANG': 'en_US.UTF-8', 16:18:08 INFO - 'LANGUAGE': 'en_US:en', 16:18:08 INFO - 'LOGNAME': 'cltbld', 16:18:08 INFO - 'MAIL': '/var/mail/cltbld', 16:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:08 INFO - 'MOZ_NO_REMOTE': '1', 16:18:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:08 INFO - 'NO_EM_RESTART': '1', 16:18:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:08 INFO - 'PWD': '/builds/slave/test', 16:18:08 INFO - 'SHELL': '/bin/bash', 16:18:08 INFO - 'SHLVL': '1', 16:18:08 INFO - 'TERM': 'linux', 16:18:08 INFO - 'TMOUT': '86400', 16:18:08 INFO - 'USER': 'cltbld', 16:18:08 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', 16:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:08 INFO - '_': '/tools/buildbot/bin/python'} 16:18:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:09 INFO - Downloading/unpacking blobuploader==1.2.4 16:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:10 INFO - Downloading blobuploader-1.2.4.tar.gz 16:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:18:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:18:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:10 INFO - Downloading docopt-0.6.1.tar.gz 16:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:18:10 INFO - Installing collected packages: blobuploader, requests, docopt 16:18:10 INFO - Running setup.py install for blobuploader 16:18:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:18:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:18:11 INFO - Running setup.py install for requests 16:18:11 INFO - Running setup.py install for docopt 16:18:11 INFO - Successfully installed blobuploader requests docopt 16:18:11 INFO - Cleaning up... 16:18:11 INFO - Return code: 0 16:18:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:11 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 0x1d981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21059c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2102d60>, '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 0x2103a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2103ef0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', '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 16:18:11 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 16:18:11 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 16:18:11 INFO - Using env: {'DISPLAY': ':0', 16:18:11 INFO - 'HOME': '/home/cltbld', 16:18:11 INFO - 'LANG': 'en_US.UTF-8', 16:18:11 INFO - 'LANGUAGE': 'en_US:en', 16:18:11 INFO - 'LOGNAME': 'cltbld', 16:18:11 INFO - 'MAIL': '/var/mail/cltbld', 16:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:11 INFO - 'MOZ_NO_REMOTE': '1', 16:18:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:11 INFO - 'NO_EM_RESTART': '1', 16:18:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:11 INFO - 'PWD': '/builds/slave/test', 16:18:11 INFO - 'SHELL': '/bin/bash', 16:18:11 INFO - 'SHLVL': '1', 16:18:11 INFO - 'TERM': 'linux', 16:18:11 INFO - 'TMOUT': '86400', 16:18:11 INFO - 'USER': 'cltbld', 16:18:11 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', 16:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:11 INFO - '_': '/tools/buildbot/bin/python'} 16:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:11 INFO - Running setup.py (path:/tmp/pip-FITzQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:11 INFO - Running setup.py (path:/tmp/pip-e1wgfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:11 INFO - Running setup.py (path:/tmp/pip-FnvXEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:11 INFO - Running setup.py (path:/tmp/pip-HkaKUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:12 INFO - Running setup.py (path:/tmp/pip-1cyH93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:12 INFO - Running setup.py (path:/tmp/pip-Wmx6Iv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:12 INFO - Running setup.py (path:/tmp/pip-nUqr6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:12 INFO - Running setup.py (path:/tmp/pip-UoCAyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:12 INFO - Running setup.py (path:/tmp/pip-l0XjY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:12 INFO - Running setup.py (path:/tmp/pip-hMZ5M8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:12 INFO - Running setup.py (path:/tmp/pip-dySECp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:12 INFO - Running setup.py (path:/tmp/pip-HOUZg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:12 INFO - Running setup.py (path:/tmp/pip-UmqjF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:12 INFO - Running setup.py (path:/tmp/pip-hoEUES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:12 INFO - Running setup.py (path:/tmp/pip-QCSYXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:12 INFO - Running setup.py (path:/tmp/pip-63uWau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:12 INFO - Running setup.py (path:/tmp/pip-XXLrlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:18:12 INFO - Running setup.py install for manifestparser 16:18:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:18:13 INFO - Running setup.py install for mozcrash 16:18:13 INFO - Running setup.py install for mozdebug 16:18:13 INFO - Running setup.py install for mozdevice 16:18:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:18:13 INFO - Running setup.py install for mozfile 16:18:13 INFO - Running setup.py install for mozhttpd 16:18:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:18:13 INFO - Running setup.py install for mozinfo 16:18:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:18:13 INFO - Running setup.py install for mozInstall 16:18:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:18:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:18:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:18:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:18:13 INFO - Running setup.py install for mozleak 16:18:13 INFO - Running setup.py install for mozlog 16:18:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:18:13 INFO - Running setup.py install for moznetwork 16:18:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:18:14 INFO - Running setup.py install for mozprocess 16:18:14 INFO - Running setup.py install for mozprofile 16:18:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:18:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:18:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:18:14 INFO - Running setup.py install for mozrunner 16:18:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:18:14 INFO - Running setup.py install for mozscreenshot 16:18:14 INFO - Running setup.py install for moztest 16:18:14 INFO - Running setup.py install for mozversion 16:18:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:18:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:18:14 INFO - Cleaning up... 16:18:14 INFO - Return code: 0 16:18:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:14 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 0x1d981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21059c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2102d60>, '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 0x2103a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2103ef0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', '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 16:18:14 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 16:18:14 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 16:18:14 INFO - Using env: {'DISPLAY': ':0', 16:18:14 INFO - 'HOME': '/home/cltbld', 16:18:14 INFO - 'LANG': 'en_US.UTF-8', 16:18:14 INFO - 'LANGUAGE': 'en_US:en', 16:18:14 INFO - 'LOGNAME': 'cltbld', 16:18:14 INFO - 'MAIL': '/var/mail/cltbld', 16:18:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:14 INFO - 'MOZ_NO_REMOTE': '1', 16:18:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:14 INFO - 'NO_EM_RESTART': '1', 16:18:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:14 INFO - 'PWD': '/builds/slave/test', 16:18:14 INFO - 'SHELL': '/bin/bash', 16:18:14 INFO - 'SHLVL': '1', 16:18:14 INFO - 'TERM': 'linux', 16:18:14 INFO - 'TMOUT': '86400', 16:18:14 INFO - 'USER': 'cltbld', 16:18:14 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', 16:18:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:14 INFO - '_': '/tools/buildbot/bin/python'} 16:18:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:14 INFO - Running setup.py (path:/tmp/pip-GkGdlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:15 INFO - Running setup.py (path:/tmp/pip-M1Bmaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:15 INFO - Running setup.py (path:/tmp/pip-g8kf8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:15 INFO - Running setup.py (path:/tmp/pip-pNq7dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:15 INFO - Running setup.py (path:/tmp/pip-l6JRba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:15 INFO - Running setup.py (path:/tmp/pip-SbqNLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:15 INFO - Running setup.py (path:/tmp/pip-9RbaIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:15 INFO - Running setup.py (path:/tmp/pip-fB0KIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:15 INFO - Running setup.py (path:/tmp/pip-3Ax9l5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:15 INFO - Running setup.py (path:/tmp/pip-5xIKYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:15 INFO - Running setup.py (path:/tmp/pip-houjMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:15 INFO - Running setup.py (path:/tmp/pip-alvhyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:15 INFO - Running setup.py (path:/tmp/pip-uM6chj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:15 INFO - Running setup.py (path:/tmp/pip-5gQ7gX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:15 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)) 16:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:15 INFO - Running setup.py (path:/tmp/pip-6abKxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:16 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)) 16:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:16 INFO - Running setup.py (path:/tmp/pip-qNLB0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:16 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)) 16:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:16 INFO - Running setup.py (path:/tmp/pip-Zx4m66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:16 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)) 16:18:16 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)) 16:18:16 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)) 16:18:16 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)) 16:18:16 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)) 16:18:16 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)) 16:18:16 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)) 16:18:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:18:16 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 16:18:16 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 16:18:16 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 16:18:16 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 16:18:17 INFO - Downloading blessings-1.6.tar.gz 16:18:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:18:17 INFO - Installing collected packages: blessings 16:18:17 INFO - Running setup.py install for blessings 16:18:17 INFO - Successfully installed blessings 16:18:17 INFO - Cleaning up... 16:18:17 INFO - Return code: 0 16:18:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:18:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:17 INFO - Reading from file tmpfile_stdout 16:18:17 INFO - Current package versions: 16:18:17 INFO - argparse == 1.2.1 16:18:17 INFO - blessings == 1.6 16:18:17 INFO - blobuploader == 1.2.4 16:18:17 INFO - docopt == 0.6.1 16:18:17 INFO - functools32 == 3.2.3-2 16:18:17 INFO - jsonschema == 2.5.1 16:18:17 INFO - manifestparser == 1.1 16:18:17 INFO - mozInstall == 1.13 16:18:17 INFO - mozcrash == 1.0 16:18:17 INFO - mozdebug == 0.1 16:18:17 INFO - mozdevice == 0.51 16:18:17 INFO - mozfile == 1.2 16:18:17 INFO - mozhttpd == 0.7 16:18:17 INFO - mozinfo == 0.10 16:18:17 INFO - mozleak == 0.1 16:18:17 INFO - mozlog == 3.5 16:18:17 INFO - moznetwork == 0.27 16:18:17 INFO - mozprocess == 0.25 16:18:17 INFO - mozprofile == 0.28 16:18:17 INFO - mozrunner == 6.13 16:18:17 INFO - mozscreenshot == 0.1 16:18:17 INFO - mozsystemmonitor == 0.3 16:18:17 INFO - moztest == 0.8 16:18:17 INFO - mozversion == 1.4 16:18:17 INFO - psutil == 3.1.1 16:18:17 INFO - requests == 1.2.3 16:18:17 INFO - wsgiref == 0.1.2 16:18:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:17 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')]} 16:18:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:17 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')]} 16:18:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:17 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 0x1d981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21059c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2102d60>, '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 0x2103a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2103ef0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', '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 16:18:17 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 16:18:17 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 16:18:17 INFO - Using env: {'DISPLAY': ':0', 16:18:17 INFO - 'HOME': '/home/cltbld', 16:18:17 INFO - 'LANG': 'en_US.UTF-8', 16:18:17 INFO - 'LANGUAGE': 'en_US:en', 16:18:17 INFO - 'LOGNAME': 'cltbld', 16:18:17 INFO - 'MAIL': '/var/mail/cltbld', 16:18:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:17 INFO - 'MOZ_NO_REMOTE': '1', 16:18:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:17 INFO - 'NO_EM_RESTART': '1', 16:18:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:17 INFO - 'PWD': '/builds/slave/test', 16:18:17 INFO - 'SHELL': '/bin/bash', 16:18:17 INFO - 'SHLVL': '1', 16:18:17 INFO - 'TERM': 'linux', 16:18:17 INFO - 'TMOUT': '86400', 16:18:17 INFO - 'USER': 'cltbld', 16:18:17 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', 16:18:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:17 INFO - '_': '/tools/buildbot/bin/python'} 16:18:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 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)) 16:18:17 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:18:17 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 16:18:17 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 16:18:17 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 16:18:17 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 16:18:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:18:19 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)) 16:18:19 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)) 16:18:19 INFO - Installing collected packages: simplejson 16:18:19 INFO - Running setup.py install for simplejson 16:18:19 INFO - building 'simplejson._speedups' extension 16:18:19 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 16:18:20 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 16:18:20 INFO - Successfully installed simplejson 16:18:20 INFO - Cleaning up... 16:18:20 INFO - Return code: 0 16:18:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:18:20 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')]} 16:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:20 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')]} 16:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:20 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 0x1d981f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f60b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21059c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2102d60>, '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 0x2103a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2103ef0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', '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 16:18:20 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 16:18:20 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 16:18:20 INFO - Using env: {'DISPLAY': ':0', 16:18:20 INFO - 'HOME': '/home/cltbld', 16:18:20 INFO - 'LANG': 'en_US.UTF-8', 16:18:20 INFO - 'LANGUAGE': 'en_US:en', 16:18:20 INFO - 'LOGNAME': 'cltbld', 16:18:20 INFO - 'MAIL': '/var/mail/cltbld', 16:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:20 INFO - 'MOZ_NO_REMOTE': '1', 16:18:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:20 INFO - 'NO_EM_RESTART': '1', 16:18:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:20 INFO - 'PWD': '/builds/slave/test', 16:18:20 INFO - 'SHELL': '/bin/bash', 16:18:20 INFO - 'SHLVL': '1', 16:18:20 INFO - 'TERM': 'linux', 16:18:20 INFO - 'TMOUT': '86400', 16:18:20 INFO - 'USER': 'cltbld', 16:18:20 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', 16:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:20 INFO - '_': '/tools/buildbot/bin/python'} 16:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:18:20 INFO - Cleaning up... 16:18:20 INFO - Return code: 0 16:18:20 INFO - Running post-action listener: _resource_record_post_action 16:18:20 INFO - Running post-action listener: _start_resource_monitoring 16:18:20 INFO - Starting resource monitoring. 16:18:20 INFO - [mozharness: 2017-09-24 23:18:20.441129Z] Finished create-virtualenv step (success) 16:18:20 INFO - [mozharness: 2017-09-24 23:18:20.441645Z] Running install step. 16:18:20 INFO - Running pre-action listener: _resource_record_pre_action 16:18:20 INFO - Running main action method: install 16:18:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:20 INFO - Reading from file tmpfile_stdout 16:18:20 INFO - Detecting whether we're running mozinstall >=1.0... 16:18:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:18:20 INFO - Reading from file tmpfile_stdout 16:18:20 INFO - Output received: 16:18:20 INFO - Usage: mozinstall [options] installer 16:18:20 INFO - Options: 16:18:20 INFO - -h, --help show this help message and exit 16:18:20 INFO - -d DEST, --destination=DEST 16:18:20 INFO - Directory to install application into. [default: 16:18:20 INFO - "/builds/slave/test"] 16:18:20 INFO - --app=APP Application being installed. [default: firefox] 16:18:20 INFO - mkdir: /builds/slave/test/build/application 16:18:20 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'] 16:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:18:38 INFO - Reading from file tmpfile_stdout 16:18:38 INFO - Output received: 16:18:38 INFO - /builds/slave/test/build/application/firefox/firefox 16:18:38 INFO - Running post-action listener: _resource_record_post_action 16:18:38 INFO - [mozharness: 2017-09-24 23:18:38.435174Z] Finished install step (success) 16:18:38 INFO - [mozharness: 2017-09-24 23:18:38.435283Z] Running setup-mitmproxy step. 16:18:38 INFO - Running pre-action listener: _resource_record_pre_action 16:18:38 INFO - Running main action method: setup_mitmproxy 16:18:38 INFO - Skipping: mitmproxy is not required 16:18:38 INFO - Running post-action listener: _resource_record_post_action 16:18:38 INFO - [mozharness: 2017-09-24 23:18:38.435761Z] Finished setup-mitmproxy step (success) 16:18:38 INFO - [mozharness: 2017-09-24 23:18:38.435853Z] Running run-tests step. 16:18:38 INFO - Running pre-action listener: _resource_record_pre_action 16:18:38 INFO - Running pre-action listener: _set_gcov_prefix 16:18:38 INFO - Running main action method: run_tests 16:18:38 WARNING - Try message not found. 16:18:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:18:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:18:38 INFO - Python 2.7.3 16:18:38 INFO - Return code: 0 16:18:38 INFO - grabbing minidump binary from tooltool 16:18:38 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')]} 16:18:38 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 0x2102d60>, '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 0x2103a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2103ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:18:38 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 16:18:38 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 16:18:38 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 16:18:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:18:38 INFO - Return code: 0 16:18:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:18:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:18:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:18:38 INFO - ENV: RUST_BACKTRACE is now full 16:18:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:18:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:18:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 16:18:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 16:18:38 INFO - Using env: {'DISPLAY': ':0', 16:18:38 INFO - 'HOME': '/home/cltbld', 16:18:38 INFO - 'LANG': 'en_US.UTF-8', 16:18:38 INFO - 'LANGUAGE': 'en_US:en', 16:18:38 INFO - 'LOGNAME': 'cltbld', 16:18:38 INFO - 'MAIL': '/var/mail/cltbld', 16:18:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:18:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:38 INFO - 'MOZ_NO_REMOTE': '1', 16:18:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:18:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:38 INFO - 'NO_EM_RESTART': '1', 16:18:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:38 INFO - 'PWD': '/builds/slave/test', 16:18:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:18:38 INFO - 'RUST_BACKTRACE': 'full', 16:18:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:18:38 INFO - 'SHELL': '/bin/bash', 16:18:38 INFO - 'SHLVL': '1', 16:18:38 INFO - 'TERM': 'linux', 16:18:38 INFO - 'TMOUT': '86400', 16:18:38 INFO - 'USER': 'cltbld', 16:18:38 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', 16:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:38 INFO - '_': '/tools/buildbot/bin/python'} 16:18:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 16:18:38 INFO - mozversion application_buildid: 20170924215251 16:18:38 INFO - mozversion application_changeset: b44e80e0590e6eed86a1902365424673c8c2eaec 16:18:38 INFO - mozversion application_display_name: Nightly 16:18:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:18:38 INFO - mozversion application_name: Firefox 16:18:38 INFO - mozversion application_remotingname: firefox 16:18:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:18:38 INFO - mozversion application_vendor: Mozilla 16:18:38 INFO - mozversion application_version: 58.0a1 16:18:38 INFO - mozversion platform_buildid: 20170924215251 16:18:38 INFO - mozversion platform_changeset: b44e80e0590e6eed86a1902365424673c8c2eaec 16:18:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:18:38 INFO - mozversion platform_version: 58.0a1 16:18:38 INFO - using testdate: 1506295118 16:18:38 INFO - actual date: 1506295118 16:18:38 INFO - starting webserver on 'localhost:42846' 16:18:38 INFO - SUITE-START | Running 2 tests 16:18:38 INFO - TEST-START | dromaeo_css 16:18:38 INFO - Initialising browser for dromaeo_css test... 16:18:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42846/getInfo.html -profile /tmp/tmptb85di/profile 16:18:38 INFO - TEST-INFO | started process 27225 (/builds/slave/test/build/application/firefox/firefox http://localhost:42846/getInfo.html) 16:18:44 INFO - TEST-INFO | 27225: exit 0 16:18:44 INFO - Browser initialized. 16:18:44 INFO - Running cycle 1/1 for dromaeo_css test... 16:18:44 INFO - Using env: {'DISPLAY': ':0', 16:18:44 INFO - 'HOME': '/home/cltbld', 16:18:44 INFO - 'JSGC_DISABLE_POISONING': '1', 16:18:44 INFO - 'LANG': 'en_US.UTF-8', 16:18:44 INFO - 'LANGUAGE': 'en_US:en', 16:18:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:18:44 INFO - 'LOGNAME': 'cltbld', 16:18:44 INFO - 'MAIL': '/var/mail/cltbld', 16:18:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:18:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:18:44 INFO - 'MOZ_CRASHREPORTER': '1', 16:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:18:44 INFO - 'MOZ_NO_REMOTE': '1', 16:18:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:18:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:44 INFO - 'NO_EM_RESTART': '1', 16:18:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:44 INFO - 'PWD': '/builds/slave/test', 16:18:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:18:44 INFO - 'RUST_BACKTRACE': 'full', 16:18:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:18:44 INFO - 'SHELL': '/bin/bash', 16:18:44 INFO - 'SHLVL': '1', 16:18:44 INFO - 'TERM': 'linux', 16:18:44 INFO - 'TMOUT': '86400', 16:18:44 INFO - 'USER': 'cltbld', 16:18:44 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', 16:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:44 INFO - '_': '/tools/buildbot/bin/python'} 16:18:44 INFO - TEST-INFO | started process 27463 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptb85di/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:18:45 INFO - PID 27463 | 16:18:45 INFO - PID 27463 | (/builds/slave/test/build/application/firefox/firefox:27513): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:18:45 INFO - PID 27463 | 16:18:55 INFO - PID 27463 | RSS: Main: 144752640 16:18:55 INFO - PID 27463 | 16:21:53 INFO - PID 27463 | Cycle 1(1): loaded http://localhost:42846/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42846/tests/dromaeo/cssquery-ext.html) 16:21:54 INFO - PID 27463 | RSS: Main: 146915328 16:21:54 INFO - PID 27463 | 16:24:56 INFO - PID 27463 | Cycle 1(1): loaded http://localhost:42846/tests/dromaeo/cssquery-ext.html (next: http://localhost:42846/tests/dromaeo/cssquery-jquery.html) 16:24:56 INFO - PID 27463 | RSS: Main: 145367040 16:24:56 INFO - PID 27463 | 16:27:58 INFO - PID 27463 | Cycle 1(1): loaded http://localhost:42846/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42846/tests/dromaeo/cssquery-mootools.html) 16:27:59 INFO - PID 27463 | RSS: Main: 148881408 16:27:59 INFO - PID 27463 | 16:31:00 INFO - PID 27463 | Cycle 1(1): loaded http://localhost:42846/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42846/tests/dromaeo/cssquery-prototype.html) 16:31:01 INFO - PID 27463 | RSS: Main: 145485824 16:31:01 INFO - PID 27463 | 16:34:04 INFO - PID 27463 | Cycle 1(1): loaded http://localhost:42846/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42846/tests/dromaeo/cssquery-yui.html) 16:34:04 INFO - PID 27463 | RSS: Main: 148176896 16:34:04 INFO - PID 27463 | 16:37:06 INFO - PID 27463 | Cycle 1(1): loaded http://localhost:42846/tests/dromaeo/cssquery-yui.html (next: http://localhost:42846/tests/dromaeo/cssquery-dojo.html) 16:37:06 INFO - PID 27463 | RSS: Main: 150913024 16:37:06 INFO - PID 27463 | 16:37:06 INFO - PID 27463 | __start_tp_report 16:37:06 INFO - PID 27463 | _x_x_mozilla_page_load 16:37:06 INFO - PID 27463 | _x_x_mozilla_page_load_details 16:37:06 INFO - PID 27463 | |i|pagename|runs| 16:37:06 INFO - PID 27463 | |0;dojo.html;3880.573136954935;3893;3897.590752970938;3906.3359228931095;3911.2568611963707;6224.937750622493;6238.313785483593;6254.593451425654;6314.431701146902;6320.462760665343;6341.112244285792;6347.587406818554;6367.331430199832;6392.840178995528;6394.904076438857;5640.182173584828;5695.601307908448;5708.286464191966;5756.222909907172;5756.597038207327;5711.086226203808;5744.770209191648;5791.10237913122;5800;5855.180274761537;5546.084895992157;5679.858003549903;5751;5808.303003639569;5828;5322.680639161663;5683.460071293221;5748.080307150836;5769.884602307972;5778.133280007991;5651.15232715096;5677.318721753416;5738.167965645;5748.051571490699;5774.191613174159;6198.132261483396;6234.719437625317;6258;6265.592736472115;6290.43386095249;6857.211420686635;6920.5847649141215;6929.792106236821;6940.444764418834;6971.058907047585;6904.240533541307;6982.685779139951;7013.053237812881;7014.053102831103;7028.297170282931;6262.530310226751;6382.106505089291;6416.101745755598;6472.385123413268;6481.546291759579;6920.377166055079;7067.257937916547;7069.575825450489;7069.717211311593;7083.82290442743;6668.433183179484;6671.39957403836;6764.898526522106;6842.692078856463;6845.589264644137;6905.516613837083;6974.755883544051;6997.090378250897;6997.195322537871;7002.4398048157045;6827.2831352708245;6869.141357330333;6884.552504087318;6930.93069069313;6967.1291088613925;6830.487713421513;6924.203716572558;6926.826829329207;6932.306769323028;6932.549384290005;5035.647504674637;5079.4412614612365;5082.949170508322;5095.617828662865;5107.438181799999;4550.88622784433;4624.745638989888;4639.443266807937;4640.048789998043;4642.72143671374;6240;6323.367663233732;6332.271788744261;6411.743530258831;6412.422881940648;6865.244823069458;6878.105846240057;6885.414739747077;6888.724451022003;6890.758823441155;6297.275813281532;6300.716467758962;6313.2424109107205;6314.052892066226;6384.52116091295;5546.69493177871;5550.139728342114;5608.186812912105;5610.1584762285165;5622.522085622768;6943.444524438033;7112.182099058675;7124.46566507514;7127.857442851114;7165.498415110892;6702.430293425114;6721.428678562377;6790.83022924411;6819.4544436443975;6865.725370985305;7012.298770122947;7098.467614928798;7122.537035092702;7123.394511466583;7133.928660713326;6766.729330826712;6837.179538455417;6841.828954276183;6861.622610756456;6866.588004719732;6880.552764070319;6880.827979300558;6882.483813714091;6979.40675042627;6984.895226571504;6550.574212676161;6633.137692100189;6639.402453779183;6676.198856137295;6698.196216454056;6602.801915942529;6719.26088130315;6754.088198093391;6759.053732477555;6761.357671021246;6561.40947314744;6592.307807680317;6593.769218077345;6686.632235226914;6688.866222675617;6544.411003009752;6676.666166691549;6731.124953755981;6746.69639866217;6784.559003664746;6418.972964325685;6483.286838447672;6553.868922621617;6554.770583029571;6559.114519539801 16:37:06 INFO - PID 27463 | |1;ext.html;939.7697564096798;960.3757557587567;961.653804630333;962.1388856973014;964.14191369681;41349;45813;45890.54109458905;45943.54056459435;45988.31017534732;42554.36168457475;42575;42766.78616606921;42827.571724282745;43099.569004309946;7624.08510978682;7757.5345479271145;7804.531728096304;7812.414068944823;7829.17793631669;7589.658465369044;7622.199669034755;7654.578998155099;7668.07983042033;7683.308502234798;7588.241175882423;7694.615269236544;7731.22687731228;8186.181381861796;8265.752027439157;8232.917670823275;8297.377696672773;8309.37679674027;8359.707410240613;8364.414490985633;8075.838483230361;8332.62503187358;8367.330613550963;8400.117987611275;8408.453450525696;40912;40944.795276023426;40975.7951210245;41056.589434105874;41460.792696036326;3663.578688450809;3747.681447077002;3753.1555400035045;3768.2840260350445;3770.6794922431627;33677.6632233677;33715.49426758601;33830.323393531995;33872.49191262133;33900.32199355999;5097.8215762448135;5125.1287281162295;5132.820351287687;5217.2695822584865;5221.582273418117;234181;234362;234689;234966;235370;46064.309035364495;46420.535794642296;49652.75173624109;50676.493235067916;50900;53395.466045339825;53872.46127538675;53909.92180156453;53912.73043634835;53946;16666.33334666624;16897.83102168987;16898.662026759397;16911.661766764842;16916.830831691525;13766.65583360424;13768.311584420939;13878.306084695927;13882.514112006233;13902.652433689238;14422;14451.277436128152;14512.564623061326;14589.416423342947;14601.634959126106;5708.71456427177;5789.9710501447225;5813.912791308134;5824.534037277008;5872.559558033165;1084.2897901874285;1084.6854412220525;1089.1123734156638;1096.8464414981909;1099.1316859680762;25394.492110157695;25473.490530189294;25642.358941026625;25738.48523029529;25775.355616109748;1181.8772166441915;1191.9940400298117;1199.424276347358;1207.275634619239;1209.6492017315056;13076.280804555841;13395.196288222543;13700;13774.517891873937;13831.930840345734;266949;267606;267877;268306;268589;56612.43387566154;56808;56891.715541422025;57210.42789572134;57226.141607875914;1499.7450433426343;1521.2089713349217;1529.250667173077;1539.6074001129641;1557.2603013568691;24976.875115624305;25191.49617007686;25387.873060634578;25408.237752867444;25456;40421.59578404178;40448.3932741015;40465.39301910415;40503;40582;241416;242102;243797;244336;244695;32556.674433255364;32850.1787455306;33068.1732956678;34835.65164348324;35131;46614.53385466102;46810.76594617005;46939.530604693515;47065.52934470611;47085.29372059353;34390.48414273838;34690;34828.78099266513;35181;35585.288294233455;34448;34767.82616086904;34908.82545587256;35064.47403288902;35243;40662.39006414847;40720.79639601783;40798.796006020966;41216.1756764869;41236.175276493705;33507.162320942174;33706;33824.66175338215;33875.15312117217;34537.65462345345;25729.614055789552;26010.609840852023;26117.869410652824;26412;26500.337491562866 16:37:06 INFO - PID 27463 | |2;jquery.html;598.5903197968784;599.7061439894447;605.74255941225;636.4113195294353;640.2700920950116;22969.19607813727;24006.039758409686;24094.87952560237;24235.878820605893;24267.514649706995;22773.4306642334;23495.882520587416;23684.763152368472;23747.168849090285;23755.881220593874;6604.141461609984;6757.459403247753;6764.053032575419;6769.796906092825;6771.289014653465;6620.900686489683;6874.518783685144;6895;6900.068490753759;6905.378515933565;6872.553284036547;6874.48441366897;6881.13985751781;6881.862362752742;6883.139607549057;6614.305497922697;6858.897116543231;6867;6876.415504682108;6879.3120687931305;6319.715612797389;6396.552241343108;6416.262129855079;6422.357764223587;6427.132337134473;30674.233144171576;30767.384652306828;30843;30933.535996960065;31041;2238.3173132194675;2265.728112626479;2266.240809328877;2272.1706577099403;2275.9089636414524;20074.498137546532;21601.567968640695;21716;21717.565648686937;21724.674129888066;2423.6243382275775;2430.8663023533877;2448.5592593333195;2509.5608268553115;2624.5669464538328;153678;159054;159137;159142.6128608072;159167.99999999884;16427.671446571003;16696.916515417346;16762.32950681959;16781.328746850235;16858.662826743395;26241.868790655924;26242.606360904603;26277.605835912476;26302.736972630413;26370.868145659148;9921.80156396868;10025.899741002642;10068.647597334204;10105.292629515863;10233.590656373663;8697.173768491983;8833.33749968755;8850.468971861581;8863.822723545622;8875.689350872817;9893.45585992763;10147.543360548792;10153.847692284622;10157.9492102539;10205.336653117673;2932.1057077591113;2943.8813250964495;2965.530329514473;2982.164993801739;2983.701629837042;1000.4847503535685;1016.4307987526861;1017.5115944346755;1020.5764607687727;1024.7386916336286;20799.68800467994;20997.47506312324;21274.36176914695;21477.67783483249;21510.677339839916;1062.1449732965036;1064.3347907557777;1077.665923563682;1091.1380009792333;1091.7598128411737;10810.729731756768;11624.709382265342;11673.182877198515;11737.941310293394;11754.471048802825;593087;593506;596208;617136;618956;133552;134244;137026;137208;137616.31191843978;1201.1891972918245;1208.6374087773809;1242.4471110355805;1246.6135497995651;1248.875112488762;18984.905075474533;19114.23543058262;19164.233430662618;19282.7107593389;19638.018099095272;64172.358276416635;65568.67215663892;65760.67119664371;65900;66505.66747166427;84871.15128848632;84937;85226.57386713027;85272;85350.57324713336;15506.069635821887;15729.44946926852;15869.603259918595;15909.204539773196;15948.521544353687;23792.167274145322;24094.87952560226;24166.87916560406;24216.152434664702;24520.632190517514;18074.915505069737;19588.90205548963;19779.109940052444;19937.900310498353;20005;16809.495715128567;16913.746293805358;17054.82945170582;17459.47621571355;18418.539536511693;18400.355987540373;19763.308284209983;19874.801251987872;20028.898410586975;20346.89826550858;15329.6167595811;15667;15739.606509836887;15759.763603546186;15803.920980395025;19184;19337.12982915744;19407.320743774464;19649.70525442091;19656.410307690792 16:37:06 INFO - PID 27463 | |3;mootools.html;1422.4879043544324;1470.1913947328974;1476.3208923895008;1493.1936754152757;1497.5956491747224;16950.406735764234;17654.38209662662;17658.293668253253;17675.55811104723;17687.557811054714;17062.914685426556;17086.31654733809;17125.657486850287;17212.225449854755;17258.395956141536;2823.3929705113396;2848.8005839591183;2922.620059392276;2932.9706702933017;2939.5884576159247;2853.115534184399;2878.582605522198;2895.5222388305906;2897.333613268949;2898.4348052129844;2607.739226077396;2831.3063299491655;2831.348789778352;2831.7451429371354;2884.6682631497333;2884.5817356483194;2921.35730139369;2944.6319210098736;2948.7935844490894;2949.734523892849;2676;2777.3056735816044;2804.284907348613;2840.8863645454157;2849.2734352739917;16557.50982411589;16728.24722887473;16728.916355418267;16761.245743941552;16761.580960475963;8562.48625082497;8819.51492667903;8831.470111793313;8871.24594409476;8927.151920567536;5505.752241149158;5798.536117110663;5853.560982926298;5879.3826648202175;5917.142014407887;2061.5155438471947;2068.7827778083233;2112.0706888968803;2148.8603240789457;2159.9892000540053;31304.37391252185;31458.37083258345;31620.209494762585;31688.841555792074;31731.20671983219;7452.403807695479;7452.552846829208;7481.401487880957;7584.469087163846;7716.498427602188;20009.09959051846;20015;20035.49911252201;20047.699284510745;20058;8465;8598;8632.093630168742;8643.222110010003;8651.091635378194;7512.47412681118;7846.411519136088;7881.487703299267;7927.603619818986;8068.9596552016865;5733.3406658234;5737.225474560964;5758.1362795581;5789;5861.88276234473;1664.9167541622871;1703;1709.358990378608;1711.9572010699833;1712.2722842791763;962.37926537383;969.132626299458;976.0138118928089;995.8456439251928;1003.1222680154865;8294.751157465285;8340;8418.663253469793;8441.113683063313;8475.449095808754;764.0831002796666;768.1703759939252;810.2586133687728;824.2828738996991;829.344817594106;5304.363476382859;5337.359516857924;5905.557083218776;6032.909506357408;6140.263168419729;25133.748662513506;25712.357191070372;25762.227133186036;25780.355491112874;26051.478970420485;24416.63375049376;25198.24405267845;25807.74192258091;25839.74160258411;25861.74138258631;12012.219205751599;12069.939650301692;12137.089718271165;12376;12625.116241863165;9153.149199922243;9351.766205844637;9476.194523465307;9500.714978550655;9569.186619137452;20895.68656470124;20936.68594971107;21250.89374553117;21289;21525.46186345354;21826.672599911333;22153.335399938027;22170.334889953327;22261.332160035225;22319.553608928054;12420;12494.812577811523;12519;12620.43208055658;12663.93668031654;15610.141442220332;15993.520194394187;16014.519564413085;16015.039097654178;16025.198740063184;11838.763224735629;12397.070219733376;12426.254424734545;12475.376231188222;12493.625191244277;12511.186772859735;12546.749065018466;12642.936785316015;12793.42429590652;12901.225926444444;10995.780084398428;11154.051905588116;11158.776824463628;11166.274192177483;11199.328040317607;8404.831903362021;8523.872141918;8546.35902307317;8563.143685631387;8585.270252028398;8316.501009939422;8335.95832020836;8374;8386.287165590993;8449 16:37:06 INFO - PID 27463 | |4;prototype.html;531.3464438740351;545.9344878614564;570.873496226556;588.2529187931327;593.2791658135368;6074.210352654154;6095.7256923438445;6152.200213972183;6312.905306420407;6405.647689377082;6166.414190651892;6168.9383106168925;6213.906791398133;6237.750489980395;6260.7808726694575;5144.305518754958;5146.202338637517;5146.536811686948;5166.793328266865;5196.58427325813;4903.548225887056;4952.579030782388;4986.227134794112;5001.774920128585;5089.974550127245;4969.8509044728535;4979.950200498003;4982.427020892589;5010.3737032870895;5022.623303252252;4947.6784009039475;4962.280469331945;4976.477469865648;4985.626078044161;4996.500349964975;4530;4567.406237189178;4588.564086411786;4597.908041839144;4626.21354369758;6197.349278325801;6211.627302361828;6250.499960003189;6252.749890004395;6273.65494897785;5852.12218167274;5863.560232982543;5868.735906884158;5878.177055212231;5899.469047785677;6583.440407565363;6673.165854268216;6687.230968438643;6705.899411508832;6719.227288861745;5040.7731652075745;5041.747912604392;5069.340985671839;5084.593232541388;5086.694798312114;6855.314468553154;6870.62211578363;6879.5184337096425;6893.620850853201;6915.654217289115;5123.410807757155;5216.1393370094265;5244.868878278074;5246.527812496894;5269.340157850265;5315.73421328932;5316.627836051439;5319.255304257407;5344.198370244417;5356.758945847446;5857.209276747584;5914.822555323347;5915.3788852170765;5931.525477961753;5937.376575459601;6371;6469.805905822833;6617.900731489031;6623.205215374186;6626.668666566652;1726.5510967148632;1749.9850086949539;1804.5849454625509;1807.2951548895942;1811.1668632428966;1280.3918138883955;1285.4665313894816;1287.9806802897965;1296.9286689231994;1306.9019823513274;6099.176611157525;6101.237345331833;6172.321044685081;6184.907226391608;6194.969025154845;6585.2426970898005;6661.566998145105;6735.663216839139;6760.966195168992;6814.931850681529;6165.753369865155;6187.133801267826;6202.906956395657;6222.533310001678;6269.373062693785;3518.683318501347;3529.8588056477965;3530.982345088258;3586.421974331286;3587.497750314958;6999.8600027999155;7048.801703710382;7088.291170882871;7093.787186384417;7105.43156547475;6689.832754181184;6802.319768023257;6904.689288982008;6918.584884906922;6942.166939967236;6889.483288753364;6949.548279361826;7019.262977387403;7036.6129862857015;7089.255628158969;6698.933010669831;6708.19501659804;6714.294999025227;6742.460208390438;6745.8194815908;6607.91190783104;6616.735330586723;6697.732090716317;6711.409395973131;6784.525083244125;6914.720776656334;7032.367086962198;7054.576725396492;7078.539894906815;7080.468964827762;5113.846584602468;5133.383993920754;5153.1754919214245;5161.458046905021;5167.534921857051;6455.741770329134;6526.282108967915;6739.865202695821;6773.830654233683;6791.166385076101;6298.716557755004;6499.1876015498065;6545.21457425112;6559.934400655932;6587.341265873374;6436;6456.096146539582;6489.9351006491215;6568.901466477911;6601.834954126185;6321.1782468278825;6379.298277189599;6404.711787969646;6416.51876109303;6490.221173459215;6582.407583317333;6597.868042639025;6615.4046135848;6631.767888123893;6652.201735791736;6281.905771413341;6309.2744334400295;6423.582467139808;6441.194850643669;6468.579542329733 16:37:06 INFO - PID 27463 | |5;yui.html;945.1635302757062;957.3059531839417;964.482700152602;966.3909590531765;981.6956743409544;18679.71980420293;19378.90310548447;21876.343709688714;22696.319110426713;22703.318900432972;20510.589788204263;20663.793362066375;20828.271010514636;20960;21999.780002199972;1055.4406164732684;1088.0588291128156;1088.3524303039678;1088.4448931045185;1088.5700148441401;1028.090140225898;1059.7668512927144;1063.191974099686;1072.5334479501403;1075.7740874416381;1108.362691452414;1119.4067144413473;1121.2151493954225;1122.4892673833426;1122.5060973171778;1113.7104352868273;1120.3445984099312;1122.9494672739706;1123.1239633086207;1124.3703526025452;819.5574389829485;820.5527987246905;823.7611092783145;826.0046643794274;827.148037521354;20107.39677809668;20233.39299820993;20334.898325508424;20506.897465512728;20879;20771.79228207714;20857.478563035893;21230.575388492143;21249.68125478119;21310.14759409622;18669.906650466797;18688.159032843418;18689.34587289452;18739.34412295563;18779.342723004767;3825.2731980923536;3897;3949.249642567903;3967.4842270504937;3976.1252524444576;158921;161638.19180904137;161982;163228;163911.9999999988;16976.915115424592;17143.65712685764;17308.826911730972;17370;17375.913120434318;28105;28255.71744282572;28265.57601635977;30145.09564713062;30158.84920575383;8989.820203596022;9130.132637399569;9133.451992880315;9134.223590994707;9187.724368268962;4473.485549161888;4505.391772110723;4537.115262523776;4564;4565.178267911741;5693.259876216065;5792.4497172768515;5799.101139323411;5809.970950145223;5812.505936995402;1038.6157121864958;1043.9164866810636;1057.7038429239672;1060.0777323728405;1063.7819247054335;676.7935779587175;696.2932623387265;698.5180225644289;707.1726080485844;713.4078714666814;12144.392780360946;12283.815742763865;12532.81200781989;12858.9357053216;13050.869491305155;679.8844196486609;695.3776370148789;697.10770214126;702.0942983551162;710.2648758534891;5710.229119068956;5711.228984087095;5827.825165245049;5862.941370586324;5912.379200183918;147741;148292;150859.24570377078;152823;155592;129610;131675;133050.33474832564;133433;136888;114597;116043;117530.74848314254;119945;120400.39799800944;11047.779044418905;11241.325520468798;11331.433428328715;11372.260803047775;11423.97184253388;56156.71921640365;56469;57484;57628.13557796553;58495;74958.25041749731;76930.61534692514;78468;78641;79121.60439197767;15933.283002264694;16595.585110372336;16663.583410414838;16839.41062062822;16973.405930792364;19377.709334359715;19446.902765486648;19549.218031279157;19664.705029424855;19689.606207876048;14858.925705371405;15063.171525566198;15268.694626107637;15322.310496027483;15381.923090384476;15602;15612.141332226382;15698.215089245263;15837.683246335238;15865.68268634644;16496.587585309982;16626.75059874127;16685.74971375406;16717.49847504577;16756.32974680999;11177.944110279397;11194.888051119384;11340.829887551528;11364.261323013978;11613.129015323715;10549;10790.1367890567;10849.403282819521;11005.229633925868;11236.83144752813 16:37:06 INFO - PID 27463 | __end_tp_report 16:37:06 INFO - PID 27463 | __start_cc_report 16:37:06 INFO - PID 27463 | _x_x_mozilla_cycle_collect,281 16:37:06 INFO - PID 27463 | __end_cc_report 16:37:06 INFO - PID 27463 | __startTimestamp1506296226626__endTimestamp 16:37:06 INFO - PID 27463 | 16:37:06 INFO - PID 27463 | ------- Summary: start ------- 16:37:06 INFO - PID 27463 | Number of tests: 6 16:37:06 INFO - PID 27463 | 16:37:06 INFO - PID 27463 | [#0] dojo.html Cycles:165 Average:6334.36 Median:6561.41 stddev:747.00 (11.4%) stddev-sans-first:724.07 16:37:06 INFO - PID 27463 | Values: 3880.6 3893.0 3897.6 3906.3 3911.3 6224.9 6238.3 6254.6 6314.4 6320.5 6341.1 6347.6 6367.3 6392.8 6394.9 5640.2 5695.6 5708.3 5756.2 5756.6 5711.1 5744.8 5791.1 5800.0 5855.2 5546.1 5679.9 5751.0 5808.3 5828.0 5322.7 5683.5 5748.1 5769.9 5778.1 5651.2 5677.3 5738.2 5748.1 5774.2 6198.1 6234.7 6258.0 6265.6 6290.4 6857.2 6920.6 6929.8 6940.4 6971.1 6904.2 6982.7 7013.1 7014.1 7028.3 6262.5 6382.1 6416.1 6472.4 6481.5 6920.4 7067.3 7069.6 7069.7 7083.8 6668.4 6671.4 6764.9 6842.7 6845.6 6905.5 6974.8 6997.1 6997.2 7002.4 6827.3 6869.1 6884.6 6930.9 6967.1 6830.5 6924.2 6926.8 6932.3 6932.5 5035.6 5079.4 5082.9 5095.6 5107.4 4550.9 4624.7 4639.4 4640.0 4642.7 6240.0 6323.4 6332.3 6411.7 6412.4 6865.2 6878.1 6885.4 6888.7 6890.8 6297.3 6300.7 6313.2 6314.1 6384.5 5546.7 5550.1 5608.2 5610.2 5622.5 6943.4 7112.2 7124.5 7127.9 7165.5 6702.4 6721.4 6790.8 6819.5 6865.7 7012.3 7098.5 7122.5 7123.4 7133.9 6766.7 6837.2 6841.8 6861.6 6866.6 6880.6 6880.8 6882.5 6979.4 6984.9 6550.6 6633.1 6639.4 6676.2 6698.2 6602.8 6719.3 6754.1 6759.1 6761.4 6561.4 6592.3 6593.8 6686.6 6688.9 6544.4 6676.7 6731.1 6746.7 6784.6 6419.0 6483.3 6553.9 6554.8 6559.1 16:37:06 INFO - PID 27463 | 16:37:06 INFO - PID 27463 | [#1] ext.html Cycles:180 Average:42469.84 Median:3705.63 stddev:64682.71 (1745.5%) stddev-sans-first:64789.00 16:37:06 INFO - PID 27463 | Values: 939.8 960.4 961.7 962.1 964.1 41349.0 45813.0 45890.5 45943.5 45988.3 42554.4 42575.0 42766.8 42827.6 43099.6 7624.1 7757.5 7804.5 7812.4 7829.2 7589.7 7622.2 7654.6 7668.1 7683.3 7588.2 7694.6 7731.2 8186.2 8265.8 8232.9 8297.4 8309.4 8359.7 8364.4 8075.8 8332.6 8367.3 8400.1 8408.5 40912.0 40944.8 40975.8 41056.6 41460.8 3663.6 3747.7 3753.2 3768.3 3770.7 33677.7 33715.5 33830.3 33872.5 33900.3 5097.8 5125.1 5132.8 5217.3 5221.6 234181.0 234362.0 234689.0 234966.0 235370.0 46064.3 46420.5 49652.8 50676.5 50900.0 53395.5 53872.5 53909.9 53912.7 53946.0 16666.3 16897.8 16898.7 16911.7 16916.8 13766.7 13768.3 13878.3 13882.5 13902.7 14422.0 14451.3 14512.6 14589.4 14601.6 5708.7 5790.0 5813.9 5824.5 5872.6 1084.3 1084.7 1089.1 1096.8 1099.1 25394.5 25473.5 25642.4 25738.5 25775.4 1181.9 1192.0 1199.4 1207.3 1209.6 13076.3 13395.2 13700.0 13774.5 13831.9 266949.0 267606.0 267877.0 268306.0 268589.0 56612.4 56808.0 56891.7 57210.4 57226.1 1499.7 1521.2 1529.3 1539.6 1557.3 24976.9 25191.5 25387.9 25408.2 25456.0 40421.6 40448.4 40465.4 40503.0 40582.0 241416.0 242102.0 243797.0 244336.0 244695.0 32556.7 32850.2 33068.2 34835.7 35131.0 46614.5 46810.8 46939.5 47065.5 47085.3 34390.5 34690.0 34828.8 35181.0 35585.3 34448.0 34767.8 34908.8 35064.5 35243.0 40662.4 40720.8 40798.8 41216.2 41236.2 33507.2 33706.0 33824.7 33875.2 34537.7 25729.6 26010.6 26117.9 26412.0 26500.3 16:37:06 INFO - PID 27463 | 16:37:06 INFO - PID 27463 | [#2] jquery.html Cycles:180 Average:40247.70 Median:2269.21 stddev:101509.90 (4473.4%) stddev-sans-first:101751.01 16:37:06 INFO - PID 27463 | Values: 598.6 599.7 605.7 636.4 640.3 22969.2 24006.0 24094.9 24235.9 24267.5 22773.4 23495.9 23684.8 23747.2 23755.9 6604.1 6757.5 6764.1 6769.8 6771.3 6620.9 6874.5 6895.0 6900.1 6905.4 6872.6 6874.5 6881.1 6881.9 6883.1 6614.3 6858.9 6867.0 6876.4 6879.3 6319.7 6396.6 6416.3 6422.4 6427.1 30674.2 30767.4 30843.0 30933.5 31041.0 2238.3 2265.7 2266.2 2272.2 2275.9 20074.5 21601.6 21716.0 21717.6 21724.7 2423.6 2430.9 2448.6 2509.6 2624.6 153678.0 159054.0 159137.0 159142.6 159168.0 16427.7 16696.9 16762.3 16781.3 16858.7 26241.9 26242.6 26277.6 26302.7 26370.9 9921.8 10025.9 10068.6 10105.3 10233.6 8697.2 8833.3 8850.5 8863.8 8875.7 9893.5 10147.5 10153.8 10157.9 10205.3 2932.1 2943.9 2965.5 2982.2 2983.7 1000.5 1016.4 1017.5 1020.6 1024.7 20799.7 20997.5 21274.4 21477.7 21510.7 1062.1 1064.3 1077.7 1091.1 1091.8 10810.7 11624.7 11673.2 11737.9 11754.5 593087.0 593506.0 596208.0 617136.0 618956.0 133552.0 134244.0 137026.0 137208.0 137616.3 1201.2 1208.6 1242.4 1246.6 1248.9 18984.9 19114.2 19164.2 19282.7 19638.0 64172.4 65568.7 65760.7 65900.0 66505.7 84871.2 84937.0 85226.6 85272.0 85350.6 15506.1 15729.4 15869.6 15909.2 15948.5 23792.2 24094.9 24166.9 24216.2 24520.6 18074.9 19588.9 19779.1 19937.9 20005.0 16809.5 16913.7 17054.8 17459.5 18418.5 18400.4 19763.3 19874.8 20028.9 20346.9 15329.6 15667.0 15739.6 15759.8 15803.9 19184.0 19337.1 19407.3 19649.7 19656.4 16:37:06 INFO - PID 27463 | 16:37:06 INFO - PID 27463 | [#3] mootools.html Cycles:180 Average:10567.47 Median:2827.35 stddev:7883.92 (278.8%) stddev-sans-first:7876.10 16:37:06 INFO - PID 27463 | Values: 1422.5 1470.2 1476.3 1493.2 1497.6 16950.4 17654.4 17658.3 17675.6 17687.6 17062.9 17086.3 17125.7 17212.2 17258.4 2823.4 2848.8 2922.6 2933.0 2939.6 2853.1 2878.6 2895.5 2897.3 2898.4 2607.7 2831.3 2831.3 2831.7 2884.7 2884.6 2921.4 2944.6 2948.8 2949.7 2676.0 2777.3 2804.3 2840.9 2849.3 16557.5 16728.2 16728.9 16761.2 16761.6 8562.5 8819.5 8831.5 8871.2 8927.2 5505.8 5798.5 5853.6 5879.4 5917.1 2061.5 2068.8 2112.1 2148.9 2160.0 31304.4 31458.4 31620.2 31688.8 31731.2 7452.4 7452.6 7481.4 7584.5 7716.5 20009.1 20015.0 20035.5 20047.7 20058.0 8465.0 8598.0 8632.1 8643.2 8651.1 7512.5 7846.4 7881.5 7927.6 8069.0 5733.3 5737.2 5758.1 5789.0 5861.9 1664.9 1703.0 1709.4 1712.0 1712.3 962.4 969.1 976.0 995.8 1003.1 8294.8 8340.0 8418.7 8441.1 8475.4 764.1 768.2 810.3 824.3 829.3 5304.4 5337.4 5905.6 6032.9 6140.3 25133.7 25712.4 25762.2 25780.4 26051.5 24416.6 25198.2 25807.7 25839.7 25861.7 12012.2 12069.9 12137.1 12376.0 12625.1 9153.1 9351.8 9476.2 9500.7 9569.2 20895.7 20936.7 21250.9 21289.0 21525.5 21826.7 22153.3 22170.3 22261.3 22319.6 12420.0 12494.8 12519.0 12620.4 12663.9 15610.1 15993.5 16014.5 16015.0 16025.2 11838.8 12397.1 12426.3 12475.4 12493.6 12511.2 12546.7 12642.9 12793.4 12901.2 10995.8 11154.1 11158.8 11166.3 11199.3 8404.8 8523.9 8546.4 8563.1 8585.3 8316.5 8336.0 8374.0 8386.3 8449.0 16:37:06 INFO - PID 27463 | 16:37:06 INFO - PID 27463 | [#4] prototype.html Cycles:180 Average:5629.89 Median:6218.22 stddev:1567.64 (25.2%) stddev-sans-first:1524.61 16:37:06 INFO - PID 27463 | Values: 531.3 545.9 570.9 588.3 593.3 6074.2 6095.7 6152.2 6312.9 6405.6 6166.4 6168.9 6213.9 6237.8 6260.8 5144.3 5146.2 5146.5 5166.8 5196.6 4903.5 4952.6 4986.2 5001.8 5090.0 4969.9 4980.0 4982.4 5010.4 5022.6 4947.7 4962.3 4976.5 4985.6 4996.5 4530.0 4567.4 4588.6 4597.9 4626.2 6197.3 6211.6 6250.5 6252.7 6273.7 5852.1 5863.6 5868.7 5878.2 5899.5 6583.4 6673.2 6687.2 6705.9 6719.2 5040.8 5041.7 5069.3 5084.6 5086.7 6855.3 6870.6 6879.5 6893.6 6915.7 5123.4 5216.1 5244.9 5246.5 5269.3 5315.7 5316.6 5319.3 5344.2 5356.8 5857.2 5914.8 5915.4 5931.5 5937.4 6371.0 6469.8 6617.9 6623.2 6626.7 1726.6 1750.0 1804.6 1807.3 1811.2 1280.4 1285.5 1288.0 1296.9 1306.9 6099.2 6101.2 6172.3 6184.9 6195.0 6585.2 6661.6 6735.7 6761.0 6814.9 6165.8 6187.1 6202.9 6222.5 6269.4 3518.7 3529.9 3531.0 3586.4 3587.5 6999.9 7048.8 7088.3 7093.8 7105.4 6689.8 6802.3 6904.7 6918.6 6942.2 6889.5 6949.5 7019.3 7036.6 7089.3 6698.9 6708.2 6714.3 6742.5 6745.8 6607.9 6616.7 6697.7 6711.4 6784.5 6914.7 7032.4 7054.6 7078.5 7080.5 5113.8 5133.4 5153.2 5161.5 5167.5 6455.7 6526.3 6739.9 6773.8 6791.2 6298.7 6499.2 6545.2 6559.9 6587.3 6436.0 6456.1 6489.9 6568.9 6601.8 6321.2 6379.3 6404.7 6416.5 6490.2 6582.4 6597.9 6615.4 6631.8 6652.2 6281.9 6309.3 6423.6 6441.2 6468.6 16:37:06 INFO - PID 27463 | 16:37:06 INFO - PID 27463 | [#5] yui.html Cycles:180 Average:28181.93 Median:18674.81 stddev:43157.83 (231.1%) stddev-sans-first:43230.45 16:37:06 INFO - PID 27463 | Values: 945.2 957.3 964.5 966.4 981.7 18679.7 19378.9 21876.3 22696.3 22703.3 20510.6 20663.8 20828.3 20960.0 21999.8 1055.4 1088.1 1088.4 1088.4 1088.6 1028.1 1059.8 1063.2 1072.5 1075.8 1108.4 1119.4 1121.2 1122.5 1122.5 1113.7 1120.3 1122.9 1123.1 1124.4 819.6 820.6 823.8 826.0 827.1 20107.4 20233.4 20334.9 20506.9 20879.0 20771.8 20857.5 21230.6 21249.7 21310.1 18669.9 18688.2 18689.3 18739.3 18779.3 3825.3 3897.0 3949.2 3967.5 3976.1 158921.0 161638.2 161982.0 163228.0 163912.0 16976.9 17143.7 17308.8 17370.0 17375.9 28105.0 28255.7 28265.6 30145.1 30158.8 8989.8 9130.1 9133.5 9134.2 9187.7 4473.5 4505.4 4537.1 4564.0 4565.2 5693.3 5792.4 5799.1 5810.0 5812.5 1038.6 1043.9 1057.7 1060.1 1063.8 676.8 696.3 698.5 707.2 713.4 12144.4 12283.8 12532.8 12858.9 13050.9 679.9 695.4 697.1 702.1 710.3 5710.2 5711.2 5827.8 5862.9 5912.4 147741.0 148292.0 150859.2 152823.0 155592.0 129610.0 131675.0 133050.3 133433.0 136888.0 114597.0 116043.0 117530.7 119945.0 120400.4 11047.8 11241.3 11331.4 11372.3 11424.0 56156.7 56469.0 57484.0 57628.1 58495.0 74958.3 76930.6 78468.0 78641.0 79121.6 15933.3 16595.6 16663.6 16839.4 16973.4 19377.7 19446.9 19549.2 19664.7 19689.6 14858.9 15063.2 15268.7 15322.3 15381.9 15602.0 15612.1 15698.2 15837.7 15865.7 16496.6 16626.8 16685.7 16717.5 16756.3 11177.9 11194.9 11340.8 11364.3 11613.1 10549.0 10790.1 10849.4 11005.2 11236.8 16:37:06 INFO - PID 27463 | -------- Summary: end -------- 16:37:06 INFO - PID 27463 | 16:37:07 INFO - TEST-INFO | 27463: exit 0 16:37:07 INFO - TEST-OK | dromaeo_css | took 1108482ms 16:37:07 INFO - TEST-START | kraken 16:37:07 INFO - Initialising browser for kraken test... 16:37:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42846/getInfo.html -profile /tmp/tmpQdpQ4z/profile 16:37:07 INFO - TEST-INFO | started process 27835 (/builds/slave/test/build/application/firefox/firefox http://localhost:42846/getInfo.html) 16:37:12 INFO - TEST-INFO | 27835: exit 0 16:37:12 INFO - Browser initialized. 16:37:12 INFO - Running cycle 1/1 for kraken test... 16:37:12 INFO - Using env: {'DISPLAY': ':0', 16:37:12 INFO - 'HOME': '/home/cltbld', 16:37:12 INFO - 'JSGC_DISABLE_POISONING': '1', 16:37:12 INFO - 'LANG': 'en_US.UTF-8', 16:37:12 INFO - 'LANGUAGE': 'en_US:en', 16:37:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:37:12 INFO - 'LOGNAME': 'cltbld', 16:37:12 INFO - 'MAIL': '/var/mail/cltbld', 16:37:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:37:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:37:12 INFO - 'MOZ_CRASHREPORTER': '1', 16:37:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:37:12 INFO - 'MOZ_NO_REMOTE': '1', 16:37:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:37:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:12 INFO - 'NO_EM_RESTART': '1', 16:37:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:12 INFO - 'PWD': '/builds/slave/test', 16:37:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:37:12 INFO - 'RUST_BACKTRACE': 'full', 16:37:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:37:12 INFO - 'SHELL': '/bin/bash', 16:37:12 INFO - 'SHLVL': '1', 16:37:12 INFO - 'TERM': 'linux', 16:37:12 INFO - 'TMOUT': '86400', 16:37:12 INFO - 'USER': 'cltbld', 16:37:12 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179', 16:37:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:12 INFO - '_': '/tools/buildbot/bin/python'} 16:37:12 INFO - TEST-INFO | started process 28074 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQdpQ4z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:37:13 INFO - PID 28074 | 16:37:13 INFO - PID 28074 | (/builds/slave/test/build/application/firefox/firefox:28124): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:37:13 INFO - PID 28074 | 16:37:23 INFO - PID 28074 | RSS: Main: 144637952 16:37:23 INFO - PID 28074 | 16:37:26 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42846/tests/kraken/driver.html?testName=audio-beat-detection) 16:37:27 INFO - PID 28074 | RSS: Main: 151228416 16:37:27 INFO - PID 28074 | 16:37:29 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42846/tests/kraken/driver.html?testName=audio-dft) 16:37:30 INFO - PID 28074 | RSS: Main: 152104960 16:37:30 INFO - PID 28074 | 16:37:33 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42846/tests/kraken/driver.html?testName=audio-fft) 16:37:34 INFO - PID 28074 | RSS: Main: 147673088 16:37:34 INFO - PID 28074 | 16:37:36 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42846/tests/kraken/driver.html?testName=audio-oscillator) 16:37:36 INFO - PID 28074 | RSS: Main: 148811776 16:37:36 INFO - PID 28074 | 16:37:38 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42846/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:37:39 INFO - PID 28074 | RSS: Main: 148824064 16:37:39 INFO - PID 28074 | 16:37:42 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42846/tests/kraken/driver.html?testName=imaging-darkroom) 16:37:43 INFO - PID 28074 | RSS: Main: 149327872 16:37:43 INFO - PID 28074 | 16:37:46 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42846/tests/kraken/driver.html?testName=imaging-desaturate) 16:37:47 INFO - PID 28074 | RSS: Main: 151973888 16:37:47 INFO - PID 28074 | 16:37:50 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42846/tests/kraken/driver.html?testName=json-parse-financial) 16:37:50 INFO - PID 28074 | RSS: Main: 151834624 16:37:50 INFO - PID 28074 | 16:37:52 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42846/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:37:52 INFO - PID 28074 | RSS: Main: 151826432 16:37:52 INFO - PID 28074 | 16:37:54 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42846/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:37:55 INFO - PID 28074 | RSS: Main: 151982080 16:37:55 INFO - PID 28074 | 16:37:57 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42846/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:37:57 INFO - PID 28074 | RSS: Main: 148905984 16:37:57 INFO - PID 28074 | 16:38:00 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42846/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:38:00 INFO - PID 28074 | RSS: Main: 149508096 16:38:00 INFO - PID 28074 | 16:38:03 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42846/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:38:04 INFO - PID 28074 | RSS: Main: 149393408 16:38:04 INFO - PID 28074 | 16:38:05 INFO - PID 28074 | Cycle 1(1): loaded http://localhost:42846/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42846/tests/kraken/driver.html?testName=ai-astar) 16:38:06 INFO - PID 28074 | RSS: Main: 150859776 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | __start_tp_report 16:38:06 INFO - PID 28074 | _x_x_mozilla_page_load 16:38:06 INFO - PID 28074 | _x_x_mozilla_page_load_details 16:38:06 INFO - PID 28074 | |i|pagename|runs| 16:38:06 INFO - PID 28074 | |0;ai-astar;104;104;104;104;105;92;108;105;105;104 16:38:06 INFO - PID 28074 | |1;audio-beat-detection;115;117;116;113;135;111;114;116;113;116 16:38:06 INFO - PID 28074 | |2;audio-dft;163;162;163;163;162;162;165;165;163;167 16:38:06 INFO - PID 28074 | |3;audio-fft;74;77;73;73;74;76;76;75;75;76 16:38:06 INFO - PID 28074 | |4;audio-oscillator;79;89;77;78;78;78;78;130;77;79 16:38:06 INFO - PID 28074 | |5;imaging-gaussian-blur;114;114;113;113;110;115;114;114;111;114 16:38:06 INFO - PID 28074 | |6;imaging-darkroom;149;149;150;151;149;149;150;151;150;150 16:38:06 INFO - PID 28074 | |7;imaging-desaturate;95;95;94;97;95;94;113;96;94;101 16:38:06 INFO - PID 28074 | |8;json-parse-financial;45;45;45;46;45;48;45;45;46;46 16:38:06 INFO - PID 28074 | |9;json-stringify-tinderbox;40;40;37;37;36;36;37;38;37;37 16:38:06 INFO - PID 28074 | |10;stanford-crypto-aes;70;71;73;71;71;70;71;70;71;71 16:38:06 INFO - PID 28074 | |11;stanford-crypto-ccm;123;108;110;110;108;110;105;108;114;101 16:38:06 INFO - PID 28074 | |12;stanford-crypto-pbkdf2;155;158;163;159;149;151;150;156;154;165 16:38:06 INFO - PID 28074 | |13;stanford-crypto-sha256-iterative;66;63;61;59;60;62;62;61;60;77 16:38:06 INFO - PID 28074 | __end_tp_report 16:38:06 INFO - PID 28074 | __start_cc_report 16:38:06 INFO - PID 28074 | _x_x_mozilla_cycle_collect,693 16:38:06 INFO - PID 28074 | __end_cc_report 16:38:06 INFO - PID 28074 | __startTimestamp1506296286105__endTimestamp 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | ------- Summary: start ------- 16:38:06 INFO - PID 28074 | Number of tests: 14 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#0] ai-astar Cycles:10 Average:103.50 Median:105.00 stddev:4.22 (4.0%) stddev-sans-first:4.48 16:38:06 INFO - PID 28074 | Values: 104.0 104.0 104.0 104.0 105.0 92.0 108.0 105.0 105.0 104.0 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#1] audio-beat-detection Cycles:10 Average:116.60 Median:116.00 stddev:6.72 (5.8%) stddev-sans-first:7.10 16:38:06 INFO - PID 28074 | Values: 115.0 117.0 116.0 113.0 135.0 111.0 114.0 116.0 113.0 116.0 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.00 stddev:1.65 (1.0%) stddev-sans-first:1.74 16:38:06 INFO - PID 28074 | Values: 163.0 162.0 163.0 163.0 162.0 162.0 165.0 165.0 163.0 167.0 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#3] audio-fft Cycles:10 Average:74.90 Median:75.50 stddev:1.37 (1.8%) stddev-sans-first:1.41 16:38:06 INFO - PID 28074 | Values: 74.0 77.0 73.0 73.0 74.0 76.0 76.0 75.0 75.0 76.0 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#4] audio-oscillator Cycles:10 Average:84.30 Median:78.00 stddev:16.44 (21.1%) stddev-sans-first:17.32 16:38:06 INFO - PID 28074 | Values: 79.0 89.0 77.0 78.0 78.0 78.0 78.0 130.0 77.0 79.0 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:114.00 stddev:1.55 (1.4%) stddev-sans-first:1.62 16:38:06 INFO - PID 28074 | Values: 114.0 114.0 113.0 113.0 110.0 115.0 114.0 114.0 111.0 114.0 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#6] imaging-darkroom Cycles:10 Average:149.80 Median:150.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 16:38:06 INFO - PID 28074 | Values: 149.0 149.0 150.0 151.0 149.0 149.0 150.0 151.0 150.0 150.0 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#7] imaging-desaturate Cycles:10 Average:97.40 Median:95.00 stddev:5.87 (6.2%) stddev-sans-first:6.16 16:38:06 INFO - PID 28074 | Values: 95.0 95.0 94.0 97.0 95.0 94.0 113.0 96.0 94.0 101.0 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#8] json-parse-financial Cycles:10 Average:45.60 Median:45.50 stddev:0.97 (2.1%) stddev-sans-first:1.00 16:38:06 INFO - PID 28074 | Values: 45.0 45.0 45.0 46.0 45.0 48.0 45.0 45.0 46.0 46.0 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#9] json-stringify-tinderbox Cycles:10 Average:37.50 Median:37.00 stddev:1.43 (3.9%) stddev-sans-first:1.20 16:38:06 INFO - PID 28074 | Values: 40.0 40.0 37.0 37.0 36.0 36.0 37.0 38.0 37.0 37.0 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#10] stanford-crypto-aes Cycles:10 Average:70.90 Median:71.00 stddev:0.88 (1.2%) stddev-sans-first:0.87 16:38:06 INFO - PID 28074 | Values: 70.0 71.0 73.0 71.0 71.0 70.0 71.0 70.0 71.0 71.0 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#11] stanford-crypto-ccm Cycles:10 Average:109.70 Median:110.00 stddev:5.79 (5.3%) stddev-sans-first:3.63 16:38:06 INFO - PID 28074 | Values: 123.0 108.0 110.0 110.0 108.0 110.0 105.0 108.0 114.0 101.0 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.00 Median:157.00 stddev:5.35 (3.4%) stddev-sans-first:5.67 16:38:06 INFO - PID 28074 | Values: 155.0 158.0 163.0 159.0 149.0 151.0 150.0 156.0 154.0 165.0 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:62.00 stddev:5.26 (8.5%) stddev-sans-first:5.47 16:38:06 INFO - PID 28074 | Values: 66.0 63.0 61.0 59.0 60.0 62.0 62.0 61.0 60.0 77.0 16:38:06 INFO - PID 28074 | -------- Summary: end -------- 16:38:06 INFO - PID 28074 | 16:38:06 INFO - PID 28074 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:38:06 INFO - TEST-INFO | 28074: exit 0 16:38:06 INFO - TEST-OK | kraken | took 59468ms 16:38:06 INFO - SUITE-END | took 1167s 16:38:06 INFO - Completed test suite (00:19:27) 16:38:06 INFO - javascript benchmark 16:38:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9028.408122242718, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3880.573136954935, 3893.0, 3897.590752970938, 3906.3359228931095, 3911.2568611963707, 6224.937750622493, 6238.313785483593, 6254.593451425654, 6314.431701146902, 6320.462760665343, 6341.112244285792, 6347.587406818554, 6367.331430199832, 6392.840178995528, 6394.904076438857, 5640.182173584828, 5695.601307908448, 5708.286464191966, 5756.222909907172, 5756.597038207327, 5711.086226203808, 5744.770209191648, 5791.10237913122, 5800.0, 5855.180274761537, 5546.084895992157, 5679.858003549903, 5751.0, 5808.303003639569, 5828.0, 5322.680639161663, 5683.460071293221, 5748.080307150836, 5769.884602307972, 5778.133280007991, 5651.15232715096, 5677.318721753416, 5738.167965645, 5748.051571490699, 5774.191613174159, 6198.132261483396, 6234.719437625317, 6258.0, 6265.592736472115, 6290.43386095249, 6857.211420686635, 6920.5847649141215, 6929.792106236821, 6940.444764418834, 6971.058907047585, 6904.240533541307, 6982.685779139951, 7013.053237812881, 7014.053102831103, 7028.297170282931, 6262.530310226751, 6382.106505089291, 6416.101745755598, 6472.385123413268, 6481.546291759579, 6920.377166055079, 7067.257937916547, 7069.575825450489, 7069.717211311593, 7083.82290442743, 6668.433183179484, 6671.39957403836, 6764.898526522106, 6842.692078856463, 6845.589264644137, 6905.516613837083, 6974.755883544051, 6997.090378250897, 6997.195322537871, 7002.4398048157045, 6827.2831352708245, 6869.141357330333, 6884.552504087318, 6930.93069069313, 6967.1291088613925, 6830.487713421513, 6924.203716572558, 6926.826829329207, 6932.306769323028, 6932.549384290005, 5035.647504674637, 5079.4412614612365, 5082.949170508322, 5095.617828662865, 5107.438181799999, 4550.88622784433, 4624.745638989888, 4639.443266807937, 4640.048789998043, 4642.72143671374, 6240.0, 6323.367663233732, 6332.271788744261, 6411.743530258831, 6412.422881940648, 6865.244823069458, 6878.105846240057, 6885.414739747077, 6888.724451022003, 6890.758823441155, 6297.275813281532, 6300.716467758962, 6313.2424109107205, 6314.052892066226, 6384.52116091295, 5546.69493177871, 5550.139728342114, 5608.186812912105, 5610.1584762285165, 5622.522085622768, 6943.444524438033, 7112.182099058675, 7124.46566507514, 7127.857442851114, 7165.498415110892, 6702.430293425114, 6721.428678562377, 6790.83022924411, 6819.4544436443975, 6865.725370985305, 7012.298770122947, 7098.467614928798, 7122.537035092702, 7123.394511466583, 7133.928660713326, 6766.729330826712, 6837.179538455417, 6841.828954276183, 6861.622610756456, 6866.588004719732, 6880.552764070319, 6880.827979300558, 6882.483813714091, 6979.40675042627, 6984.895226571504, 6550.574212676161, 6633.137692100189, 6639.402453779183, 6676.198856137295, 6698.196216454056, 6602.801915942529, 6719.26088130315, 6754.088198093391, 6759.053732477555, 6761.357671021246, 6561.40947314744, 6592.307807680317, 6593.769218077345, 6686.632235226914, 6688.866222675617, 6544.411003009752, 6676.666166691549, 6731.124953755981, 6746.69639866217, 6784.559003664746, 6418.972964325685, 6483.286838447672, 6553.868922621617, 6554.770583029571, 6559.114519539801], "value": 6284.02027402339, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [939.7697564096798, 960.3757557587567, 961.653804630333, 962.1388856973014, 964.14191369681, 41349.0, 45813.0, 45890.54109458905, 45943.54056459435, 45988.31017534732, 42554.36168457475, 42575.0, 42766.78616606921, 42827.571724282745, 43099.569004309946, 7624.08510978682, 7757.5345479271145, 7804.531728096304, 7812.414068944823, 7829.17793631669, 7589.658465369044, 7622.199669034755, 7654.578998155099, 7668.07983042033, 7683.308502234798, 7588.241175882423, 7694.615269236544, 7731.22687731228, 8186.181381861796, 8265.752027439157, 8232.917670823275, 8297.377696672773, 8309.37679674027, 8359.707410240613, 8364.414490985633, 8075.838483230361, 8332.62503187358, 8367.330613550963, 8400.117987611275, 8408.453450525696, 40912.0, 40944.795276023426, 40975.7951210245, 41056.589434105874, 41460.792696036326, 3663.578688450809, 3747.681447077002, 3753.1555400035045, 3768.2840260350445, 3770.6794922431627, 33677.6632233677, 33715.49426758601, 33830.323393531995, 33872.49191262133, 33900.32199355999, 5097.8215762448135, 5125.1287281162295, 5132.820351287687, 5217.2695822584865, 5221.582273418117, 234181.0, 234362.0, 234689.0, 234966.0, 235370.0, 46064.309035364495, 46420.535794642296, 49652.75173624109, 50676.493235067916, 50900.0, 53395.466045339825, 53872.46127538675, 53909.92180156453, 53912.73043634835, 53946.0, 16666.33334666624, 16897.83102168987, 16898.662026759397, 16911.661766764842, 16916.830831691525, 13766.65583360424, 13768.311584420939, 13878.306084695927, 13882.514112006233, 13902.652433689238, 14422.0, 14451.277436128152, 14512.564623061326, 14589.416423342947, 14601.634959126106, 5708.71456427177, 5789.9710501447225, 5813.912791308134, 5824.534037277008, 5872.559558033165, 1084.2897901874285, 1084.6854412220525, 1089.1123734156638, 1096.8464414981909, 1099.1316859680762, 25394.492110157695, 25473.490530189294, 25642.358941026625, 25738.48523029529, 25775.355616109748, 1181.8772166441915, 1191.9940400298117, 1199.424276347358, 1207.275634619239, 1209.6492017315056, 13076.280804555841, 13395.196288222543, 13700.0, 13774.517891873937, 13831.930840345734, 266949.0, 267606.0, 267877.0, 268306.0, 268589.0, 56612.43387566154, 56808.0, 56891.715541422025, 57210.42789572134, 57226.141607875914, 1499.7450433426343, 1521.2089713349217, 1529.250667173077, 1539.6074001129641, 1557.2603013568691, 24976.875115624305, 25191.49617007686, 25387.873060634578, 25408.237752867444, 25456.0, 40421.59578404178, 40448.3932741015, 40465.39301910415, 40503.0, 40582.0, 241416.0, 242102.0, 243797.0, 244336.0, 244695.0, 32556.674433255364, 32850.1787455306, 33068.1732956678, 34835.65164348324, 35131.0, 46614.53385466102, 46810.76594617005, 46939.530604693515, 47065.52934470611, 47085.29372059353, 34390.48414273838, 34690.0, 34828.78099266513, 35181.0, 35585.288294233455, 34448.0, 34767.82616086904, 34908.82545587256, 35064.47403288902, 35243.0, 40662.39006414847, 40720.79639601783, 40798.796006020966, 41216.1756764869, 41236.175276493705, 33507.162320942174, 33706.0, 33824.66175338215, 33875.15312117217, 34537.65462345345, 25729.614055789552, 26010.609840852023, 26117.869410652824, 26412.0, 26500.337491562866], "value": 18423.04949450878, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.5903197968784, 599.7061439894447, 605.74255941225, 636.4113195294353, 640.2700920950116, 22969.19607813727, 24006.039758409686, 24094.87952560237, 24235.878820605893, 24267.514649706995, 22773.4306642334, 23495.882520587416, 23684.763152368472, 23747.168849090285, 23755.881220593874, 6604.141461609984, 6757.459403247753, 6764.053032575419, 6769.796906092825, 6771.289014653465, 6620.900686489683, 6874.518783685144, 6895.0, 6900.068490753759, 6905.378515933565, 6872.553284036547, 6874.48441366897, 6881.13985751781, 6881.862362752742, 6883.139607549057, 6614.305497922697, 6858.897116543231, 6867.0, 6876.415504682108, 6879.3120687931305, 6319.715612797389, 6396.552241343108, 6416.262129855079, 6422.357764223587, 6427.132337134473, 30674.233144171576, 30767.384652306828, 30843.0, 30933.535996960065, 31041.0, 2238.3173132194675, 2265.728112626479, 2266.240809328877, 2272.1706577099403, 2275.9089636414524, 20074.498137546532, 21601.567968640695, 21716.0, 21717.565648686937, 21724.674129888066, 2423.6243382275775, 2430.8663023533877, 2448.5592593333195, 2509.5608268553115, 2624.5669464538328, 153678.0, 159054.0, 159137.0, 159142.6128608072, 159167.99999999884, 16427.671446571003, 16696.916515417346, 16762.32950681959, 16781.328746850235, 16858.662826743395, 26241.868790655924, 26242.606360904603, 26277.605835912476, 26302.736972630413, 26370.868145659148, 9921.80156396868, 10025.899741002642, 10068.647597334204, 10105.292629515863, 10233.590656373663, 8697.173768491983, 8833.33749968755, 8850.468971861581, 8863.822723545622, 8875.689350872817, 9893.45585992763, 10147.543360548792, 10153.847692284622, 10157.9492102539, 10205.336653117673, 2932.1057077591113, 2943.8813250964495, 2965.530329514473, 2982.164993801739, 2983.701629837042, 1000.4847503535685, 1016.4307987526861, 1017.5115944346755, 1020.5764607687727, 1024.7386916336286, 20799.68800467994, 20997.47506312324, 21274.36176914695, 21477.67783483249, 21510.677339839916, 1062.1449732965036, 1064.3347907557777, 1077.665923563682, 1091.1380009792333, 1091.7598128411737, 10810.729731756768, 11624.709382265342, 11673.182877198515, 11737.941310293394, 11754.471048802825, 593087.0, 593506.0, 596208.0, 617136.0, 618956.0, 133552.0, 134244.0, 137026.0, 137208.0, 137616.31191843978, 1201.1891972918245, 1208.6374087773809, 1242.4471110355805, 1246.6135497995651, 1248.875112488762, 18984.905075474533, 19114.23543058262, 19164.233430662618, 19282.7107593389, 19638.018099095272, 64172.358276416635, 65568.67215663892, 65760.67119664371, 65900.0, 66505.66747166427, 84871.15128848632, 84937.0, 85226.57386713027, 85272.0, 85350.57324713336, 15506.069635821887, 15729.44946926852, 15869.603259918595, 15909.204539773196, 15948.521544353687, 23792.167274145322, 24094.87952560226, 24166.87916560406, 24216.152434664702, 24520.632190517514, 18074.915505069737, 19588.90205548963, 19779.109940052444, 19937.900310498353, 20005.0, 16809.495715128567, 16913.746293805358, 17054.82945170582, 17459.47621571355, 18418.539536511693, 18400.355987540373, 19763.308284209983, 19874.801251987872, 20028.898410586975, 20346.89826550858, 15329.6167595811, 15667.0, 15739.606509836887, 15759.763603546186, 15803.920980395025, 19184.0, 19337.12982915744, 19407.320743774464, 19649.70525442091, 19656.410307690792], "value": 12806.528739902953, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.4879043544324, 1470.1913947328974, 1476.3208923895008, 1493.1936754152757, 1497.5956491747224, 16950.406735764234, 17654.38209662662, 17658.293668253253, 17675.55811104723, 17687.557811054714, 17062.914685426556, 17086.31654733809, 17125.657486850287, 17212.225449854755, 17258.395956141536, 2823.3929705113396, 2848.8005839591183, 2922.620059392276, 2932.9706702933017, 2939.5884576159247, 2853.115534184399, 2878.582605522198, 2895.5222388305906, 2897.333613268949, 2898.4348052129844, 2607.739226077396, 2831.3063299491655, 2831.348789778352, 2831.7451429371354, 2884.6682631497333, 2884.5817356483194, 2921.35730139369, 2944.6319210098736, 2948.7935844490894, 2949.734523892849, 2676.0, 2777.3056735816044, 2804.284907348613, 2840.8863645454157, 2849.2734352739917, 16557.50982411589, 16728.24722887473, 16728.916355418267, 16761.245743941552, 16761.580960475963, 8562.48625082497, 8819.51492667903, 8831.470111793313, 8871.24594409476, 8927.151920567536, 5505.752241149158, 5798.536117110663, 5853.560982926298, 5879.3826648202175, 5917.142014407887, 2061.5155438471947, 2068.7827778083233, 2112.0706888968803, 2148.8603240789457, 2159.9892000540053, 31304.37391252185, 31458.37083258345, 31620.209494762585, 31688.841555792074, 31731.20671983219, 7452.403807695479, 7452.552846829208, 7481.401487880957, 7584.469087163846, 7716.498427602188, 20009.09959051846, 20015.0, 20035.49911252201, 20047.699284510745, 20058.0, 8465.0, 8598.0, 8632.093630168742, 8643.222110010003, 8651.091635378194, 7512.47412681118, 7846.411519136088, 7881.487703299267, 7927.603619818986, 8068.9596552016865, 5733.3406658234, 5737.225474560964, 5758.1362795581, 5789.0, 5861.88276234473, 1664.9167541622871, 1703.0, 1709.358990378608, 1711.9572010699833, 1712.2722842791763, 962.37926537383, 969.132626299458, 976.0138118928089, 995.8456439251928, 1003.1222680154865, 8294.751157465285, 8340.0, 8418.663253469793, 8441.113683063313, 8475.449095808754, 764.0831002796666, 768.1703759939252, 810.2586133687728, 824.2828738996991, 829.344817594106, 5304.363476382859, 5337.359516857924, 5905.557083218776, 6032.909506357408, 6140.263168419729, 25133.748662513506, 25712.357191070372, 25762.227133186036, 25780.355491112874, 26051.478970420485, 24416.63375049376, 25198.24405267845, 25807.74192258091, 25839.74160258411, 25861.74138258631, 12012.219205751599, 12069.939650301692, 12137.089718271165, 12376.0, 12625.116241863165, 9153.149199922243, 9351.766205844637, 9476.194523465307, 9500.714978550655, 9569.186619137452, 20895.68656470124, 20936.68594971107, 21250.89374553117, 21289.0, 21525.46186345354, 21826.672599911333, 22153.335399938027, 22170.334889953327, 22261.332160035225, 22319.553608928054, 12420.0, 12494.812577811523, 12519.0, 12620.43208055658, 12663.93668031654, 15610.141442220332, 15993.520194394187, 16014.519564413085, 16015.039097654178, 16025.198740063184, 11838.763224735629, 12397.070219733376, 12426.254424734545, 12475.376231188222, 12493.625191244277, 12511.186772859735, 12546.749065018466, 12642.936785316015, 12793.42429590652, 12901.225926444444, 10995.780084398428, 11154.051905588116, 11158.776824463628, 11166.274192177483, 11199.328040317607, 8404.831903362021, 8523.872141918, 8546.35902307317, 8563.143685631387, 8585.270252028398, 8316.501009939422, 8335.95832020836, 8374.0, 8386.287165590993, 8449.0], "value": 7357.369147930816, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [531.3464438740351, 545.9344878614564, 570.873496226556, 588.2529187931327, 593.2791658135368, 6074.210352654154, 6095.7256923438445, 6152.200213972183, 6312.905306420407, 6405.647689377082, 6166.414190651892, 6168.9383106168925, 6213.906791398133, 6237.750489980395, 6260.7808726694575, 5144.305518754958, 5146.202338637517, 5146.536811686948, 5166.793328266865, 5196.58427325813, 4903.548225887056, 4952.579030782388, 4986.227134794112, 5001.774920128585, 5089.974550127245, 4969.8509044728535, 4979.950200498003, 4982.427020892589, 5010.3737032870895, 5022.623303252252, 4947.6784009039475, 4962.280469331945, 4976.477469865648, 4985.626078044161, 4996.500349964975, 4530.0, 4567.406237189178, 4588.564086411786, 4597.908041839144, 4626.21354369758, 6197.349278325801, 6211.627302361828, 6250.499960003189, 6252.749890004395, 6273.65494897785, 5852.12218167274, 5863.560232982543, 5868.735906884158, 5878.177055212231, 5899.469047785677, 6583.440407565363, 6673.165854268216, 6687.230968438643, 6705.899411508832, 6719.227288861745, 5040.7731652075745, 5041.747912604392, 5069.340985671839, 5084.593232541388, 5086.694798312114, 6855.314468553154, 6870.62211578363, 6879.5184337096425, 6893.620850853201, 6915.654217289115, 5123.410807757155, 5216.1393370094265, 5244.868878278074, 5246.527812496894, 5269.340157850265, 5315.73421328932, 5316.627836051439, 5319.255304257407, 5344.198370244417, 5356.758945847446, 5857.209276747584, 5914.822555323347, 5915.3788852170765, 5931.525477961753, 5937.376575459601, 6371.0, 6469.805905822833, 6617.900731489031, 6623.205215374186, 6626.668666566652, 1726.5510967148632, 1749.9850086949539, 1804.5849454625509, 1807.2951548895942, 1811.1668632428966, 1280.3918138883955, 1285.4665313894816, 1287.9806802897965, 1296.9286689231994, 1306.9019823513274, 6099.176611157525, 6101.237345331833, 6172.321044685081, 6184.907226391608, 6194.969025154845, 6585.2426970898005, 6661.566998145105, 6735.663216839139, 6760.966195168992, 6814.931850681529, 6165.753369865155, 6187.133801267826, 6202.906956395657, 6222.533310001678, 6269.373062693785, 3518.683318501347, 3529.8588056477965, 3530.982345088258, 3586.421974331286, 3587.497750314958, 6999.8600027999155, 7048.801703710382, 7088.291170882871, 7093.787186384417, 7105.43156547475, 6689.832754181184, 6802.319768023257, 6904.689288982008, 6918.584884906922, 6942.166939967236, 6889.483288753364, 6949.548279361826, 7019.262977387403, 7036.6129862857015, 7089.255628158969, 6698.933010669831, 6708.19501659804, 6714.294999025227, 6742.460208390438, 6745.8194815908, 6607.91190783104, 6616.735330586723, 6697.732090716317, 6711.409395973131, 6784.525083244125, 6914.720776656334, 7032.367086962198, 7054.576725396492, 7078.539894906815, 7080.468964827762, 5113.846584602468, 5133.383993920754, 5153.1754919214245, 5161.458046905021, 5167.534921857051, 6455.741770329134, 6526.282108967915, 6739.865202695821, 6773.830654233683, 6791.166385076101, 6298.716557755004, 6499.1876015498065, 6545.21457425112, 6559.934400655932, 6587.341265873374, 6436.0, 6456.096146539582, 6489.9351006491215, 6568.901466477911, 6601.834954126185, 6321.1782468278825, 6379.298277189599, 6404.711787969646, 6416.51876109303, 6490.221173459215, 6582.407583317333, 6597.868042639025, 6615.4046135848, 6631.767888123893, 6652.201735791736, 6281.905771413341, 6309.2744334400295, 6423.582467139808, 6441.194850643669, 6468.579542329733], "value": 5178.670454393436, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [945.1635302757062, 957.3059531839417, 964.482700152602, 966.3909590531765, 981.6956743409544, 18679.71980420293, 19378.90310548447, 21876.343709688714, 22696.319110426713, 22703.318900432972, 20510.589788204263, 20663.793362066375, 20828.271010514636, 20960.0, 21999.780002199972, 1055.4406164732684, 1088.0588291128156, 1088.3524303039678, 1088.4448931045185, 1088.5700148441401, 1028.090140225898, 1059.7668512927144, 1063.191974099686, 1072.5334479501403, 1075.7740874416381, 1108.362691452414, 1119.4067144413473, 1121.2151493954225, 1122.4892673833426, 1122.5060973171778, 1113.7104352868273, 1120.3445984099312, 1122.9494672739706, 1123.1239633086207, 1124.3703526025452, 819.5574389829485, 820.5527987246905, 823.7611092783145, 826.0046643794274, 827.148037521354, 20107.39677809668, 20233.39299820993, 20334.898325508424, 20506.897465512728, 20879.0, 20771.79228207714, 20857.478563035893, 21230.575388492143, 21249.68125478119, 21310.14759409622, 18669.906650466797, 18688.159032843418, 18689.34587289452, 18739.34412295563, 18779.342723004767, 3825.2731980923536, 3897.0, 3949.249642567903, 3967.4842270504937, 3976.1252524444576, 158921.0, 161638.19180904137, 161982.0, 163228.0, 163911.9999999988, 16976.915115424592, 17143.65712685764, 17308.826911730972, 17370.0, 17375.913120434318, 28105.0, 28255.71744282572, 28265.57601635977, 30145.09564713062, 30158.84920575383, 8989.820203596022, 9130.132637399569, 9133.451992880315, 9134.223590994707, 9187.724368268962, 4473.485549161888, 4505.391772110723, 4537.115262523776, 4564.0, 4565.178267911741, 5693.259876216065, 5792.4497172768515, 5799.101139323411, 5809.970950145223, 5812.505936995402, 1038.6157121864958, 1043.9164866810636, 1057.7038429239672, 1060.0777323728405, 1063.7819247054335, 676.7935779587175, 696.2932623387265, 698.5180225644289, 707.1726080485844, 713.4078714666814, 12144.392780360946, 12283.815742763865, 12532.81200781989, 12858.9357053216, 13050.869491305155, 679.8844196486609, 695.3776370148789, 697.10770214126, 702.0942983551162, 710.2648758534891, 5710.229119068956, 5711.228984087095, 5827.825165245049, 5862.941370586324, 5912.379200183918, 147741.0, 148292.0, 150859.24570377078, 152823.0, 155592.0, 129610.0, 131675.0, 133050.33474832564, 133433.0, 136888.0, 114597.0, 116043.0, 117530.74848314254, 119945.0, 120400.39799800944, 11047.779044418905, 11241.325520468798, 11331.433428328715, 11372.260803047775, 11423.97184253388, 56156.71921640365, 56469.0, 57484.0, 57628.13557796553, 58495.0, 74958.25041749731, 76930.61534692514, 78468.0, 78641.0, 79121.60439197767, 15933.283002264694, 16595.585110372336, 16663.583410414838, 16839.41062062822, 16973.405930792364, 19377.709334359715, 19446.902765486648, 19549.218031279157, 19664.705029424855, 19689.606207876048, 14858.925705371405, 15063.171525566198, 15268.694626107637, 15322.310496027483, 15381.923090384476, 15602.0, 15612.141332226382, 15698.215089245263, 15837.683246335238, 15865.68268634644, 16496.587585309982, 16626.75059874127, 16685.74971375406, 16717.49847504577, 16756.32974680999, 11177.944110279397, 11194.888051119384, 11340.829887551528, 11364.261323013978, 11613.129015323715, 10549.0, 10790.1367890567, 10849.403282819521, 11005.229633925868, 11236.83144752813], "value": 9586.709898921292, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1386.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 104.0, 104.0, 104.0, 105.0, 92.0, 108.0, 105.0, 105.0, 104.0], "value": 103.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 116.0, 113.0, 135.0, 111.0, 114.0, 116.0, 113.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 163.0, 162.0, 162.0, 165.0, 165.0, 163.0, 167.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 73.0, 73.0, 74.0, 76.0, 76.0, 75.0, 75.0, 76.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 89.0, 77.0, 78.0, 78.0, 78.0, 78.0, 130.0, 77.0, 79.0], "value": 84.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 113.0, 110.0, 115.0, 114.0, 114.0, 111.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 151.0, 149.0, 149.0, 150.0, 151.0, 150.0, 150.0], "value": 149.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 97.0, 95.0, 94.0, 113.0, 96.0, 94.0, 101.0], "value": 97.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 45.0, 45.0, 46.0, 45.0, 48.0, 45.0, 45.0, 46.0, 46.0], "value": 45.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 40.0, 37.0, 37.0, 36.0, 36.0, 37.0, 38.0, 37.0, 37.0], "value": 37.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 73.0, 71.0, 71.0, 70.0, 71.0, 70.0, 71.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 108.0, 110.0, 110.0, 108.0, 110.0, 105.0, 108.0, 114.0, 101.0], "value": 109.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 158.0, 163.0, 159.0, 149.0, 151.0, 150.0, 156.0, 154.0, 165.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 61.0, 59.0, 60.0, 62.0, 62.0, 61.0, 60.0, 77.0], "value": 63.1, "unit": "score"}]}]} 16:38:06 INFO - Return code: 0 16:38:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:38:06 INFO - # TBPL SUCCESS # 16:38:06 INFO - Running post-action listener: _package_coverage_data 16:38:06 INFO - Running post-action listener: _resource_record_post_action 16:38:06 INFO - [mozharness: 2017-09-24 23:38:06.870481Z] Finished run-tests step (success) 16:38:06 INFO - Running post-run listener: _resource_record_post_run 16:38:06 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 8192; Write bytes: 330493952; Read time: 208; Write time: 187188 16:38:06 INFO - TinderboxPrint: CPU usage
13.1% 16:38:06 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 208 16:38:06 INFO - TinderboxPrint: I/O write bytes / time
330,493,952 / 187,188 16:38:06 INFO - TinderboxPrint: CPU idle
8,219.5 (86.9%) 16:38:06 INFO - TinderboxPrint: CPU user
1,188.1 (12.6%) 16:38:06 INFO - TinderboxPrint: Swap in / out
0 / 0 16:38:06 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12746752; Read time: 0; Write time: 2052 16:38:06 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:38:06 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 8192; Write bytes: 317747200; Read time: 208; Write time: 185136 16:38:07 INFO - Running post-run listener: _upload_blobber_files 16:38:07 INFO - Blob upload gear active. 16:38:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:38:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:38:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:38:07 INFO - (blobuploader) - INFO - Open directory for files ... 16:38:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:38:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:38:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:08 INFO - (blobuploader) - INFO - Done attempting. 16:38:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:38:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:38:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:09 INFO - (blobuploader) - INFO - Done attempting. 16:38:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:38:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:38:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:16 INFO - (blobuploader) - INFO - Done attempting. 16:38:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:38:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:38:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:17 INFO - (blobuploader) - INFO - Done attempting. 16:38:17 INFO - (blobuploader) - INFO - Iteration through files over. 16:38:17 INFO - Return code: 0 16:38:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:38:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:38:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04c7a1f9d41d40a2d87d4419dfd2af977a4c0f8570b12717352b82d49ff797cf43ccd3a65b783f52f39fd42719c2872c4be155e262f1739838a635f8d9a4d28d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0da35b8a352b168a7e1f6bb084bd53f1412087f3996ada7d222c399b20a77b3931dd3a1fa9151f19155d81df9b2b66c22f8aa4e5f2dff6785a0ceda95f572041", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b41ed39dcf475e3e822a7efaf3026301a93b255078985d80fed760d5bf7d8abc270fecec419982ece7d90c1fbb2a22bd0010589b0d99e89ce18eaab748f8a7d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e0ee5ca0dad273452253f13aa83b1f121c3bc5896f06d8af61e25ed231b6ed37314e1d623479b44124afe9c15c301f48d2552c47864bf809c65107e985f0f15"} 16:38:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:38:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:38:17 INFO - Contents: 16:38:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04c7a1f9d41d40a2d87d4419dfd2af977a4c0f8570b12717352b82d49ff797cf43ccd3a65b783f52f39fd42719c2872c4be155e262f1739838a635f8d9a4d28d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0da35b8a352b168a7e1f6bb084bd53f1412087f3996ada7d222c399b20a77b3931dd3a1fa9151f19155d81df9b2b66c22f8aa4e5f2dff6785a0ceda95f572041", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b41ed39dcf475e3e822a7efaf3026301a93b255078985d80fed760d5bf7d8abc270fecec419982ece7d90c1fbb2a22bd0010589b0d99e89ce18eaab748f8a7d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e0ee5ca0dad273452253f13aa83b1f121c3bc5896f06d8af61e25ed231b6ed37314e1d623479b44124afe9c15c301f48d2552c47864bf809c65107e985f0f15"} 16:38:17 INFO - Running post-run listener: copy_logs_to_upload_dir 16:38:17 INFO - Copying logs to upload dir... 16:38:17 INFO - mkdir: /builds/slave/test/build/upload/logs 16:38:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.420894 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-24 16:38:17.841023) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-24 16:38:17.848934) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04c7a1f9d41d40a2d87d4419dfd2af977a4c0f8570b12717352b82d49ff797cf43ccd3a65b783f52f39fd42719c2872c4be155e262f1739838a635f8d9a4d28d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0da35b8a352b168a7e1f6bb084bd53f1412087f3996ada7d222c399b20a77b3931dd3a1fa9151f19155d81df9b2b66c22f8aa4e5f2dff6785a0ceda95f572041", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b41ed39dcf475e3e822a7efaf3026301a93b255078985d80fed760d5bf7d8abc270fecec419982ece7d90c1fbb2a22bd0010589b0d99e89ce18eaab748f8a7d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e0ee5ca0dad273452253f13aa83b1f121c3bc5896f06d8af61e25ed231b6ed37314e1d623479b44124afe9c15c301f48d2552c47864bf809c65107e985f0f15"} build_url:https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011293 build_url: 'https://queue.taskcluster.net/v1/task/ep1GVcu_QMWkzKOeJ-WQbg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04c7a1f9d41d40a2d87d4419dfd2af977a4c0f8570b12717352b82d49ff797cf43ccd3a65b783f52f39fd42719c2872c4be155e262f1739838a635f8d9a4d28d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0da35b8a352b168a7e1f6bb084bd53f1412087f3996ada7d222c399b20a77b3931dd3a1fa9151f19155d81df9b2b66c22f8aa4e5f2dff6785a0ceda95f572041", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b41ed39dcf475e3e822a7efaf3026301a93b255078985d80fed760d5bf7d8abc270fecec419982ece7d90c1fbb2a22bd0010589b0d99e89ce18eaab748f8a7d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e0ee5ca0dad273452253f13aa83b1f121c3bc5896f06d8af61e25ed231b6ed37314e1d623479b44124afe9c15c301f48d2552c47864bf809c65107e985f0f15"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-24 16:38:17.882256) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:38:17.882621) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1506294958.455640-990550179 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004518 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:38:17.968592) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-24 16:38:17.968896) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-24 16:38:17.969205) ========= ========= Total master_lag: 0.22 =========