builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-088 starttime: 1505935705.26 results: success (0) revision: 469eb992a9d166004f2601ce725786f671219054 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 12:28:25.257026) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 12:28:25.257586) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 12:28:25.257882) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 12:28:25.378244) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 12:28:25.379829) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-20 12:28:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.3M=0s 2017-09-20 12:28:25 (43.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124168 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 12:28:25.552337) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 12:28:25.552683) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015922 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 12:28:25.653593) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-20 12:28:25.654009) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 469eb992a9d166004f2601ce725786f671219054 --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 469eb992a9d166004f2601ce725786f671219054 --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=9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-20 12:28:25,530 truncating revision to first 12 chars 2017-09-20 12:28:25,530 Setting DEBUG logging. 2017-09-20 12:28:25,530 attempt 1/10 2017-09-20 12:28:25,530 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/469eb992a9d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-20 12:28:26,418 unpacking tar archive at: mozilla-central-469eb992a9d1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.197752 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-20 12:28:26.885463) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 12:28:26.885845) ========= script_repo_revision: 469eb992a9d166004f2601ce725786f671219054 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 12:28:26.886355) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 12:28:26.886625) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 12:28:26.900652) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-20 12:28:26.901063) ========= /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=9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:28:26 INFO - MultiFileLogger online at 20170920 12:28:26 in /builds/slave/test 12:28:26 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 12:28:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:28:26 INFO - {'append_to_log': False, 12:28:26 INFO - 'base_work_dir': '/builds/slave/test', 12:28:26 INFO - 'blob_upload_branch': 'Firefox', 12:28:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:28:26 INFO - 'branch': 'Firefox', 12:28:26 INFO - 'buildbot_json_path': 'buildprops.json', 12:28:26 INFO - 'code_coverage': False, 12:28:26 INFO - 'config_files': ('talos/linux_config.py',), 12:28:26 INFO - 'default_actions': ('clobber', 12:28:26 INFO - 'read-buildbot-config', 12:28:26 INFO - 'download-and-extract', 12:28:26 INFO - 'populate-webroot', 12:28:26 INFO - 'create-virtualenv', 12:28:26 INFO - 'install', 12:28:26 INFO - 'setup-mitmproxy', 12:28:26 INFO - 'run-tests'), 12:28:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:28:26 INFO - 'disable_ccov_upload': False, 12:28:26 INFO - 'disable_stylo': False, 12:28:26 INFO - 'download_minidump_stackwalk': True, 12:28:26 INFO - 'download_symbols': 'ondemand', 12:28:26 INFO - 'enable_stylo': False, 12:28:26 INFO - 'enable_webrender': False, 12:28:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:28:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:28:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:28:26 INFO - 'gecko_profile': False, 12:28:26 INFO - 'gecko_profile_interval': 0, 12:28:26 INFO - 'installer_path': 'installer.exe', 12:28:26 INFO - 'log_level': 'info', 12:28:26 INFO - 'log_name': 'talos', 12:28:26 INFO - 'log_to_console': True, 12:28:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:28:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:28:26 INFO - 'opt_config_files': (), 12:28:26 INFO - 'pip_index': False, 12:28:26 INFO - 'suite': 'dromaeojs-e10s', 12:28:26 INFO - 'system_bits': '32', 12:28:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:28:26 INFO - 'title': 'talos-linux64-ix-088', 12:28:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:28:26 INFO - 'use_talos_json': True, 12:28:26 INFO - 'verify': 'False', 12:28:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:28:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:28:26 INFO - 'work_dir': 'build'} 12:28:26 INFO - [mozharness: 2017-09-20 19:28:26.905803Z] Running clobber step. 12:28:26 INFO - Running pre-action listener: _resource_record_pre_action 12:28:26 INFO - Running main action method: clobber 12:28:26 INFO - rmtree: /builds/slave/test/build 12:28:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:28:27 INFO - Running post-action listener: _resource_record_post_action 12:28:27 INFO - [mozharness: 2017-09-20 19:28:27.920454Z] Finished clobber step (success) 12:28:27 INFO - [mozharness: 2017-09-20 19:28:27.920551Z] Running read-buildbot-config step. 12:28:27 INFO - Running pre-action listener: _resource_record_pre_action 12:28:27 INFO - Running main action method: read_buildbot_config 12:28:27 INFO - Using buildbot properties: 12:28:27 INFO - { 12:28:27 INFO - "project": "", 12:28:27 INFO - "product": "firefox", 12:28:27 INFO - "who": "ryanvm@gmail.com", 12:28:27 INFO - "installer_path": "public/build/target.tar.bz2", 12:28:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:28:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:28:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 12:28:27 INFO - "stage_platform": "linux64-pgo", 12:28:27 INFO - "basedir": "/builds/slave/test", 12:28:27 INFO - "buildnumber": 186, 12:28:27 INFO - "platform": "ubuntu64_hw", 12:28:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:28:27 INFO - "slavebuilddir": "test", 12:28:27 INFO - "taskId": "SvFiXjD7ScKx2edZdCtErg", 12:28:27 INFO - "branch": "mozilla-central", 12:28:27 INFO - "script_repo_revision": "production", 12:28:27 INFO - "revision": "469eb992a9d166004f2601ce725786f671219054", 12:28:27 INFO - "slavename": "talos-linux64-ix-088", 12:28:27 INFO - "repo_path": "mozilla-central" 12:28:27 INFO - } 12:28:27 INFO - Finding installer, test and symbols from parent task. 12:28:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SvFiXjD7ScKx2edZdCtErg'}, attempt #1 12:28:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SvFiXjD7ScKx2edZdCtErg'}, attempt #1 12:28:28 INFO - Task dependencies: VRcOoJ32QBqWk7yTyN305Q 12:28:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q'}, attempt #1 12:28:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.tar.bz2 12:28:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.test_packages.json 12:28:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.crashreporter-symbols.zip 12:28:29 INFO - Running post-action listener: _resource_record_post_action 12:28:29 INFO - [mozharness: 2017-09-20 19:28:29.294970Z] Finished read-buildbot-config step (success) 12:28:29 INFO - [mozharness: 2017-09-20 19:28:29.295187Z] Running download-and-extract step. 12:28:29 INFO - Running pre-action listener: _resource_record_pre_action 12:28:29 INFO - Running main action method: download_and_extract 12:28:29 INFO - mkdir: /builds/slave/test/build/tests 12:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:29 INFO - trying https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.test_packages.json 12:28:29 INFO - Downloading https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:28:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:28:29 INFO - Downloaded 989 bytes. 12:28:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:28:29 INFO - Using the following test package requirements: 12:28:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:28:29 INFO - u'common': [u'target.common.tests.zip'], 12:28:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:28:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:28:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:28:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:28:29 INFO - u'mozbase': [u'target.common.tests.zip'], 12:28:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:28:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:28:29 INFO - u'web-platform': [u'target.common.tests.zip', 12:28:29 INFO - u'target.web-platform.tests.tar.gz'], 12:28:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:28:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:28:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.common.tests.zip 12:28:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:28:29 INFO - Fetch https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.common.tests.zip into memory 12:28:32 INFO - Content-Length response header: 55282580 12:28:32 INFO - Bytes received: 55282580 12:28:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.talos.tests.zip 12:28:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:28:42 INFO - Fetch https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.talos.tests.zip into memory 12:28:44 INFO - Content-Length response header: 14508893 12:28:44 INFO - Bytes received: 14508893 12:28:44 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')]} 12:28:44 INFO - trying https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.tar.bz2 12:28:44 INFO - Downloading https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:28:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:28:47 INFO - Downloaded 62357426 bytes. 12:28:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.tar.bz2 12:28:47 INFO - mkdir: /builds/slave/test/properties 12:28:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:28:47 INFO - Writing to file /builds/slave/test/properties/build_url 12:28:47 INFO - Contents: 12:28:47 INFO - build_url:https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.tar.bz2 12:28:47 INFO - Running post-action listener: _resource_record_post_action 12:28:47 INFO - Running post-action listener: find_tests_for_verification 12:28:47 INFO - Running post-action listener: set_extra_try_arguments 12:28:47 INFO - [mozharness: 2017-09-20 19:28:47.421994Z] Finished download-and-extract step (success) 12:28:47 INFO - [mozharness: 2017-09-20 19:28:47.422178Z] Running populate-webroot step. 12:28:47 INFO - Running pre-action listener: _resource_record_pre_action 12:28:47 INFO - Running main action method: populate_webroot 12:28:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:28:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:28:47 INFO - 'tests': ['tresize', 12:28:47 INFO - 'tcanvasmark']}, 12:28:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:28:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:28:47 INFO - 'tests': ['dromaeo_css', 12:28:47 INFO - 'kraken']}, 12:28:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:28:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:28:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:28:47 INFO - 'talos_options': ['--disable-stylo'], 12:28:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:28:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:28:47 INFO - 'tests': ['damp', 'tps']}, 12:28:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:28:47 INFO - 'talos_options': ['--disable-stylo'], 12:28:47 INFO - 'tests': ['damp', 'tps']}, 12:28:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:28:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:28:47 INFO - 'tests': ['dromaeo_dom']}, 12:28:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:28:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:28:47 INFO - 'tests': ['basic_compositor_video', 12:28:47 INFO - 'glvideo']}, 12:28:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:28:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:28:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:28:47 INFO - 'talos_options': ['--disable-stylo'], 12:28:47 INFO - 'tests': ['ts_paint_webext', 12:28:47 INFO - 'tp5o_webext']}, 12:28:47 INFO - 'other-e10s': {'tests': ['a11yr', 12:28:47 INFO - 'ts_paint', 12:28:47 INFO - 'tpaint', 12:28:47 INFO - 'sessionrestore', 12:28:47 INFO - 'sessionrestore_many_windows', 12:28:47 INFO - 'sessionrestore_no_auto_restore', 12:28:47 INFO - 'tabpaint']}, 12:28:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:28:47 INFO - 'tests': ['a11yr', 12:28:47 INFO - 'ts_paint', 12:28:47 INFO - 'tpaint', 12:28:47 INFO - 'sessionrestore', 12:28:47 INFO - 'sessionrestore_many_windows', 12:28:47 INFO - 'sessionrestore_no_auto_restore', 12:28:47 INFO - 'tabpaint']}, 12:28:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 12:28:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:28:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:28:47 INFO - 'tests': ['perf_reftest_singletons']}, 12:28:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:28:47 INFO - 'tests': ['bloom_basic']}, 12:28:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:28:47 INFO - 'tsvgr_opacity', 12:28:47 INFO - 'tart', 12:28:47 INFO - 'tscrollx', 12:28:47 INFO - 'tsvg_static']}, 12:28:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:28:47 INFO - 'tests': ['tsvgx', 12:28:47 INFO - 'tsvgr_opacity', 12:28:47 INFO - 'tart', 12:28:47 INFO - 'tscrollx', 12:28:47 INFO - 'tsvg_static']}, 12:28:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:28:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:28:47 INFO - 'talos_options': ['--disable-stylo'], 12:28:47 INFO - 'tests': ['tp5o']}, 12:28:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:28:47 INFO - 'talos_options': ['--mitmproxy', 12:28:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:28:47 INFO - '--firstNonBlankPaint'], 12:28:47 INFO - 'tests': ['tp6_google', 12:28:47 INFO - 'tp6_youtube', 12:28:47 INFO - 'tp6_amazon', 12:28:47 INFO - 'tp6_facebook']}, 12:28:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:28:47 INFO - 'talos_options': ['--disable-stylo', 12:28:47 INFO - '--mitmproxy', 12:28:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:28:47 INFO - '--firstNonBlankPaint'], 12:28:47 INFO - 'tests': ['tp6_google', 12:28:47 INFO - 'tp6_youtube', 12:28:47 INFO - 'tp6_amazon', 12:28:47 INFO - 'tp6_facebook']}, 12:28:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:28:47 INFO - 'talos_options': ['--stylo-threads=1', 12:28:47 INFO - '--mitmproxy', 12:28:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:28:47 INFO - '--firstNonBlankPaint'], 12:28:47 INFO - 'tests': ['tp6_google', 12:28:47 INFO - 'tp6_youtube', 12:28:47 INFO - 'tp6_amazon', 12:28:47 INFO - 'tp6_facebook']}, 12:28:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:28:47 INFO - 'talos_options': ['--xperf_path', 12:28:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:28:47 INFO - 'tests': ['tp5n']}, 12:28:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:28:47 INFO - 'talos_options': ['--disable-stylo', 12:28:47 INFO - '--xperf_path', 12:28:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:28:47 INFO - 'tests': ['tp5n']}}} 12:28:47 INFO - Running post-action listener: _resource_record_post_action 12:28:47 INFO - [mozharness: 2017-09-20 19:28:47.504989Z] Finished populate-webroot step (success) 12:28:47 INFO - [mozharness: 2017-09-20 19:28:47.505097Z] Running create-virtualenv step. 12:28:47 INFO - Running pre-action listener: _resource_record_pre_action 12:28:47 INFO - Running main action method: create_virtualenv 12:28:47 INFO - Creating virtualenv /builds/slave/test/build/venv 12:28:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:28:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:28:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:28:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:28:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:28:48 INFO - Installing distribute.............................................................................................................................................................................................done. 12:28:48 INFO - Installing pip...............done. 12:28:48 INFO - Return code: 0 12:28:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:28:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:28:48 INFO - Reading from file tmpfile_stdout 12:28:48 INFO - Output received: 12:28:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:28:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:48 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 0xd321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2090>, '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 0x10a2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cf90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', '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 12:28:48 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 12:28:48 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 12:28:48 INFO - Using env: {'DISPLAY': ':0', 12:28:48 INFO - 'HOME': '/home/cltbld', 12:28:48 INFO - 'LANG': 'en_US.UTF-8', 12:28:48 INFO - 'LANGUAGE': 'en_US:en', 12:28:48 INFO - 'LOGNAME': 'cltbld', 12:28:48 INFO - 'MAIL': '/var/mail/cltbld', 12:28:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:48 INFO - 'MOZ_NO_REMOTE': '1', 12:28:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:48 INFO - 'NO_EM_RESTART': '1', 12:28:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:48 INFO - 'PWD': '/builds/slave/test', 12:28:48 INFO - 'SHELL': '/bin/bash', 12:28:48 INFO - 'SHLVL': '1', 12:28:48 INFO - 'TERM': 'linux', 12:28:48 INFO - 'TMOUT': '86400', 12:28:48 INFO - 'USER': 'cltbld', 12:28:48 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', 12:28:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:48 INFO - '_': '/tools/buildbot/bin/python'} 12:28:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:28:49 INFO - Downloading/unpacking pip>=1.5 12:28:49 INFO - Running setup.py egg_info for package pip 12:28:49 INFO - warning: no files found matching 'pip/cacert.pem' 12:28:49 INFO - warning: no files found matching '*.html' under directory 'docs' 12:28:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:28:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:28:49 INFO - Installing collected packages: pip 12:28:49 INFO - Found existing installation: pip 1.1 12:28:49 INFO - Uninstalling pip: 12:28:49 INFO - Successfully uninstalled pip 12:28:49 INFO - Running setup.py install for pip 12:28:49 INFO - warning: no files found matching 'pip/cacert.pem' 12:28:49 INFO - warning: no files found matching '*.html' under directory 'docs' 12:28:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:28:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:28:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:28:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:28:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:28:49 INFO - Successfully installed pip 12:28:49 INFO - Cleaning up... 12:28:49 INFO - Return code: 0 12:28:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:28:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2090>, '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 0x10a2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cf90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', '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 12:28:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:28:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:28:49 INFO - Using env: {'DISPLAY': ':0', 12:28:49 INFO - 'HOME': '/home/cltbld', 12:28:49 INFO - 'LANG': 'en_US.UTF-8', 12:28:49 INFO - 'LANGUAGE': 'en_US:en', 12:28:49 INFO - 'LOGNAME': 'cltbld', 12:28:49 INFO - 'MAIL': '/var/mail/cltbld', 12:28:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:49 INFO - 'MOZ_NO_REMOTE': '1', 12:28:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:49 INFO - 'NO_EM_RESTART': '1', 12:28:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:49 INFO - 'PWD': '/builds/slave/test', 12:28:49 INFO - 'SHELL': '/bin/bash', 12:28:49 INFO - 'SHLVL': '1', 12:28:49 INFO - 'TERM': 'linux', 12:28:49 INFO - 'TMOUT': '86400', 12:28:49 INFO - 'USER': 'cltbld', 12:28:49 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', 12:28:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:49 INFO - '_': '/tools/buildbot/bin/python'} 12:28:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:49 INFO - Downloading/unpacking psutil>=3.1.1 12:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:28:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:28:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:28:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:28:51 INFO - Installing collected packages: psutil 12:28:51 INFO - Running setup.py install for psutil 12:28:51 INFO - building 'psutil._psutil_linux' extension 12:28:51 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 12:28:51 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 12:28:51 INFO - building 'psutil._psutil_posix' extension 12:28:51 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 12:28:51 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 12:28:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:28:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:28:51 INFO - Successfully installed psutil 12:28:51 INFO - Cleaning up... 12:28:51 INFO - Return code: 0 12:28:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2090>, '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 0x10a2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cf90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', '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 12:28:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:28:51 INFO - Using env: {'DISPLAY': ':0', 12:28:51 INFO - 'HOME': '/home/cltbld', 12:28:51 INFO - 'LANG': 'en_US.UTF-8', 12:28:51 INFO - 'LANGUAGE': 'en_US:en', 12:28:51 INFO - 'LOGNAME': 'cltbld', 12:28:51 INFO - 'MAIL': '/var/mail/cltbld', 12:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:51 INFO - 'MOZ_NO_REMOTE': '1', 12:28:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:51 INFO - 'NO_EM_RESTART': '1', 12:28:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:51 INFO - 'PWD': '/builds/slave/test', 12:28:51 INFO - 'SHELL': '/bin/bash', 12:28:51 INFO - 'SHLVL': '1', 12:28:51 INFO - 'TERM': 'linux', 12:28:51 INFO - 'TMOUT': '86400', 12:28:51 INFO - 'USER': 'cltbld', 12:28:51 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', 12:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:51 INFO - '_': '/tools/buildbot/bin/python'} 12:28:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:28:51 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 12:28:51 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 12:28:51 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 12:28:51 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 12:28:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:28:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:28:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:28:54 INFO - Installing collected packages: mozsystemmonitor 12:28:54 INFO - Running setup.py install for mozsystemmonitor 12:28:54 INFO - Successfully installed mozsystemmonitor 12:28:54 INFO - Cleaning up... 12:28:54 INFO - Return code: 0 12:28:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:28:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2090>, '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 0x10a2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cf90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', '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 12:28:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:28:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:28:54 INFO - Using env: {'DISPLAY': ':0', 12:28:54 INFO - 'HOME': '/home/cltbld', 12:28:54 INFO - 'LANG': 'en_US.UTF-8', 12:28:54 INFO - 'LANGUAGE': 'en_US:en', 12:28:54 INFO - 'LOGNAME': 'cltbld', 12:28:54 INFO - 'MAIL': '/var/mail/cltbld', 12:28:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:54 INFO - 'MOZ_NO_REMOTE': '1', 12:28:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:54 INFO - 'NO_EM_RESTART': '1', 12:28:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:54 INFO - 'PWD': '/builds/slave/test', 12:28:54 INFO - 'SHELL': '/bin/bash', 12:28:54 INFO - 'SHLVL': '1', 12:28:54 INFO - 'TERM': 'linux', 12:28:54 INFO - 'TMOUT': '86400', 12:28:54 INFO - 'USER': 'cltbld', 12:28:54 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', 12:28:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:54 INFO - '_': '/tools/buildbot/bin/python'} 12:28:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:55 INFO - Downloading/unpacking jsonschema==2.5.1 12:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:28:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:28:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:28:56 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 12:28:56 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 12:28:56 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 12:28:56 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 12:28:56 INFO - Downloading functools32-3.2.3-2.tar.gz 12:28:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:28:56 INFO - warning: no files found matching '*.txt' 12:28:56 INFO - no previously-included directories found matching 'build' 12:28:56 INFO - no previously-included directories found matching 'dist' 12:28:56 INFO - no previously-included directories found matching '.git*' 12:28:56 INFO - Installing collected packages: jsonschema, functools32 12:28:56 INFO - Running setup.py install for functools32 12:28:56 INFO - warning: no files found matching '*.txt' 12:28:56 INFO - no previously-included directories found matching 'build' 12:28:56 INFO - no previously-included directories found matching 'dist' 12:28:56 INFO - no previously-included directories found matching '.git*' 12:28:56 INFO - Successfully installed jsonschema functools32 12:28:56 INFO - Cleaning up... 12:28:56 INFO - Return code: 0 12:28:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:28: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')]} 12:28:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28: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')]} 12:28:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:56 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 0xd321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2090>, '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 0x10a2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cf90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', '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 12:28:56 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 12:28:56 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 12:28:56 INFO - Using env: {'DISPLAY': ':0', 12:28:56 INFO - 'HOME': '/home/cltbld', 12:28:56 INFO - 'LANG': 'en_US.UTF-8', 12:28:56 INFO - 'LANGUAGE': 'en_US:en', 12:28:56 INFO - 'LOGNAME': 'cltbld', 12:28:56 INFO - 'MAIL': '/var/mail/cltbld', 12:28:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:56 INFO - 'MOZ_NO_REMOTE': '1', 12:28:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:56 INFO - 'NO_EM_RESTART': '1', 12:28:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:56 INFO - 'PWD': '/builds/slave/test', 12:28:56 INFO - 'SHELL': '/bin/bash', 12:28:56 INFO - 'SHLVL': '1', 12:28:56 INFO - 'TERM': 'linux', 12:28:56 INFO - 'TMOUT': '86400', 12:28:56 INFO - 'USER': 'cltbld', 12:28:56 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', 12:28:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:56 INFO - '_': '/tools/buildbot/bin/python'} 12:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:28:57 INFO - Cleaning up... 12:28:57 INFO - Return code: 0 12:28:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:28:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2090>, '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 0x10a2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cf90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', '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 12:28:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:28:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:28:57 INFO - Using env: {'DISPLAY': ':0', 12:28:57 INFO - 'HOME': '/home/cltbld', 12:28:57 INFO - 'LANG': 'en_US.UTF-8', 12:28:57 INFO - 'LANGUAGE': 'en_US:en', 12:28:57 INFO - 'LOGNAME': 'cltbld', 12:28:57 INFO - 'MAIL': '/var/mail/cltbld', 12:28:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:57 INFO - 'MOZ_NO_REMOTE': '1', 12:28:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:57 INFO - 'NO_EM_RESTART': '1', 12:28:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:57 INFO - 'PWD': '/builds/slave/test', 12:28:57 INFO - 'SHELL': '/bin/bash', 12:28:57 INFO - 'SHLVL': '1', 12:28:57 INFO - 'TERM': 'linux', 12:28:57 INFO - 'TMOUT': '86400', 12:28:57 INFO - 'USER': 'cltbld', 12:28:57 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', 12:28:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:57 INFO - '_': '/tools/buildbot/bin/python'} 12:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:57 INFO - Downloading/unpacking blobuploader==1.2.4 12:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:28:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:28:58 INFO - Downloading blobuploader-1.2.4.tar.gz 12:28:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:28:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:28:58 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 12:28:58 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 12:28:58 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 12:28:58 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 12:28:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:28:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:28:58 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 12:28:58 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 12:28:58 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 12:28:58 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 12:28:59 INFO - Downloading docopt-0.6.1.tar.gz 12:28:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:28:59 INFO - Installing collected packages: blobuploader, requests, docopt 12:28:59 INFO - Running setup.py install for blobuploader 12:28:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:28:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:28:59 INFO - Running setup.py install for requests 12:28:59 INFO - Running setup.py install for docopt 12:28:59 INFO - Successfully installed blobuploader requests docopt 12:28:59 INFO - Cleaning up... 12:28:59 INFO - Return code: 0 12:28:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:28:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:28:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:28:59 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 0xd321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2090>, '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 0x10a2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cf90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', '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 12:28:59 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 12:28:59 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 12:28:59 INFO - Using env: {'DISPLAY': ':0', 12:28:59 INFO - 'HOME': '/home/cltbld', 12:28:59 INFO - 'LANG': 'en_US.UTF-8', 12:28:59 INFO - 'LANGUAGE': 'en_US:en', 12:28:59 INFO - 'LOGNAME': 'cltbld', 12:28:59 INFO - 'MAIL': '/var/mail/cltbld', 12:28:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:28:59 INFO - 'MOZ_NO_REMOTE': '1', 12:28:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:28:59 INFO - 'NO_EM_RESTART': '1', 12:28:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:28:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:28:59 INFO - 'PWD': '/builds/slave/test', 12:28:59 INFO - 'SHELL': '/bin/bash', 12:28:59 INFO - 'SHLVL': '1', 12:28:59 INFO - 'TERM': 'linux', 12:28:59 INFO - 'TMOUT': '86400', 12:28:59 INFO - 'USER': 'cltbld', 12:28:59 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', 12:28:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:28:59 INFO - '_': '/tools/buildbot/bin/python'} 12:28:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:28:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:28:59 INFO - Running setup.py (path:/tmp/pip-qMJexA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:28:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:28:59 INFO - Running setup.py (path:/tmp/pip-61IvTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:00 INFO - Running setup.py (path:/tmp/pip-NZZoiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:00 INFO - Running setup.py (path:/tmp/pip-zJSWhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:00 INFO - Running setup.py (path:/tmp/pip-TzBN0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:00 INFO - Running setup.py (path:/tmp/pip-UIrLm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:00 INFO - Running setup.py (path:/tmp/pip-cL4J0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:00 INFO - Running setup.py (path:/tmp/pip-FZJEes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:00 INFO - Running setup.py (path:/tmp/pip-cyY72C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:00 INFO - Running setup.py (path:/tmp/pip-0XbscR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:00 INFO - Running setup.py (path:/tmp/pip-SIqB7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:00 INFO - Running setup.py (path:/tmp/pip-dydbe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:00 INFO - Running setup.py (path:/tmp/pip-744zPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:00 INFO - Running setup.py (path:/tmp/pip-qBHqkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:00 INFO - Running setup.py (path:/tmp/pip-woVR6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:00 INFO - Running setup.py (path:/tmp/pip-OWcDsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:01 INFO - Running setup.py (path:/tmp/pip-IhuaDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:29:01 INFO - Running setup.py install for manifestparser 12:29:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:29:01 INFO - Running setup.py install for mozcrash 12:29:01 INFO - Running setup.py install for mozdebug 12:29:01 INFO - Running setup.py install for mozdevice 12:29:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:29:01 INFO - Running setup.py install for mozfile 12:29:01 INFO - Running setup.py install for mozhttpd 12:29:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:29:01 INFO - Running setup.py install for mozinfo 12:29:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:29:01 INFO - Running setup.py install for mozInstall 12:29:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:29:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:29:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:29:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:29:01 INFO - Running setup.py install for mozleak 12:29:01 INFO - Running setup.py install for mozlog 12:29:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:29:02 INFO - Running setup.py install for moznetwork 12:29:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:29:02 INFO - Running setup.py install for mozprocess 12:29:02 INFO - Running setup.py install for mozprofile 12:29:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:29:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:29:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:29:02 INFO - Running setup.py install for mozrunner 12:29:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:29:02 INFO - Running setup.py install for mozscreenshot 12:29:02 INFO - Running setup.py install for moztest 12:29:02 INFO - Running setup.py install for mozversion 12:29:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:29:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:29:02 INFO - Cleaning up... 12:29:02 INFO - Return code: 0 12:29:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:02 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 0xd321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2090>, '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 0x10a2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cf90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', '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 12:29:02 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 12:29:02 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 12:29:02 INFO - Using env: {'DISPLAY': ':0', 12:29:02 INFO - 'HOME': '/home/cltbld', 12:29:02 INFO - 'LANG': 'en_US.UTF-8', 12:29:02 INFO - 'LANGUAGE': 'en_US:en', 12:29:02 INFO - 'LOGNAME': 'cltbld', 12:29:02 INFO - 'MAIL': '/var/mail/cltbld', 12:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:02 INFO - 'MOZ_NO_REMOTE': '1', 12:29:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:02 INFO - 'NO_EM_RESTART': '1', 12:29:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:02 INFO - 'PWD': '/builds/slave/test', 12:29:02 INFO - 'SHELL': '/bin/bash', 12:29:02 INFO - 'SHLVL': '1', 12:29:02 INFO - 'TERM': 'linux', 12:29:02 INFO - 'TMOUT': '86400', 12:29:02 INFO - 'USER': 'cltbld', 12:29:02 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', 12:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:02 INFO - '_': '/tools/buildbot/bin/python'} 12:29:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:29:03 INFO - Running setup.py (path:/tmp/pip-jHcs6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:29:03 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)) 12:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:29:03 INFO - Running setup.py (path:/tmp/pip-dqwfs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:03 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)) 12:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:03 INFO - Running setup.py (path:/tmp/pip-eRZ1jw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:03 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)) 12:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:03 INFO - Running setup.py (path:/tmp/pip-KCplvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:03 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)) 12:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:03 INFO - Running setup.py (path:/tmp/pip-Py6I0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:03 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)) 12:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:03 INFO - Running setup.py (path:/tmp/pip-UK_0TM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:03 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)) 12:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:03 INFO - Running setup.py (path:/tmp/pip-bQbr7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:03 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)) 12:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:03 INFO - Running setup.py (path:/tmp/pip-v5Q_4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:03 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)) 12:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:03 INFO - Running setup.py (path:/tmp/pip-2D3T6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:03 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)) 12:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:03 INFO - Running setup.py (path:/tmp/pip-L3ZcsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:03 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)) 12:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:03 INFO - Running setup.py (path:/tmp/pip-F2_w18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:03 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)) 12:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:03 INFO - Running setup.py (path:/tmp/pip-taavVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:03 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)) 12:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:03 INFO - Running setup.py (path:/tmp/pip-RCvquV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:04 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)) 12:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:04 INFO - Running setup.py (path:/tmp/pip-whWptH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:04 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)) 12:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:04 INFO - Running setup.py (path:/tmp/pip-qkxxIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:04 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)) 12:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:04 INFO - Running setup.py (path:/tmp/pip-Dj5VxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:04 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)) 12:29:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:04 INFO - Running setup.py (path:/tmp/pip-m11Klv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:04 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)) 12:29:04 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)) 12:29:04 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)) 12:29:04 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)) 12:29:04 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)) 12:29:04 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)) 12:29:04 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)) 12:29:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:29:04 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 12:29:04 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 12:29:04 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 12:29:04 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 12:29:05 INFO - Downloading blessings-1.6.tar.gz 12:29:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:29:05 INFO - Installing collected packages: blessings 12:29:05 INFO - Running setup.py install for blessings 12:29:05 INFO - Successfully installed blessings 12:29:05 INFO - Cleaning up... 12:29:05 INFO - Return code: 0 12:29:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:29:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:29:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:29:05 INFO - Reading from file tmpfile_stdout 12:29:05 INFO - Current package versions: 12:29:05 INFO - argparse == 1.2.1 12:29:05 INFO - blessings == 1.6 12:29:05 INFO - blobuploader == 1.2.4 12:29:05 INFO - docopt == 0.6.1 12:29:05 INFO - functools32 == 3.2.3-2 12:29:05 INFO - jsonschema == 2.5.1 12:29:05 INFO - manifestparser == 1.1 12:29:05 INFO - mozInstall == 1.13 12:29:05 INFO - mozcrash == 1.0 12:29:05 INFO - mozdebug == 0.1 12:29:05 INFO - mozdevice == 0.51 12:29:05 INFO - mozfile == 1.2 12:29:05 INFO - mozhttpd == 0.7 12:29:05 INFO - mozinfo == 0.10 12:29:05 INFO - mozleak == 0.1 12:29:05 INFO - mozlog == 3.5 12:29:05 INFO - moznetwork == 0.27 12:29:05 INFO - mozprocess == 0.25 12:29:05 INFO - mozprofile == 0.28 12:29:05 INFO - mozrunner == 6.13 12:29:05 INFO - mozscreenshot == 0.1 12:29:05 INFO - mozsystemmonitor == 0.3 12:29:05 INFO - moztest == 0.8 12:29:05 INFO - mozversion == 1.4 12:29:05 INFO - psutil == 3.1.1 12:29:05 INFO - requests == 1.2.3 12:29:05 INFO - wsgiref == 0.1.2 12:29:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29: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')]} 12:29:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29: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')]} 12:29:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:05 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 0xd321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2090>, '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 0x10a2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cf90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', '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 12:29:05 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 12:29:05 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 12:29:05 INFO - Using env: {'DISPLAY': ':0', 12:29:05 INFO - 'HOME': '/home/cltbld', 12:29:05 INFO - 'LANG': 'en_US.UTF-8', 12:29:05 INFO - 'LANGUAGE': 'en_US:en', 12:29:05 INFO - 'LOGNAME': 'cltbld', 12:29:05 INFO - 'MAIL': '/var/mail/cltbld', 12:29:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:05 INFO - 'MOZ_NO_REMOTE': '1', 12:29:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:05 INFO - 'NO_EM_RESTART': '1', 12:29:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:05 INFO - 'PWD': '/builds/slave/test', 12:29:05 INFO - 'SHELL': '/bin/bash', 12:29:05 INFO - 'SHLVL': '1', 12:29:05 INFO - 'TERM': 'linux', 12:29:05 INFO - 'TMOUT': '86400', 12:29:05 INFO - 'USER': 'cltbld', 12:29:05 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', 12:29:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:05 INFO - '_': '/tools/buildbot/bin/python'} 12:29:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:06 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)) 12:29:06 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)) 12:29:06 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)) 12:29:06 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)) 12:29:06 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)) 12:29:06 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)) 12:29:06 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)) 12:29:06 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)) 12:29:06 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)) 12:29:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:29:06 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 12:29:06 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 12:29:06 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 12:29:06 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 12:29:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:29:07 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)) 12:29:07 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)) 12:29:07 INFO - Installing collected packages: simplejson 12:29:07 INFO - Running setup.py install for simplejson 12:29:07 INFO - building 'simplejson._speedups' extension 12:29:07 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 12:29:08 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 12:29:08 INFO - Successfully installed simplejson 12:29:08 INFO - Cleaning up... 12:29:08 INFO - Return code: 0 12:29:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:29: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')]} 12:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29: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')]} 12:29:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd321f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109ea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2090>, '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 0x10a2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cf90>, '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': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', '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 12:29: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', 'jsonschema'] in /builds/slave/test/build 12:29: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 jsonschema 12:29:08 INFO - Using env: {'DISPLAY': ':0', 12:29:08 INFO - 'HOME': '/home/cltbld', 12:29:08 INFO - 'LANG': 'en_US.UTF-8', 12:29:08 INFO - 'LANGUAGE': 'en_US:en', 12:29:08 INFO - 'LOGNAME': 'cltbld', 12:29:08 INFO - 'MAIL': '/var/mail/cltbld', 12:29:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:08 INFO - 'MOZ_NO_REMOTE': '1', 12:29:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:08 INFO - 'NO_EM_RESTART': '1', 12:29:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:08 INFO - 'PWD': '/builds/slave/test', 12:29:08 INFO - 'SHELL': '/bin/bash', 12:29:08 INFO - 'SHLVL': '1', 12:29:08 INFO - 'TERM': 'linux', 12:29:08 INFO - 'TMOUT': '86400', 12:29:08 INFO - 'USER': 'cltbld', 12:29:08 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', 12:29:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:08 INFO - '_': '/tools/buildbot/bin/python'} 12:29:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:29:08 INFO - Cleaning up... 12:29:08 INFO - Return code: 0 12:29:08 INFO - Running post-action listener: _resource_record_post_action 12:29:08 INFO - Running post-action listener: _start_resource_monitoring 12:29:08 INFO - Starting resource monitoring. 12:29:08 INFO - [mozharness: 2017-09-20 19:29:08.578896Z] Finished create-virtualenv step (success) 12:29:08 INFO - [mozharness: 2017-09-20 19:29:08.579392Z] Running install step. 12:29:08 INFO - Running pre-action listener: _resource_record_pre_action 12:29:08 INFO - Running main action method: install 12:29:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:29:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:29:08 INFO - Reading from file tmpfile_stdout 12:29:08 INFO - Detecting whether we're running mozinstall >=1.0... 12:29:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:29:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:29:08 INFO - Reading from file tmpfile_stdout 12:29:08 INFO - Output received: 12:29:08 INFO - Usage: mozinstall [options] installer 12:29:08 INFO - Options: 12:29:08 INFO - -h, --help show this help message and exit 12:29:08 INFO - -d DEST, --destination=DEST 12:29:08 INFO - Directory to install application into. [default: 12:29:08 INFO - "/builds/slave/test"] 12:29:08 INFO - --app=APP Application being installed. [default: firefox] 12:29:08 INFO - mkdir: /builds/slave/test/build/application 12:29:08 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'] 12:29:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:29:26 INFO - Reading from file tmpfile_stdout 12:29:26 INFO - Output received: 12:29:26 INFO - /builds/slave/test/build/application/firefox/firefox 12:29:26 INFO - Running post-action listener: _resource_record_post_action 12:29:26 INFO - [mozharness: 2017-09-20 19:29:26.539408Z] Finished install step (success) 12:29:26 INFO - [mozharness: 2017-09-20 19:29:26.539663Z] Running setup-mitmproxy step. 12:29:26 INFO - Running pre-action listener: _resource_record_pre_action 12:29:26 INFO - Running main action method: setup_mitmproxy 12:29:26 INFO - Skipping: mitmproxy is not required 12:29:26 INFO - Running post-action listener: _resource_record_post_action 12:29:26 INFO - [mozharness: 2017-09-20 19:29:26.540795Z] Finished setup-mitmproxy step (success) 12:29:26 INFO - [mozharness: 2017-09-20 19:29:26.541016Z] Running run-tests step. 12:29:26 INFO - Running pre-action listener: _resource_record_pre_action 12:29:26 INFO - Running pre-action listener: _set_gcov_prefix 12:29:26 INFO - Running main action method: run_tests 12:29:26 WARNING - Try message not found. 12:29:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:29:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:29:26 INFO - Python 2.7.3 12:29:26 INFO - Return code: 0 12:29:26 INFO - grabbing minidump binary from tooltool 12:29:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:29:26 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 0x10a2090>, '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 0x10a2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109cf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:29:26 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 12:29:26 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 12:29:26 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 12:29:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:29:26 INFO - Return code: 0 12:29:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:29:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:29:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:29:26 INFO - ENV: RUST_BACKTRACE is now full 12:29:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:29:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:29:26 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/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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 12:29:26 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/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-088 --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 12:29:26 INFO - Using env: {'DISPLAY': ':0', 12:29:26 INFO - 'HOME': '/home/cltbld', 12:29:26 INFO - 'LANG': 'en_US.UTF-8', 12:29:26 INFO - 'LANGUAGE': 'en_US:en', 12:29:26 INFO - 'LOGNAME': 'cltbld', 12:29:26 INFO - 'MAIL': '/var/mail/cltbld', 12:29:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:29:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:29:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:26 INFO - 'MOZ_NO_REMOTE': '1', 12:29:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:29:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:26 INFO - 'NO_EM_RESTART': '1', 12:29:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:26 INFO - 'PWD': '/builds/slave/test', 12:29:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:29:26 INFO - 'RUST_BACKTRACE': 'full', 12:29:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:29:26 INFO - 'SHELL': '/bin/bash', 12:29:26 INFO - 'SHLVL': '1', 12:29:26 INFO - 'TERM': 'linux', 12:29:26 INFO - 'TMOUT': '86400', 12:29:26 INFO - 'USER': 'cltbld', 12:29:26 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', 12:29:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:26 INFO - '_': '/tools/buildbot/bin/python'} 12:29:26 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/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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 12:29:26 INFO - mozversion application_buildid: 20170920180853 12:29:26 INFO - mozversion application_changeset: 469eb992a9d166004f2601ce725786f671219054 12:29:26 INFO - mozversion application_display_name: Nightly 12:29:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:29:26 INFO - mozversion application_name: Firefox 12:29:26 INFO - mozversion application_remotingname: firefox 12:29:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:29:26 INFO - mozversion application_vendor: Mozilla 12:29:26 INFO - mozversion application_version: 57.0a1 12:29:26 INFO - mozversion platform_buildid: 20170920180853 12:29:26 INFO - mozversion platform_changeset: 469eb992a9d166004f2601ce725786f671219054 12:29:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:29:26 INFO - mozversion platform_version: 57.0a1 12:29:26 INFO - using testdate: 1505935766 12:29:26 INFO - actual date: 1505935766 12:29:26 INFO - starting webserver on 'localhost:57997' 12:29:26 INFO - SUITE-START | Running 2 tests 12:29:26 INFO - TEST-START | dromaeo_css 12:29:26 INFO - Initialising browser for dromaeo_css test... 12:29:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57997/getInfo.html -profile /tmp/tmpTohhF5/profile 12:29:26 INFO - TEST-INFO | started process 30763 (/builds/slave/test/build/application/firefox/firefox http://localhost:57997/getInfo.html) 12:29:32 INFO - TEST-INFO | 30763: exit 0 12:29:32 INFO - Browser initialized. 12:29:32 INFO - Running cycle 1/1 for dromaeo_css test... 12:29:32 INFO - Using env: {'DISPLAY': ':0', 12:29:32 INFO - 'HOME': '/home/cltbld', 12:29:32 INFO - 'JSGC_DISABLE_POISONING': '1', 12:29:32 INFO - 'LANG': 'en_US.UTF-8', 12:29:32 INFO - 'LANGUAGE': 'en_US:en', 12:29:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:29:32 INFO - 'LOGNAME': 'cltbld', 12:29:32 INFO - 'MAIL': '/var/mail/cltbld', 12:29:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:29:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:29:32 INFO - 'MOZ_CRASHREPORTER': '1', 12:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:29:32 INFO - 'MOZ_NO_REMOTE': '1', 12:29:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:29:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:32 INFO - 'NO_EM_RESTART': '1', 12:29:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:32 INFO - 'PWD': '/builds/slave/test', 12:29:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:29:32 INFO - 'RUST_BACKTRACE': 'full', 12:29:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:29:32 INFO - 'SHELL': '/bin/bash', 12:29:32 INFO - 'SHLVL': '1', 12:29:32 INFO - 'TERM': 'linux', 12:29:32 INFO - 'TMOUT': '86400', 12:29:32 INFO - 'USER': 'cltbld', 12:29:32 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', 12:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:32 INFO - '_': '/tools/buildbot/bin/python'} 12:29:32 INFO - TEST-INFO | started process 31001 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTohhF5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:29:32 INFO - PID 31001 | 12:29:32 INFO - PID 31001 | (/builds/slave/test/build/application/firefox/firefox:31050): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:29:32 INFO - PID 31001 | 12:29:43 INFO - PID 31001 | RSS: Main: 146579456 12:29:43 INFO - PID 31001 | 12:32:40 INFO - PID 31001 | Cycle 1(1): loaded http://localhost:57997/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57997/tests/dromaeo/cssquery-ext.html) 12:32:42 INFO - PID 31001 | RSS: Main: 148033536 12:32:42 INFO - PID 31001 | 12:35:43 INFO - PID 31001 | Cycle 1(1): loaded http://localhost:57997/tests/dromaeo/cssquery-ext.html (next: http://localhost:57997/tests/dromaeo/cssquery-jquery.html) 12:35:44 INFO - PID 31001 | RSS: Main: 148193280 12:35:44 INFO - PID 31001 | 12:38:46 INFO - PID 31001 | Cycle 1(1): loaded http://localhost:57997/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57997/tests/dromaeo/cssquery-mootools.html) 12:38:46 INFO - PID 31001 | RSS: Main: 150384640 12:38:46 INFO - PID 31001 | 12:41:48 INFO - PID 31001 | Cycle 1(1): loaded http://localhost:57997/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57997/tests/dromaeo/cssquery-prototype.html) 12:41:48 INFO - PID 31001 | RSS: Main: 149188608 12:41:48 INFO - PID 31001 | 12:44:52 INFO - PID 31001 | Cycle 1(1): loaded http://localhost:57997/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57997/tests/dromaeo/cssquery-yui.html) 12:44:52 INFO - PID 31001 | RSS: Main: 149893120 12:44:52 INFO - PID 31001 | 12:47:54 INFO - PID 31001 | Cycle 1(1): loaded http://localhost:57997/tests/dromaeo/cssquery-yui.html (next: http://localhost:57997/tests/dromaeo/cssquery-dojo.html) 12:47:54 INFO - PID 31001 | RSS: Main: 152436736 12:47:54 INFO - PID 31001 | 12:47:54 INFO - PID 31001 | __start_tp_report 12:47:54 INFO - PID 31001 | _x_x_mozilla_page_load 12:47:54 INFO - PID 31001 | _x_x_mozilla_page_load_details 12:47:54 INFO - PID 31001 | |i|pagename|runs| 12:47:54 INFO - PID 31001 | |0;dojo.html;3854.2484215577956;3855.807209639517;3870.5548861880884;3876.4766756487875;3897.922041559167;6097.603655762372;6164.044573091167;6181.350958149397;6204.224471941007;6276.340984196657;6215.223097112861;6224.719887605057;6277.497800175986;6299.590526615778;6301.2753533343675;5508.641938274;5596.27248457701;5601.495865372116;5603.523700485463;5605.439456054402;5626.774929002835;5679.914801277984;5703.230063941356;5747.3677895431465;5753.165790960308;5650.886982260352;5693.601447898649;5710.229119068935;5755.597108202427;5758.395368486312;5512.4211957744665;5602.299712535933;5610.523105536035;5614.466625670555;5680.233168522238;5601.3558440779425;5643;5655.3779084300695;5670.773169073233;5672.205891175238;6210.285817130997;6216;6224;6302.527310451735;6322.241331040304;6674.098996635441;6765.154355705537;6768.255491895888;6776.593404395752;6780.93219067808;6757.560758550679;6784.796456106275;6812.14848143982;6815.148106486689;6837.897431538531;6301.14934483848;6358.618482891041;6360.427561519486;6379.138816259792;6379.617222966637;6839.247682754893;6909.861802764017;6922.653867306614;6999.475039372068;7009.614471204132;6565.24499682533;6624.86750264992;6657.334266573401;6657.866842663119;6743.123393958754;6786.471035061741;6798.252192258848;6802.65986700663;6803.625800581016;6875.07186529812;6736.8989465158065;6836.897446538306;6850.246472887979;6853.451723862178;6866.725330986705;6753.8649227015185;6767.1879374475375;6769.492288078315;6841.418479429304;6858.039874417585;4845.248986407112;4867.975660121748;4917.704937703749;4944.332515110414;4959.156943319572;4427.977860110679;4465.084657645175;4510.345999830006;4535.931961020588;4546.204414227563;6165.969170154121;6255.71849266775;6329.430351268408;6337.112804207414;6354.682265886687;6597.86804263912;6700.664966751643;6809.114815074008;6876.346747059021;6886.793396198122;6083.239595050619;6285.560010799291;6312.147860038928;6331.113644089831;6335.271443783931;5474.589405794582;5544.306961629796;5552.861178470571;5556.555475561945;5558.527525160407;6802.3537763912345;7026.437884969292;7036.683349249296;7119.038929744522;7128.7861364158125;6749.797506074826;6758.391744742997;6827.419669328163;6857.902735562286;6864;7059.117610298713;7081.787546373617;7155.141383033861;7172.856542869009;7184.9640751795905;6699.062131301719;6702.83242918931;6728.899066514105;6790.45676345901;6792.354726300993;6626.635535045619;6773.05177275172;6785.050092987084;6795.490338224752;6801.659917004031;6549.967250163719;6563.245226798788;6593.703283352356;6609.438197753245;6631.734730610721;6531.183602049744;6534;6593.944968804968;6597.27429982692;6614.966925165343;6381.968090159705;6438.871222575616;6465.353464653497;6469.997150441689;6489.707963141575;6558.114654521769;6590.670466476561;6594.274629790623;6610.438112760276;6621.039949207434;6349.269833969152;6396.424321810873;6416.165898433173;6417.133686952202;6418.550701450854 12:47:54 INFO - PID 31001 | |1;ext.html;950.4867371619326;950.9001554836742;978.6476868327401;989.0356902020526;1005.4319309590081;41857.58142418575;42775;42902.57097429025;42911.78544107283;42936;40289.59710402895;40618.78143655695;40638.59361406385;40715.38926916099;40731;7462.589557574332;7557.47097703161;7584.848303033936;7586.089669239699;7586.5068770530015;7534.9246507535045;7571.205023472538;7608;7614.619269036553;7619.504732192389;7967.322777563885;8179.631916563769;8201.630926608286;8202.507849529047;8206.261436470717;8117.594120293962;8171.509709417424;8175.918240817575;8188.713395031145;8189.1401402853035;8014.278714915686;8047.95976020122;8089.15063918286;8136.064352599408;8211.548364839931;38640.22719545622;38869.805650971844;39026.21947561033;39107.608923910964;39110.41334379987;3674.4855720199193;3707.480952666629;3722.1253005543676;3724.404095344732;3724.571674257441;32091.518627220612;32298.192545186324;32349;32374.514382284284;32403.83798081018;5430.348358197041;5437.429069947678;5456.154296084113;5474.507294343528;5505.944940550583;216707;216804;217045;217729;218122;51230.48769512332;51497.74251128795;51639.48360516422;51643.22535161975;51718.48281517137;53404.99462540325;53460;53507;53509;53582;15668.216589170495;15972;15990.920045399698;16025.759613605805;16037.198140092949;12896.097273190786;12898.161619494704;12898.80651790224;12899.290539020443;12910.902573281377;14947.878909081863;15008.174550399834;15013.849861501463;15042.548723538312;15054.397824087133;5992.280926288786;6036.9698151508965;6058.242719660043;6163.96918015416;6196.597221180609;1107.8725946516254;1115.6485707002269;1117.2905205194597;1123.6516679829278;1133.4502766158407;25435.491290174094;25461.236162915142;25530.617040744404;25570.360740981254;25686;1212.9265599944113;1214.5141943222782;1217.9025677945742;1218.1351240619083;1249.9937500312622;13304;13367.598972030855;13748.106373085717;13837.654058648613;13898.23559704226;268596;269227;269938;270491;270832;55673.443265567636;59453.40546594479;60615.39384606186;60741.69629151915;60884;1509.2001239343165;1530.7474266746085;1538.5615099696533;1543.6449616588247;1544.5829626001141;29841;29895.55156672695;30241.697583023888;30342;30870.845645771627;39883.60116398799;39933.20133597254;39947;40016.7999160014;40033.39949900812;242081;245443;246122;246220;246748;33358;33607.32785344328;33674.49488257629;33825.49261761125;33873.49189762205;42299.36550951676;43701.78149109234;44082.77958610187;44315;44369;33303.66696333102;33387.16532086717;33509.497357540145;33689.32621347608;34232.288385581116;32522;32974;33024;33416.498752518244;33453.66546334505;37620.62379376171;38341.80829095837;38638.613613863505;39114.4132838002;39150;31753.36493270168;32199;32286.35427291394;32493.837530812194;32599.674003259664;25199.37001574975;25394.699358785165;25602.74397256078;25804.483910322066;26084.34789130287 12:47:54 INFO - PID 31001 | |2;jquery.html;597.9701014949252;608.9360617135198;610.8045425463853;611.2847967877583;627.2253766598251;23277.068917243312;23982.76017239827;24005.75994240057;24088.397790055256;24125.758742412592;21087.2619458319;23694.76305236947;23694.881525592347;23736.287911362644;23737;6504.4471219946245;6669.266380698119;6696.397324240817;6697.732090716366;6698.263191049005;6428.100065990765;6581.736730530797;6661;6677.599344039349;6704.731810727565;6605.570637908545;6606.834829129286;6663.9333606663795;6667.166604174478;6674.06563081169;6303.684815759239;6614.272430032716;6656.8668626627195;6664;6665.866682666344;6118.571699981003;6130.693465326761;6133.325334213233;6133.570650054498;6137.693115344259;30042.84978575115;30219.69780302213;30343.393132137455;30429.239269018453;30449.23876903073;2090.5296308330658;2102.737157855268;2116.0266277512364;2121.0455295117104;2124.1715730864976;20364.083616237305;20486.487837804027;20492.69260961087;20715.27496537614;20901;2500.324912273676;2511.61070034145;2567.1528395629584;2570.4345044090455;2571.2543362424876;138175.30912345473;144178;149892;154526;155731.44268557284;15663.060216387054;15668.529944101696;15715;16216.108114053604;16574.419895303607;25874.6118808218;25881.74118258793;25881.870590647304;25931.092411765498;25933.22200333993;10285.177185825116;10511.632092876713;10525;10583.894161058444;10614.628488002883;9498.477583732823;9500.857487137699;9680.75798105053;9729.9027009729;9809.803803923882;9595.568199431042;9876.901230987742;9897.950510247403;9951.70627818379;10012.899871001197;2902.492063888811;2921.21127295629;2928.326484908483;2937.720916512928;3013.9752484155056;1008.5411137932223;1009.8737657792776;1033.9793204135874;1034.818906691326;1046.0951277145214;20232.89883550602;20371.33316003104;20541.178352866016;20637.380878573666;20967.895160524404;1065.5418170186895;1068.770214403907;1075.5267682219799;1076.7308172956762;1077.8006068877282;11187.888121118685;11296.491657875415;11353.602623908124;11383.829242561369;11413;627474;631043;633285;641821;642240;121480.39259803644;125105;126825;128301.35849320694;128850;1213.8604060532975;1257.6352857671172;1303.8500572434102;1314.3691028306275;1329.8803107720353;21843.56312873702;22071.33785986423;22226.888865555567;22250.109995599996;22336.664950026086;63223;65785.34214657792;65942;66161.66919165374;67993.66003169953;83712.58143709242;84233;85183;85805;85983.5700821517;15340.463083792014;15368.538943831703;15423.691526169638;15446.613834654223;15464.768028479357;23395.298141055795;23667.17164899289;23672.171473999017;23750.287491375286;23754.881225593763;19158.61682766365;19161.712574311117;19552.315668952087;19648.508787280432;19669.50826229356;16207;16700.58248543796;17257;17337.653246935242;17473.47579572615;19885.900570497055;20111.29610463627;20117.798822012177;20148.798512015277;20236;15116.395344186549;15205.543833685007;15316.846831531542;15419.691606168039;15430.845691542941;19298.7105193425;19437.12532935993;19509;19535.609287814448;19686.80313196907 12:47:54 INFO - PID 31001 | |3;mootools.html;1422.6087825847894;1455.4469301665365;1460.8977371583994;1471.9190444525555;1486.9702605947882;14189.929050354747;14662.853371466283;14788.038777479456;17096;17558.29766809329;16965.15174241289;17259;17286.827131728678;17291.654166916687;17297.13514324282;2740.3834137319145;2770.390514086898;2822.7036161203087;2824.378636699933;2891.783116266278;2881.207667891326;2919.284775230072;2919.7080291970847;2921.079859844153;2923.210733102061;2857.828530288179;2874.1233923653276;2883.0341835485033;2885.4950383682876;2909.2872246299685;2743.4513097380504;2763.9309017274527;2764.8617569121566;2777.1668499450084;2777.8333300001864;2658.5081759874292;2669.3326668332916;2693.0305090167526;2698.6356841826496;2701.027630053179;15955.361785528565;16031.438899638457;16045.117518536475;16058.277377517923;16117.597060073474;8246.917530824734;8468.407211495198;8499.447535910207;8519.361047921431;8536.189062039097;5228.738563071831;5263.763130659129;5357.526680162986;5417.45825417459;5423.891522169574;2058.1355830551206;2078.3972647932083;2123.4585180778804;2133.9146434142617;2150.311900191939;29621.407571848442;29958.10125696211;29980.40039199204;30087.247818804488;30489.847550762326;7126.966589844547;7145.392641625416;7170.061185982573;7275.818104547428;7373.3732632726815;19464.124114414884;19530.70703939442;19550.413487595393;19560.8043919559;19576.412707618794;8056.637451314705;8168.387370947203;8202.466839655403;8222.465539739898;8225.383096267804;7439.665215065335;7511.51175173612;7529.322360987428;7668.156502784689;7746.767596972099;5293.391260005072;5473.343198816167;5473.397926228112;5522.723863806794;5535.750891209906;1547.829738728739;1548.9302981365865;1561.695469383482;1562.4375624375625;1563.2730780187267;975.3806332978468;977.0815433492494;983.6852207293597;985.9295479493007;1012.5190534496055;8219.136990616018;8307.08622051574;8318.916810831935;8396.160383961676;8443.83112337762;782.2060608482395;784.7522439321506;788.7791418402857;796.494226166377;796.7251298302076;5043.495650435;5496.780128794883;5640.153976903415;5666.263385759908;5718.113692377605;24345.14791982272;25250.494990100462;25347.239582812545;25552.488950221265;25619.10333138331;23932.641010384858;25028.124015659363;25267;25301.62047569288;25309;10557.313774604627;10862.728431789268;12337.198082124632;12421.254724716546;12437.067219958539;9333.953330233306;9680.75798105053;9702.85445718329;9792.706218813448;9920.603175872884;19763.40709778709;19965.700514492004;20097.497562560467;20384.388468345973;20768.48078798042;20818.375448735955;22310.665340020238;22311.107555697592;22470.438239044153;22489.77510224877;11049.668509944715;11180;11182;11182.440877956233;11198.216124871507;15199.544013679608;15435.2282385878;15519.45681901128;15523.146226957626;15682.137482438575;12196.878031219929;12239.265644061385;12294;12437.564685235973;12628.179168354027;11947.522099115939;11951;12279.815802763145;12518.93740531328;12634.704200571483;9978.351407158512;10130.746731331776;10267.435291059064;10287.279890407599;10295;8165.632546535301;8173.836523269621;8219;8253.504789712637;8335.749927502186;8100.554469504234;8135.674573017013;8169;8247.95876020616;8303.04514980785 12:47:54 INFO - PID 31001 | |4;prototype.html;553.3940335332811;556.5213916032214;558.2045585041317;582.8309790160854;585.9853503662407;6259.405356491137;6273.56085074045;6293.27627322858;6334.873302533946;6364.681765911703;6185.1650027246305;6195.783147589836;6239.875202495948;6275.717592708328;6294.402031806971;5070.315507406508;5077.720725360104;5107.642465027449;5122.334096567441;5125.179971204608;4820.903581928377;5077.162268225738;5135.35808023997;5136.871578210537;5154.768035438396;4846.5880400166025;4881.585065269456;4885.169521181408;4890.804367825301;4942.332785074005;4930.556249937505;4936.3829521309835;4943.209086546171;4950.282209079699;4963.652544321899;4370;4423.336499525065;4431.09162621665;4481.148581769454;4485.775711214426;6001.66990815505;6063.727132279058;6126.571140020201;6152.661603611799;6178.289496707891;5707.74315155819;5722;5737;5763.711814409263;5779.393163717833;6600.79861267285;6604.933950660528;6608.504362172856;6656.600603963778;6694.83262918431;4982.953579748258;4989.401271847365;4994.350734404504;5019.372578427697;5029.647924645276;6711.0202490265;6731.932680673229;6832.419244364235;6922.342377474132;6975.232724400363;5073.746312684351;5108.642395032312;5124.9743751281;5142.51146141116;5153.561947234527;5194.532492075731;5198.2202669599865;5199.922001169985;5203.739813009334;5223.2948551945765;5731.166026871361;5752.6836024019085;5755.1367294906095;5780.16187652788;5806.738696758656;6654.534182607171;6670.366315200048;6679.231888332807;6687.799366019027;6689.464842812563;1793.6592047511192;1806.3948577226513;1809.9185536650882;1813.3018787766805;1817.9363722269657;1228.5912285912216;1260.7667581497453;1276.4447465352416;1279.2452453052745;1290.7612091763053;6188.400871633835;6190.721417536221;6193.049189505382;6216.990041423347;6218.844528886723;6632.5688830226845;6653.234877988996;6666.566673166326;6696.531242812956;6719.227288861745;5901.675407852609;5950.137230101611;5962.672053037125;6017.608855424471;6064.4541991221;3464.4929455686856;3502.649735026477;3513.5432393789156;3516.1385460562324;3540;6956;6961;6974.302569742985;7001.824954376181;7060.952323826135;6772.69522871472;6823.454123670094;6883.139607549057;6893.517453778288;6896.241413444517;6877.243503214642;6952.165740111219;6955.652217389212;6967.965160174268;6985.79042628722;6617.602943823386;6619.834504137435;6650.634215118215;6676.566023208476;6700.195976482853;6697.296783837627;6723.7561051205685;6740.157480314961;6753.797386078426;6798.558093723892;6815.522913396015;6942.652867356713;6998.265182156004;7004.929950700428;7032.824179395556;5019.472955339636;5058.848234552969;5065.417476990193;5065.544101030925;5084.898302034013;6433.260175079926;6572.375624315682;6634.502412318999;6652.268250492345;6692.531522793358;6355.777547785805;6398.936010639834;6409.327020662763;6452.870942581216;6503.512236582181;6345.936540634534;6393;6428.646424446701;6433.420992110732;6523.347665233438;6295.18162638854;6295.433410993032;6327.557071004985;6329;6414.935850641434;6504.771898265975;6540.869182616226;6565.737370505318;6567.146270984741;6569.9343006569325;6370.235571731422;6391.137196478416;6400.935990640034;6434.8391290218115;6447.548671592944 12:47:54 INFO - PID 31001 | |5;yui.html;963.3930623707064;988.0218583602234;1002.6290272599135;1005.2309982863111;1010.5301035018724;18705.62588748224;18910.527236819085;19028.61942761144;19040.238390464383;19057.142428590712;18362.908185459088;19954.301599443985;20409.69385459219;20465.18139274427;20475.897620511914;1023.5649848814261;1059.2585190366735;1062.2670357453371;1066.029912779371;1068.7915856408003;1062.8883967183451;1072.4262519552042;1073.1682945717055;1074.6185104287983;1075.7902209069234;1024.6413755185663;1113.5991043224433;1115.7378016166194;1117.8826223246565;1121.1031175059961;1107.6067995861474;1110.0120892405766;1110.8111621024364;1111.155521803427;1111.7609713911547;777.5295945952673;805.2712295372697;809.6599428240153;810.5339429827873;816.2286639126029;19322.71015934762;19377.709334359995;19388.612227755508;19388.903055484774;19390.90304548482;23376.64935025976;23557.411064723346;24278.39304017396;24342.63486047711;24537;16166.353345866151;16652.333906643842;16729;16767.077810720406;16865.831341686673;3793.393057110849;3816.9427458587866;3834.5973672764235;3836.271108489406;3851.4415409765706;131344;162328;165725;167011;167281.16359418246;18439.72340414895;18541.536461588297;18726.719099213522;18838.811611883706;19225.423237302904;35154.82422587871;35156;35207.94376168668;35290.470642940374;35692.64307356945;9754.658586949423;9767.51162441875;9785.021497850157;9793.706188814347;9809;4567.474740404829;4645.790939407735;4697.553732395417;4704.411948506437;4752.619790416758;6118.235220597426;6233.189685340967;6241.063840423973;6250.124982502453;6266.216722909637;1192.362086283834;1200.37580458163;1205.1503689898775;1213.6844420450745;1232.9815052774215;744.3189481624253;757.0952711509693;773.8645736996003;777.5762209595671;778.5562229529228;13207.867921320856;13236.404361803741;13307.737684593449;13368;13425.865741342657;737.5611511150856;738.3982054625463;742.1724776873747;747.3572727454385;756.1833220122255;6058.727357268934;6110.388961103943;6124.203853499017;6139.877202455926;6203.7828675996125;141856;144125;145836;146381.26809365884;148874;128705.35647321703;128747;129218;130288.3485582585;135122;108064;108796;111283.44358278156;113260;113336;11312.773744525228;11448.42757862087;11570.59502917394;11756.764864702829;11789.823152652889;55506.72246638741;56572.71713641405;56852;57226.42773572377;57868.71065644645;70783.29216707767;71598;72060;73386.26613733794;73513.26486735279;15321.004134731207;15406.691866162839;15548.455804046804;15553.222338882784;15766;17300.48098557045;17681.292748290438;17754.5561360967;17826.732599011284;18007.459776206735;18034.819651803315;18246.817531824512;18331.45005649833;18433.354832580797;18455.630887382446;15893.205339733198;15909.363625455315;16002.759958600398;16115;16191.190440478165;16095.517134485985;16246;16331.836681633507;16343.346266149221;16452.177391130157;13169.407376667881;13413.329333533089;13447.663808404868;13591.728165436834;13633.931830340785;13897.791533126809;13933.442662293395;13993.370298336396;14078.859211408164;14125.576232713034 12:47:54 INFO - PID 31001 | __end_tp_report 12:47:54 INFO - PID 31001 | __start_cc_report 12:47:54 INFO - PID 31001 | _x_x_mozilla_cycle_collect,257 12:47:54 INFO - PID 31001 | __end_cc_report 12:47:54 INFO - PID 31001 | __startTimestamp1505936874451__endTimestamp 12:47:54 INFO - PID 31001 | 12:47:54 INFO - PID 31001 | ------- Summary: start ------- 12:47:54 INFO - PID 31001 | Number of tests: 6 12:47:54 INFO - PID 31001 | 12:47:54 INFO - PID 31001 | [#0] dojo.html Cycles:165 Average:6245.67 Median:6465.35 stddev:740.14 (11.4%) stddev-sans-first:718.24 12:47:54 INFO - PID 31001 | Values: 3854.2 3855.8 3870.6 3876.5 3897.9 6097.6 6164.0 6181.4 6204.2 6276.3 6215.2 6224.7 6277.5 6299.6 6301.3 5508.6 5596.3 5601.5 5603.5 5605.4 5626.8 5679.9 5703.2 5747.4 5753.2 5650.9 5693.6 5710.2 5755.6 5758.4 5512.4 5602.3 5610.5 5614.5 5680.2 5601.4 5643.0 5655.4 5670.8 5672.2 6210.3 6216.0 6224.0 6302.5 6322.2 6674.1 6765.2 6768.3 6776.6 6780.9 6757.6 6784.8 6812.1 6815.1 6837.9 6301.1 6358.6 6360.4 6379.1 6379.6 6839.2 6909.9 6922.7 6999.5 7009.6 6565.2 6624.9 6657.3 6657.9 6743.1 6786.5 6798.3 6802.7 6803.6 6875.1 6736.9 6836.9 6850.2 6853.5 6866.7 6753.9 6767.2 6769.5 6841.4 6858.0 4845.2 4868.0 4917.7 4944.3 4959.2 4428.0 4465.1 4510.3 4535.9 4546.2 6166.0 6255.7 6329.4 6337.1 6354.7 6597.9 6700.7 6809.1 6876.3 6886.8 6083.2 6285.6 6312.1 6331.1 6335.3 5474.6 5544.3 5552.9 5556.6 5558.5 6802.4 7026.4 7036.7 7119.0 7128.8 6749.8 6758.4 6827.4 6857.9 6864.0 7059.1 7081.8 7155.1 7172.9 7185.0 6699.1 6702.8 6728.9 6790.5 6792.4 6626.6 6773.1 6785.1 6795.5 6801.7 6550.0 6563.2 6593.7 6609.4 6631.7 6531.2 6534.0 6593.9 6597.3 6615.0 6382.0 6438.9 6465.4 6470.0 6489.7 6558.1 6590.7 6594.3 6610.4 6621.0 6349.3 6396.4 6416.2 6417.1 6418.6 12:47:54 INFO - PID 31001 | 12:47:54 INFO - PID 31001 | [#1] ext.html Cycles:180 Average:41803.93 Median:18953.39 stddev:63692.63 (336.0%) stddev-sans-first:63797.43 12:47:54 INFO - PID 31001 | Values: 950.5 950.9 978.6 989.0 1005.4 41857.6 42775.0 42902.6 42911.8 42936.0 40289.6 40618.8 40638.6 40715.4 40731.0 7462.6 7557.5 7584.8 7586.1 7586.5 7534.9 7571.2 7608.0 7614.6 7619.5 7967.3 8179.6 8201.6 8202.5 8206.3 8117.6 8171.5 8175.9 8188.7 8189.1 8014.3 8048.0 8089.2 8136.1 8211.5 38640.2 38869.8 39026.2 39107.6 39110.4 3674.5 3707.5 3722.1 3724.4 3724.6 32091.5 32298.2 32349.0 32374.5 32403.8 5430.3 5437.4 5456.2 5474.5 5505.9 216707.0 216804.0 217045.0 217729.0 218122.0 51230.5 51497.7 51639.5 51643.2 51718.5 53405.0 53460.0 53507.0 53509.0 53582.0 15668.2 15972.0 15990.9 16025.8 16037.2 12896.1 12898.2 12898.8 12899.3 12910.9 14947.9 15008.2 15013.8 15042.5 15054.4 5992.3 6037.0 6058.2 6164.0 6196.6 1107.9 1115.6 1117.3 1123.7 1133.5 25435.5 25461.2 25530.6 25570.4 25686.0 1212.9 1214.5 1217.9 1218.1 1250.0 13304.0 13367.6 13748.1 13837.7 13898.2 268596.0 269227.0 269938.0 270491.0 270832.0 55673.4 59453.4 60615.4 60741.7 60884.0 1509.2 1530.7 1538.6 1543.6 1544.6 29841.0 29895.6 30241.7 30342.0 30870.8 39883.6 39933.2 39947.0 40016.8 40033.4 242081.0 245443.0 246122.0 246220.0 246748.0 33358.0 33607.3 33674.5 33825.5 33873.5 42299.4 43701.8 44082.8 44315.0 44369.0 33303.7 33387.2 33509.5 33689.3 34232.3 32522.0 32974.0 33024.0 33416.5 33453.7 37620.6 38341.8 38638.6 39114.4 39150.0 31753.4 32199.0 32286.4 32493.8 32599.7 25199.4 25394.7 25602.7 25804.5 26084.3 12:47:54 INFO - PID 31001 | 12:47:54 INFO - PID 31001 | [#2] jquery.html Cycles:180 Average:40529.49 Median:22293.39 stddev:105881.12 (474.9%) stddev-sans-first:106135.69 12:47:54 INFO - PID 31001 | Values: 598.0 608.9 610.8 611.3 627.2 23277.1 23982.8 24005.8 24088.4 24125.8 21087.3 23694.8 23694.9 23736.3 23737.0 6504.4 6669.3 6696.4 6697.7 6698.3 6428.1 6581.7 6661.0 6677.6 6704.7 6605.6 6606.8 6663.9 6667.2 6674.1 6303.7 6614.3 6656.9 6664.0 6665.9 6118.6 6130.7 6133.3 6133.6 6137.7 30042.8 30219.7 30343.4 30429.2 30449.2 2090.5 2102.7 2116.0 2121.0 2124.2 20364.1 20486.5 20492.7 20715.3 20901.0 2500.3 2511.6 2567.2 2570.4 2571.3 138175.3 144178.0 149892.0 154526.0 155731.4 15663.1 15668.5 15715.0 16216.1 16574.4 25874.6 25881.7 25881.9 25931.1 25933.2 10285.2 10511.6 10525.0 10583.9 10614.6 9498.5 9500.9 9680.8 9729.9 9809.8 9595.6 9876.9 9898.0 9951.7 10012.9 2902.5 2921.2 2928.3 2937.7 3014.0 1008.5 1009.9 1034.0 1034.8 1046.1 20232.9 20371.3 20541.2 20637.4 20967.9 1065.5 1068.8 1075.5 1076.7 1077.8 11187.9 11296.5 11353.6 11383.8 11413.0 627474.0 631043.0 633285.0 641821.0 642240.0 121480.4 125105.0 126825.0 128301.4 128850.0 1213.9 1257.6 1303.9 1314.4 1329.9 21843.6 22071.3 22226.9 22250.1 22336.7 63223.0 65785.3 65942.0 66161.7 67993.7 83712.6 84233.0 85183.0 85805.0 85983.6 15340.5 15368.5 15423.7 15446.6 15464.8 23395.3 23667.2 23672.2 23750.3 23754.9 19158.6 19161.7 19552.3 19648.5 19669.5 16207.0 16700.6 17257.0 17337.7 17473.5 19885.9 20111.3 20117.8 20148.8 20236.0 15116.4 15205.5 15316.8 15419.7 15430.8 19298.7 19437.1 19509.0 19535.6 19686.8 12:47:54 INFO - PID 31001 | 12:47:54 INFO - PID 31001 | [#3] mootools.html Cycles:180 Average:10201.65 Median:2741.92 stddev:7637.01 (278.5%) stddev-sans-first:7629.95 12:47:54 INFO - PID 31001 | Values: 1422.6 1455.4 1460.9 1471.9 1487.0 14189.9 14662.9 14788.0 17096.0 17558.3 16965.2 17259.0 17286.8 17291.7 17297.1 2740.4 2770.4 2822.7 2824.4 2891.8 2881.2 2919.3 2919.7 2921.1 2923.2 2857.8 2874.1 2883.0 2885.5 2909.3 2743.5 2763.9 2764.9 2777.2 2777.8 2658.5 2669.3 2693.0 2698.6 2701.0 15955.4 16031.4 16045.1 16058.3 16117.6 8246.9 8468.4 8499.4 8519.4 8536.2 5228.7 5263.8 5357.5 5417.5 5423.9 2058.1 2078.4 2123.5 2133.9 2150.3 29621.4 29958.1 29980.4 30087.2 30489.8 7127.0 7145.4 7170.1 7275.8 7373.4 19464.1 19530.7 19550.4 19560.8 19576.4 8056.6 8168.4 8202.5 8222.5 8225.4 7439.7 7511.5 7529.3 7668.2 7746.8 5293.4 5473.3 5473.4 5522.7 5535.8 1547.8 1548.9 1561.7 1562.4 1563.3 975.4 977.1 983.7 985.9 1012.5 8219.1 8307.1 8318.9 8396.2 8443.8 782.2 784.8 788.8 796.5 796.7 5043.5 5496.8 5640.2 5666.3 5718.1 24345.1 25250.5 25347.2 25552.5 25619.1 23932.6 25028.1 25267.0 25301.6 25309.0 10557.3 10862.7 12337.2 12421.3 12437.1 9334.0 9680.8 9702.9 9792.7 9920.6 19763.4 19965.7 20097.5 20384.4 20768.5 20818.4 22310.7 22311.1 22470.4 22489.8 11049.7 11180.0 11182.0 11182.4 11198.2 15199.5 15435.2 15519.5 15523.1 15682.1 12196.9 12239.3 12294.0 12437.6 12628.2 11947.5 11951.0 12279.8 12518.9 12634.7 9978.4 10130.7 10267.4 10287.3 10295.0 8165.6 8173.8 8219.0 8253.5 8335.7 8100.6 8135.7 8169.0 8248.0 8303.0 12:47:54 INFO - PID 31001 | 12:47:54 INFO - PID 31001 | [#4] prototype.html Cycles:180 Average:5583.68 Median:6217.92 stddev:1562.71 (25.1%) stddev-sans-first:1520.80 12:47:54 INFO - PID 31001 | Values: 553.4 556.5 558.2 582.8 586.0 6259.4 6273.6 6293.3 6334.9 6364.7 6185.2 6195.8 6239.9 6275.7 6294.4 5070.3 5077.7 5107.6 5122.3 5125.2 4820.9 5077.2 5135.4 5136.9 5154.8 4846.6 4881.6 4885.2 4890.8 4942.3 4930.6 4936.4 4943.2 4950.3 4963.7 4370.0 4423.3 4431.1 4481.1 4485.8 6001.7 6063.7 6126.6 6152.7 6178.3 5707.7 5722.0 5737.0 5763.7 5779.4 6600.8 6604.9 6608.5 6656.6 6694.8 4983.0 4989.4 4994.4 5019.4 5029.6 6711.0 6731.9 6832.4 6922.3 6975.2 5073.7 5108.6 5125.0 5142.5 5153.6 5194.5 5198.2 5199.9 5203.7 5223.3 5731.2 5752.7 5755.1 5780.2 5806.7 6654.5 6670.4 6679.2 6687.8 6689.5 1793.7 1806.4 1809.9 1813.3 1817.9 1228.6 1260.8 1276.4 1279.2 1290.8 6188.4 6190.7 6193.0 6217.0 6218.8 6632.6 6653.2 6666.6 6696.5 6719.2 5901.7 5950.1 5962.7 6017.6 6064.5 3464.5 3502.6 3513.5 3516.1 3540.0 6956.0 6961.0 6974.3 7001.8 7061.0 6772.7 6823.5 6883.1 6893.5 6896.2 6877.2 6952.2 6955.7 6968.0 6985.8 6617.6 6619.8 6650.6 6676.6 6700.2 6697.3 6723.8 6740.2 6753.8 6798.6 6815.5 6942.7 6998.3 7004.9 7032.8 5019.5 5058.8 5065.4 5065.5 5084.9 6433.3 6572.4 6634.5 6652.3 6692.5 6355.8 6398.9 6409.3 6452.9 6503.5 6345.9 6393.0 6428.6 6433.4 6523.3 6295.2 6295.4 6327.6 6329.0 6414.9 6504.8 6540.9 6565.7 6567.1 6569.9 6370.2 6391.1 6400.9 6434.8 6447.5 12:47:54 INFO - PID 31001 | 12:47:54 INFO - PID 31001 | [#5] yui.html Cycles:180 Average:27867.55 Median:17790.64 stddev:41703.67 (234.4%) stddev-sans-first:41771.73 12:47:54 INFO - PID 31001 | Values: 963.4 988.0 1002.6 1005.2 1010.5 18705.6 18910.5 19028.6 19040.2 19057.1 18362.9 19954.3 20409.7 20465.2 20475.9 1023.6 1059.3 1062.3 1066.0 1068.8 1062.9 1072.4 1073.2 1074.6 1075.8 1024.6 1113.6 1115.7 1117.9 1121.1 1107.6 1110.0 1110.8 1111.2 1111.8 777.5 805.3 809.7 810.5 816.2 19322.7 19377.7 19388.6 19388.9 19390.9 23376.6 23557.4 24278.4 24342.6 24537.0 16166.4 16652.3 16729.0 16767.1 16865.8 3793.4 3816.9 3834.6 3836.3 3851.4 131344.0 162328.0 165725.0 167011.0 167281.2 18439.7 18541.5 18726.7 18838.8 19225.4 35154.8 35156.0 35207.9 35290.5 35692.6 9754.7 9767.5 9785.0 9793.7 9809.0 4567.5 4645.8 4697.6 4704.4 4752.6 6118.2 6233.2 6241.1 6250.1 6266.2 1192.4 1200.4 1205.2 1213.7 1233.0 744.3 757.1 773.9 777.6 778.6 13207.9 13236.4 13307.7 13368.0 13425.9 737.6 738.4 742.2 747.4 756.2 6058.7 6110.4 6124.2 6139.9 6203.8 141856.0 144125.0 145836.0 146381.3 148874.0 128705.4 128747.0 129218.0 130288.3 135122.0 108064.0 108796.0 111283.4 113260.0 113336.0 11312.8 11448.4 11570.6 11756.8 11789.8 55506.7 56572.7 56852.0 57226.4 57868.7 70783.3 71598.0 72060.0 73386.3 73513.3 15321.0 15406.7 15548.5 15553.2 15766.0 17300.5 17681.3 17754.6 17826.7 18007.5 18034.8 18246.8 18331.5 18433.4 18455.6 15893.2 15909.4 16002.8 16115.0 16191.2 16095.5 16246.0 16331.8 16343.3 16452.2 13169.4 13413.3 13447.7 13591.7 13633.9 13897.8 13933.4 13993.4 14078.9 14125.6 12:47:54 INFO - PID 31001 | -------- Summary: end -------- 12:47:54 INFO - PID 31001 | 12:47:55 INFO - TEST-INFO | 31001: exit 0 12:47:55 INFO - TEST-OK | dromaeo_css | took 1108240ms 12:47:55 INFO - TEST-START | kraken 12:47:55 INFO - Initialising browser for kraken test... 12:47:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57997/getInfo.html -profile /tmp/tmp4l2_dz/profile 12:47:55 INFO - TEST-INFO | started process 31377 (/builds/slave/test/build/application/firefox/firefox http://localhost:57997/getInfo.html) 12:47:59 INFO - TEST-INFO | 31377: exit 0 12:48:00 INFO - Browser initialized. 12:48:00 INFO - Running cycle 1/1 for kraken test... 12:48:00 INFO - Using env: {'DISPLAY': ':0', 12:48:00 INFO - 'HOME': '/home/cltbld', 12:48:00 INFO - 'JSGC_DISABLE_POISONING': '1', 12:48:00 INFO - 'LANG': 'en_US.UTF-8', 12:48:00 INFO - 'LANGUAGE': 'en_US:en', 12:48:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:48:00 INFO - 'LOGNAME': 'cltbld', 12:48:00 INFO - 'MAIL': '/var/mail/cltbld', 12:48:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:48:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:48:00 INFO - 'MOZ_CRASHREPORTER': '1', 12:48:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:48:00 INFO - 'MOZ_NO_REMOTE': '1', 12:48:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:48:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:48:00 INFO - 'NO_EM_RESTART': '1', 12:48:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:48:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:00 INFO - 'PWD': '/builds/slave/test', 12:48:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:48:00 INFO - 'RUST_BACKTRACE': 'full', 12:48:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:48:00 INFO - 'SHELL': '/bin/bash', 12:48:00 INFO - 'SHLVL': '1', 12:48:00 INFO - 'TERM': 'linux', 12:48:00 INFO - 'TMOUT': '86400', 12:48:00 INFO - 'USER': 'cltbld', 12:48:00 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465', 12:48:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:00 INFO - '_': '/tools/buildbot/bin/python'} 12:48:00 INFO - TEST-INFO | started process 31615 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4l2_dz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:48:00 INFO - PID 31615 | 12:48:00 INFO - PID 31615 | (/builds/slave/test/build/application/firefox/firefox:31665): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:48:00 INFO - PID 31615 | 12:48:11 INFO - PID 31615 | RSS: Main: 147693568 12:48:11 INFO - PID 31615 | 12:48:13 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57997/tests/kraken/driver.html?testName=audio-beat-detection) 12:48:14 INFO - PID 31615 | RSS: Main: 152698880 12:48:14 INFO - PID 31615 | 12:48:17 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57997/tests/kraken/driver.html?testName=audio-dft) 12:48:17 INFO - PID 31615 | RSS: Main: 153559040 12:48:17 INFO - PID 31615 | 12:48:20 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57997/tests/kraken/driver.html?testName=audio-fft) 12:48:21 INFO - PID 31615 | RSS: Main: 149553152 12:48:21 INFO - PID 31615 | 12:48:23 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57997/tests/kraken/driver.html?testName=audio-oscillator) 12:48:23 INFO - PID 31615 | RSS: Main: 151199744 12:48:23 INFO - PID 31615 | 12:48:25 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57997/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:48:26 INFO - PID 31615 | RSS: Main: 150618112 12:48:26 INFO - PID 31615 | 12:48:29 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57997/tests/kraken/driver.html?testName=imaging-darkroom) 12:48:30 INFO - PID 31615 | RSS: Main: 150937600 12:48:30 INFO - PID 31615 | 12:48:33 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57997/tests/kraken/driver.html?testName=imaging-desaturate) 12:48:34 INFO - PID 31615 | RSS: Main: 151945216 12:48:34 INFO - PID 31615 | 12:48:37 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57997/tests/kraken/driver.html?testName=json-parse-financial) 12:48:37 INFO - PID 31615 | RSS: Main: 153444352 12:48:37 INFO - PID 31615 | 12:48:39 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57997/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:48:40 INFO - PID 31615 | RSS: Main: 153120768 12:48:40 INFO - PID 31615 | 12:48:41 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:48:42 INFO - PID 31615 | RSS: Main: 152985600 12:48:42 INFO - PID 31615 | 12:48:44 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:48:44 INFO - PID 31615 | RSS: Main: 150056960 12:48:44 INFO - PID 31615 | 12:48:47 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:48:47 INFO - PID 31615 | RSS: Main: 150548480 12:48:47 INFO - PID 31615 | 12:48:50 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:48:51 INFO - PID 31615 | RSS: Main: 150761472 12:48:51 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | Cycle 1(1): loaded http://localhost:57997/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57997/tests/kraken/driver.html?testName=ai-astar) 12:48:53 INFO - PID 31615 | RSS: Main: 152449024 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | __start_tp_report 12:48:53 INFO - PID 31615 | _x_x_mozilla_page_load 12:48:53 INFO - PID 31615 | _x_x_mozilla_page_load_details 12:48:53 INFO - PID 31615 | |i|pagename|runs| 12:48:53 INFO - PID 31615 | |0;ai-astar;106;105;105;105;105;105;108;105;105;110 12:48:53 INFO - PID 31615 | |1;audio-beat-detection;119;114;114;113;119;116;117;115;115;141 12:48:53 INFO - PID 31615 | |2;audio-dft;161;162;164;163;163;163;162;162;163;165 12:48:53 INFO - PID 31615 | |3;audio-fft;74;74;79;75;74;73;74;73;73;78 12:48:53 INFO - PID 31615 | |4;audio-oscillator;78;78;78;79;78;78;78;77;77;79 12:48:53 INFO - PID 31615 | |5;imaging-gaussian-blur;113;114;113;115;116;113;113;114;113;114 12:48:53 INFO - PID 31615 | |6;imaging-darkroom;150;151;151;151;150;149;150;148;149;148 12:48:53 INFO - PID 31615 | |7;imaging-desaturate;98;94;93;94;94;93;93;94;94;97 12:48:53 INFO - PID 31615 | |8;json-parse-financial;43;44;43;45;45;45;48;43;44;43 12:48:53 INFO - PID 31615 | |9;json-stringify-tinderbox;37;37;38;38;37;37;37;38;37;37 12:48:53 INFO - PID 31615 | |10;stanford-crypto-aes;71;72;72;73;74;76;71;72;72;71 12:48:53 INFO - PID 31615 | |11;stanford-crypto-ccm;104;107;102;104;110;108;113;108;108;103 12:48:53 INFO - PID 31615 | |12;stanford-crypto-pbkdf2;162;154;152;160;151;163;152;160;156;152 12:48:53 INFO - PID 31615 | |13;stanford-crypto-sha256-iterative;62;58;59;62;63;63;61;59;71;60 12:48:53 INFO - PID 31615 | __end_tp_report 12:48:53 INFO - PID 31615 | __start_cc_report 12:48:53 INFO - PID 31615 | _x_x_mozilla_cycle_collect,713 12:48:53 INFO - PID 31615 | __end_cc_report 12:48:53 INFO - PID 31615 | __startTimestamp1505936933248__endTimestamp 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | ------- Summary: start ------- 12:48:53 INFO - PID 31615 | Number of tests: 14 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#0] ai-astar Cycles:10 Average:105.90 Median:105.00 stddev:1.73 (1.6%) stddev-sans-first:1.83 12:48:53 INFO - PID 31615 | Values: 106.0 105.0 105.0 105.0 105.0 105.0 108.0 105.0 105.0 110.0 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#1] audio-beat-detection Cycles:10 Average:118.30 Median:116.50 stddev:8.23 (7.1%) stddev-sans-first:8.73 12:48:53 INFO - PID 31615 | Values: 119.0 114.0 114.0 113.0 119.0 116.0 117.0 115.0 115.0 141.0 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#2] audio-dft Cycles:10 Average:162.80 Median:163.00 stddev:1.14 (0.7%) stddev-sans-first:1.00 12:48:53 INFO - PID 31615 | Values: 161.0 162.0 164.0 163.0 163.0 163.0 162.0 162.0 163.0 165.0 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#3] audio-fft Cycles:10 Average:74.70 Median:74.00 stddev:2.11 (2.9%) stddev-sans-first:2.22 12:48:53 INFO - PID 31615 | Values: 74.0 74.0 79.0 75.0 74.0 73.0 74.0 73.0 73.0 78.0 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:78.00 stddev:0.67 (0.9%) stddev-sans-first:0.71 12:48:53 INFO - PID 31615 | Values: 78.0 78.0 78.0 79.0 78.0 78.0 78.0 77.0 77.0 79.0 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:1.03 (0.9%) stddev-sans-first:1.05 12:48:53 INFO - PID 31615 | Values: 113.0 114.0 113.0 115.0 116.0 113.0 113.0 114.0 113.0 114.0 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:1.16 (0.8%) stddev-sans-first:1.22 12:48:53 INFO - PID 31615 | Values: 150.0 151.0 151.0 151.0 150.0 149.0 150.0 148.0 149.0 148.0 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.00 stddev:1.71 (1.8%) stddev-sans-first:1.22 12:48:53 INFO - PID 31615 | Values: 98.0 94.0 93.0 94.0 94.0 93.0 93.0 94.0 94.0 97.0 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#8] json-parse-financial Cycles:10 Average:44.30 Median:44.50 stddev:1.57 (3.5%) stddev-sans-first:1.59 12:48:53 INFO - PID 31615 | Values: 43.0 44.0 43.0 45.0 45.0 45.0 48.0 43.0 44.0 43.0 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#9] json-stringify-tinderbox Cycles:10 Average:37.30 Median:37.00 stddev:0.48 (1.3%) stddev-sans-first:0.50 12:48:53 INFO - PID 31615 | Values: 37.0 37.0 38.0 38.0 37.0 37.0 37.0 38.0 37.0 37.0 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#10] stanford-crypto-aes Cycles:10 Average:72.40 Median:72.00 stddev:1.58 (2.2%) stddev-sans-first:1.59 12:48:53 INFO - PID 31615 | Values: 71.0 72.0 72.0 73.0 74.0 76.0 71.0 72.0 72.0 71.0 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#11] stanford-crypto-ccm Cycles:10 Average:106.70 Median:108.00 stddev:3.43 (3.2%) stddev-sans-first:3.50 12:48:53 INFO - PID 31615 | Values: 104.0 107.0 102.0 104.0 110.0 108.0 113.0 108.0 108.0 103.0 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.20 Median:158.00 stddev:4.64 (2.9%) stddev-sans-first:4.42 12:48:53 INFO - PID 31615 | Values: 162.0 154.0 152.0 160.0 151.0 163.0 152.0 160.0 156.0 152.0 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:3.68 (5.9%) stddev-sans-first:3.90 12:48:53 INFO - PID 31615 | Values: 62.0 58.0 59.0 62.0 63.0 63.0 61.0 59.0 71.0 60.0 12:48:53 INFO - PID 31615 | -------- Summary: end -------- 12:48:53 INFO - PID 31615 | 12:48:53 INFO - PID 31615 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:48:53 INFO - TEST-INFO | 31615: exit 0 12:48:53 INFO - TEST-OK | kraken | took 58758ms 12:48:53 INFO - SUITE-END | took 1166s 12:48:53 INFO - Completed test suite (00:19:27) 12:48:53 INFO - javascript benchmark 12:48:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8959.201076746842, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3854.2484215577956, 3855.807209639517, 3870.5548861880884, 3876.4766756487875, 3897.922041559167, 6097.603655762372, 6164.044573091167, 6181.350958149397, 6204.224471941007, 6276.340984196657, 6215.223097112861, 6224.719887605057, 6277.497800175986, 6299.590526615778, 6301.2753533343675, 5508.641938274, 5596.27248457701, 5601.495865372116, 5603.523700485463, 5605.439456054402, 5626.774929002835, 5679.914801277984, 5703.230063941356, 5747.3677895431465, 5753.165790960308, 5650.886982260352, 5693.601447898649, 5710.229119068935, 5755.597108202427, 5758.395368486312, 5512.4211957744665, 5602.299712535933, 5610.523105536035, 5614.466625670555, 5680.233168522238, 5601.3558440779425, 5643.0, 5655.3779084300695, 5670.773169073233, 5672.205891175238, 6210.285817130997, 6216.0, 6224.0, 6302.527310451735, 6322.241331040304, 6674.098996635441, 6765.154355705537, 6768.255491895888, 6776.593404395752, 6780.93219067808, 6757.560758550679, 6784.796456106275, 6812.14848143982, 6815.148106486689, 6837.897431538531, 6301.14934483848, 6358.618482891041, 6360.427561519486, 6379.138816259792, 6379.617222966637, 6839.247682754893, 6909.861802764017, 6922.653867306614, 6999.475039372068, 7009.614471204132, 6565.24499682533, 6624.86750264992, 6657.334266573401, 6657.866842663119, 6743.123393958754, 6786.471035061741, 6798.252192258848, 6802.65986700663, 6803.625800581016, 6875.07186529812, 6736.8989465158065, 6836.897446538306, 6850.246472887979, 6853.451723862178, 6866.725330986705, 6753.8649227015185, 6767.1879374475375, 6769.492288078315, 6841.418479429304, 6858.039874417585, 4845.248986407112, 4867.975660121748, 4917.704937703749, 4944.332515110414, 4959.156943319572, 4427.977860110679, 4465.084657645175, 4510.345999830006, 4535.931961020588, 4546.204414227563, 6165.969170154121, 6255.71849266775, 6329.430351268408, 6337.112804207414, 6354.682265886687, 6597.86804263912, 6700.664966751643, 6809.114815074008, 6876.346747059021, 6886.793396198122, 6083.239595050619, 6285.560010799291, 6312.147860038928, 6331.113644089831, 6335.271443783931, 5474.589405794582, 5544.306961629796, 5552.861178470571, 5556.555475561945, 5558.527525160407, 6802.3537763912345, 7026.437884969292, 7036.683349249296, 7119.038929744522, 7128.7861364158125, 6749.797506074826, 6758.391744742997, 6827.419669328163, 6857.902735562286, 6864.0, 7059.117610298713, 7081.787546373617, 7155.141383033861, 7172.856542869009, 7184.9640751795905, 6699.062131301719, 6702.83242918931, 6728.899066514105, 6790.45676345901, 6792.354726300993, 6626.635535045619, 6773.05177275172, 6785.050092987084, 6795.490338224752, 6801.659917004031, 6549.967250163719, 6563.245226798788, 6593.703283352356, 6609.438197753245, 6631.734730610721, 6531.183602049744, 6534.0, 6593.944968804968, 6597.27429982692, 6614.966925165343, 6381.968090159705, 6438.871222575616, 6465.353464653497, 6469.997150441689, 6489.707963141575, 6558.114654521769, 6590.670466476561, 6594.274629790623, 6610.438112760276, 6621.039949207434, 6349.269833969152, 6396.424321810873, 6416.165898433173, 6417.133686952202, 6418.550701450854], "value": 6195.463814276787, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [950.4867371619326, 950.9001554836742, 978.6476868327401, 989.0356902020526, 1005.4319309590081, 41857.58142418575, 42775.0, 42902.57097429025, 42911.78544107283, 42936.0, 40289.59710402895, 40618.78143655695, 40638.59361406385, 40715.38926916099, 40731.0, 7462.589557574332, 7557.47097703161, 7584.848303033936, 7586.089669239699, 7586.5068770530015, 7534.9246507535045, 7571.205023472538, 7608.0, 7614.619269036553, 7619.504732192389, 7967.322777563885, 8179.631916563769, 8201.630926608286, 8202.507849529047, 8206.261436470717, 8117.594120293962, 8171.509709417424, 8175.918240817575, 8188.713395031145, 8189.1401402853035, 8014.278714915686, 8047.95976020122, 8089.15063918286, 8136.064352599408, 8211.548364839931, 38640.22719545622, 38869.805650971844, 39026.21947561033, 39107.608923910964, 39110.41334379987, 3674.4855720199193, 3707.480952666629, 3722.1253005543676, 3724.404095344732, 3724.571674257441, 32091.518627220612, 32298.192545186324, 32349.0, 32374.514382284284, 32403.83798081018, 5430.348358197041, 5437.429069947678, 5456.154296084113, 5474.507294343528, 5505.944940550583, 216707.0, 216804.0, 217045.0, 217729.0, 218122.0, 51230.48769512332, 51497.74251128795, 51639.48360516422, 51643.22535161975, 51718.48281517137, 53404.99462540325, 53460.0, 53507.0, 53509.0, 53582.0, 15668.216589170495, 15972.0, 15990.920045399698, 16025.759613605805, 16037.198140092949, 12896.097273190786, 12898.161619494704, 12898.80651790224, 12899.290539020443, 12910.902573281377, 14947.878909081863, 15008.174550399834, 15013.849861501463, 15042.548723538312, 15054.397824087133, 5992.280926288786, 6036.9698151508965, 6058.242719660043, 6163.96918015416, 6196.597221180609, 1107.8725946516254, 1115.6485707002269, 1117.2905205194597, 1123.6516679829278, 1133.4502766158407, 25435.491290174094, 25461.236162915142, 25530.617040744404, 25570.360740981254, 25686.0, 1212.9265599944113, 1214.5141943222782, 1217.9025677945742, 1218.1351240619083, 1249.9937500312622, 13304.0, 13367.598972030855, 13748.106373085717, 13837.654058648613, 13898.23559704226, 268596.0, 269227.0, 269938.0, 270491.0, 270832.0, 55673.443265567636, 59453.40546594479, 60615.39384606186, 60741.69629151915, 60884.0, 1509.2001239343165, 1530.7474266746085, 1538.5615099696533, 1543.6449616588247, 1544.5829626001141, 29841.0, 29895.55156672695, 30241.697583023888, 30342.0, 30870.845645771627, 39883.60116398799, 39933.20133597254, 39947.0, 40016.7999160014, 40033.39949900812, 242081.0, 245443.0, 246122.0, 246220.0, 246748.0, 33358.0, 33607.32785344328, 33674.49488257629, 33825.49261761125, 33873.49189762205, 42299.36550951676, 43701.78149109234, 44082.77958610187, 44315.0, 44369.0, 33303.66696333102, 33387.16532086717, 33509.497357540145, 33689.32621347608, 34232.288385581116, 32522.0, 32974.0, 33024.0, 33416.498752518244, 33453.66546334505, 37620.62379376171, 38341.80829095837, 38638.613613863505, 39114.4132838002, 39150.0, 31753.36493270168, 32199.0, 32286.35427291394, 32493.837530812194, 32599.674003259664, 25199.37001574975, 25394.699358785165, 25602.74397256078, 25804.483910322066, 26084.34789130287], "value": 18286.444273187368, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.9701014949252, 608.9360617135198, 610.8045425463853, 611.2847967877583, 627.2253766598251, 23277.068917243312, 23982.76017239827, 24005.75994240057, 24088.397790055256, 24125.758742412592, 21087.2619458319, 23694.76305236947, 23694.881525592347, 23736.287911362644, 23737.0, 6504.4471219946245, 6669.266380698119, 6696.397324240817, 6697.732090716366, 6698.263191049005, 6428.100065990765, 6581.736730530797, 6661.0, 6677.599344039349, 6704.731810727565, 6605.570637908545, 6606.834829129286, 6663.9333606663795, 6667.166604174478, 6674.06563081169, 6303.684815759239, 6614.272430032716, 6656.8668626627195, 6664.0, 6665.866682666344, 6118.571699981003, 6130.693465326761, 6133.325334213233, 6133.570650054498, 6137.693115344259, 30042.84978575115, 30219.69780302213, 30343.393132137455, 30429.239269018453, 30449.23876903073, 2090.5296308330658, 2102.737157855268, 2116.0266277512364, 2121.0455295117104, 2124.1715730864976, 20364.083616237305, 20486.487837804027, 20492.69260961087, 20715.27496537614, 20901.0, 2500.324912273676, 2511.61070034145, 2567.1528395629584, 2570.4345044090455, 2571.2543362424876, 138175.30912345473, 144178.0, 149892.0, 154526.0, 155731.44268557284, 15663.060216387054, 15668.529944101696, 15715.0, 16216.108114053604, 16574.419895303607, 25874.6118808218, 25881.74118258793, 25881.870590647304, 25931.092411765498, 25933.22200333993, 10285.177185825116, 10511.632092876713, 10525.0, 10583.894161058444, 10614.628488002883, 9498.477583732823, 9500.857487137699, 9680.75798105053, 9729.9027009729, 9809.803803923882, 9595.568199431042, 9876.901230987742, 9897.950510247403, 9951.70627818379, 10012.899871001197, 2902.492063888811, 2921.21127295629, 2928.326484908483, 2937.720916512928, 3013.9752484155056, 1008.5411137932223, 1009.8737657792776, 1033.9793204135874, 1034.818906691326, 1046.0951277145214, 20232.89883550602, 20371.33316003104, 20541.178352866016, 20637.380878573666, 20967.895160524404, 1065.5418170186895, 1068.770214403907, 1075.5267682219799, 1076.7308172956762, 1077.8006068877282, 11187.888121118685, 11296.491657875415, 11353.602623908124, 11383.829242561369, 11413.0, 627474.0, 631043.0, 633285.0, 641821.0, 642240.0, 121480.39259803644, 125105.0, 126825.0, 128301.35849320694, 128850.0, 1213.8604060532975, 1257.6352857671172, 1303.8500572434102, 1314.3691028306275, 1329.8803107720353, 21843.56312873702, 22071.33785986423, 22226.888865555567, 22250.109995599996, 22336.664950026086, 63223.0, 65785.34214657792, 65942.0, 66161.66919165374, 67993.66003169953, 83712.58143709242, 84233.0, 85183.0, 85805.0, 85983.5700821517, 15340.463083792014, 15368.538943831703, 15423.691526169638, 15446.613834654223, 15464.768028479357, 23395.298141055795, 23667.17164899289, 23672.171473999017, 23750.287491375286, 23754.881225593763, 19158.61682766365, 19161.712574311117, 19552.315668952087, 19648.508787280432, 19669.50826229356, 16207.0, 16700.58248543796, 17257.0, 17337.653246935242, 17473.47579572615, 19885.900570497055, 20111.29610463627, 20117.798822012177, 20148.798512015277, 20236.0, 15116.395344186549, 15205.543833685007, 15316.846831531542, 15419.691606168039, 15430.845691542941, 19298.7105193425, 19437.12532935993, 19509.0, 19535.609287814448, 19686.80313196907], "value": 12716.555261798383, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.6087825847894, 1455.4469301665365, 1460.8977371583994, 1471.9190444525555, 1486.9702605947882, 14189.929050354747, 14662.853371466283, 14788.038777479456, 17096.0, 17558.29766809329, 16965.15174241289, 17259.0, 17286.827131728678, 17291.654166916687, 17297.13514324282, 2740.3834137319145, 2770.390514086898, 2822.7036161203087, 2824.378636699933, 2891.783116266278, 2881.207667891326, 2919.284775230072, 2919.7080291970847, 2921.079859844153, 2923.210733102061, 2857.828530288179, 2874.1233923653276, 2883.0341835485033, 2885.4950383682876, 2909.2872246299685, 2743.4513097380504, 2763.9309017274527, 2764.8617569121566, 2777.1668499450084, 2777.8333300001864, 2658.5081759874292, 2669.3326668332916, 2693.0305090167526, 2698.6356841826496, 2701.027630053179, 15955.361785528565, 16031.438899638457, 16045.117518536475, 16058.277377517923, 16117.597060073474, 8246.917530824734, 8468.407211495198, 8499.447535910207, 8519.361047921431, 8536.189062039097, 5228.738563071831, 5263.763130659129, 5357.526680162986, 5417.45825417459, 5423.891522169574, 2058.1355830551206, 2078.3972647932083, 2123.4585180778804, 2133.9146434142617, 2150.311900191939, 29621.407571848442, 29958.10125696211, 29980.40039199204, 30087.247818804488, 30489.847550762326, 7126.966589844547, 7145.392641625416, 7170.061185982573, 7275.818104547428, 7373.3732632726815, 19464.124114414884, 19530.70703939442, 19550.413487595393, 19560.8043919559, 19576.412707618794, 8056.637451314705, 8168.387370947203, 8202.466839655403, 8222.465539739898, 8225.383096267804, 7439.665215065335, 7511.51175173612, 7529.322360987428, 7668.156502784689, 7746.767596972099, 5293.391260005072, 5473.343198816167, 5473.397926228112, 5522.723863806794, 5535.750891209906, 1547.829738728739, 1548.9302981365865, 1561.695469383482, 1562.4375624375625, 1563.2730780187267, 975.3806332978468, 977.0815433492494, 983.6852207293597, 985.9295479493007, 1012.5190534496055, 8219.136990616018, 8307.08622051574, 8318.916810831935, 8396.160383961676, 8443.83112337762, 782.2060608482395, 784.7522439321506, 788.7791418402857, 796.494226166377, 796.7251298302076, 5043.495650435, 5496.780128794883, 5640.153976903415, 5666.263385759908, 5718.113692377605, 24345.14791982272, 25250.494990100462, 25347.239582812545, 25552.488950221265, 25619.10333138331, 23932.641010384858, 25028.124015659363, 25267.0, 25301.62047569288, 25309.0, 10557.313774604627, 10862.728431789268, 12337.198082124632, 12421.254724716546, 12437.067219958539, 9333.953330233306, 9680.75798105053, 9702.85445718329, 9792.706218813448, 9920.603175872884, 19763.40709778709, 19965.700514492004, 20097.497562560467, 20384.388468345973, 20768.48078798042, 20818.375448735955, 22310.665340020238, 22311.107555697592, 22470.438239044153, 22489.77510224877, 11049.668509944715, 11180.0, 11182.0, 11182.440877956233, 11198.216124871507, 15199.544013679608, 15435.2282385878, 15519.45681901128, 15523.146226957626, 15682.137482438575, 12196.878031219929, 12239.265644061385, 12294.0, 12437.564685235973, 12628.179168354027, 11947.522099115939, 11951.0, 12279.815802763145, 12518.93740531328, 12634.704200571483, 9978.351407158512, 10130.746731331776, 10267.435291059064, 10287.279890407599, 10295.0, 8165.632546535301, 8173.836523269621, 8219.0, 8253.504789712637, 8335.749927502186, 8100.554469504234, 8135.674573017013, 8169.0, 8247.95876020616, 8303.04514980785], "value": 7117.799472414366, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.3940335332811, 556.5213916032214, 558.2045585041317, 582.8309790160854, 585.9853503662407, 6259.405356491137, 6273.56085074045, 6293.27627322858, 6334.873302533946, 6364.681765911703, 6185.1650027246305, 6195.783147589836, 6239.875202495948, 6275.717592708328, 6294.402031806971, 5070.315507406508, 5077.720725360104, 5107.642465027449, 5122.334096567441, 5125.179971204608, 4820.903581928377, 5077.162268225738, 5135.35808023997, 5136.871578210537, 5154.768035438396, 4846.5880400166025, 4881.585065269456, 4885.169521181408, 4890.804367825301, 4942.332785074005, 4930.556249937505, 4936.3829521309835, 4943.209086546171, 4950.282209079699, 4963.652544321899, 4370.0, 4423.336499525065, 4431.09162621665, 4481.148581769454, 4485.775711214426, 6001.66990815505, 6063.727132279058, 6126.571140020201, 6152.661603611799, 6178.289496707891, 5707.74315155819, 5722.0, 5737.0, 5763.711814409263, 5779.393163717833, 6600.79861267285, 6604.933950660528, 6608.504362172856, 6656.600603963778, 6694.83262918431, 4982.953579748258, 4989.401271847365, 4994.350734404504, 5019.372578427697, 5029.647924645276, 6711.0202490265, 6731.932680673229, 6832.419244364235, 6922.342377474132, 6975.232724400363, 5073.746312684351, 5108.642395032312, 5124.9743751281, 5142.51146141116, 5153.561947234527, 5194.532492075731, 5198.2202669599865, 5199.922001169985, 5203.739813009334, 5223.2948551945765, 5731.166026871361, 5752.6836024019085, 5755.1367294906095, 5780.16187652788, 5806.738696758656, 6654.534182607171, 6670.366315200048, 6679.231888332807, 6687.799366019027, 6689.464842812563, 1793.6592047511192, 1806.3948577226513, 1809.9185536650882, 1813.3018787766805, 1817.9363722269657, 1228.5912285912216, 1260.7667581497453, 1276.4447465352416, 1279.2452453052745, 1290.7612091763053, 6188.400871633835, 6190.721417536221, 6193.049189505382, 6216.990041423347, 6218.844528886723, 6632.5688830226845, 6653.234877988996, 6666.566673166326, 6696.531242812956, 6719.227288861745, 5901.675407852609, 5950.137230101611, 5962.672053037125, 6017.608855424471, 6064.4541991221, 3464.4929455686856, 3502.649735026477, 3513.5432393789156, 3516.1385460562324, 3540.0, 6956.0, 6961.0, 6974.302569742985, 7001.824954376181, 7060.952323826135, 6772.69522871472, 6823.454123670094, 6883.139607549057, 6893.517453778288, 6896.241413444517, 6877.243503214642, 6952.165740111219, 6955.652217389212, 6967.965160174268, 6985.79042628722, 6617.602943823386, 6619.834504137435, 6650.634215118215, 6676.566023208476, 6700.195976482853, 6697.296783837627, 6723.7561051205685, 6740.157480314961, 6753.797386078426, 6798.558093723892, 6815.522913396015, 6942.652867356713, 6998.265182156004, 7004.929950700428, 7032.824179395556, 5019.472955339636, 5058.848234552969, 5065.417476990193, 5065.544101030925, 5084.898302034013, 6433.260175079926, 6572.375624315682, 6634.502412318999, 6652.268250492345, 6692.531522793358, 6355.777547785805, 6398.936010639834, 6409.327020662763, 6452.870942581216, 6503.512236582181, 6345.936540634534, 6393.0, 6428.646424446701, 6433.420992110732, 6523.347665233438, 6295.18162638854, 6295.433410993032, 6327.557071004985, 6329.0, 6414.935850641434, 6504.771898265975, 6540.869182616226, 6565.737370505318, 6567.146270984741, 6569.9343006569325, 6370.235571731422, 6391.137196478416, 6400.935990640034, 6434.8391290218115, 6447.548671592944], "value": 5136.01141345671, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [963.3930623707064, 988.0218583602234, 1002.6290272599135, 1005.2309982863111, 1010.5301035018724, 18705.62588748224, 18910.527236819085, 19028.61942761144, 19040.238390464383, 19057.142428590712, 18362.908185459088, 19954.301599443985, 20409.69385459219, 20465.18139274427, 20475.897620511914, 1023.5649848814261, 1059.2585190366735, 1062.2670357453371, 1066.029912779371, 1068.7915856408003, 1062.8883967183451, 1072.4262519552042, 1073.1682945717055, 1074.6185104287983, 1075.7902209069234, 1024.6413755185663, 1113.5991043224433, 1115.7378016166194, 1117.8826223246565, 1121.1031175059961, 1107.6067995861474, 1110.0120892405766, 1110.8111621024364, 1111.155521803427, 1111.7609713911547, 777.5295945952673, 805.2712295372697, 809.6599428240153, 810.5339429827873, 816.2286639126029, 19322.71015934762, 19377.709334359995, 19388.612227755508, 19388.903055484774, 19390.90304548482, 23376.64935025976, 23557.411064723346, 24278.39304017396, 24342.63486047711, 24537.0, 16166.353345866151, 16652.333906643842, 16729.0, 16767.077810720406, 16865.831341686673, 3793.393057110849, 3816.9427458587866, 3834.5973672764235, 3836.271108489406, 3851.4415409765706, 131344.0, 162328.0, 165725.0, 167011.0, 167281.16359418246, 18439.72340414895, 18541.536461588297, 18726.719099213522, 18838.811611883706, 19225.423237302904, 35154.82422587871, 35156.0, 35207.94376168668, 35290.470642940374, 35692.64307356945, 9754.658586949423, 9767.51162441875, 9785.021497850157, 9793.706188814347, 9809.0, 4567.474740404829, 4645.790939407735, 4697.553732395417, 4704.411948506437, 4752.619790416758, 6118.235220597426, 6233.189685340967, 6241.063840423973, 6250.124982502453, 6266.216722909637, 1192.362086283834, 1200.37580458163, 1205.1503689898775, 1213.6844420450745, 1232.9815052774215, 744.3189481624253, 757.0952711509693, 773.8645736996003, 777.5762209595671, 778.5562229529228, 13207.867921320856, 13236.404361803741, 13307.737684593449, 13368.0, 13425.865741342657, 737.5611511150856, 738.3982054625463, 742.1724776873747, 747.3572727454385, 756.1833220122255, 6058.727357268934, 6110.388961103943, 6124.203853499017, 6139.877202455926, 6203.7828675996125, 141856.0, 144125.0, 145836.0, 146381.26809365884, 148874.0, 128705.35647321703, 128747.0, 129218.0, 130288.3485582585, 135122.0, 108064.0, 108796.0, 111283.44358278156, 113260.0, 113336.0, 11312.773744525228, 11448.42757862087, 11570.59502917394, 11756.764864702829, 11789.823152652889, 55506.72246638741, 56572.71713641405, 56852.0, 57226.42773572377, 57868.71065644645, 70783.29216707767, 71598.0, 72060.0, 73386.26613733794, 73513.26486735279, 15321.004134731207, 15406.691866162839, 15548.455804046804, 15553.222338882784, 15766.0, 17300.48098557045, 17681.292748290438, 17754.5561360967, 17826.732599011284, 18007.459776206735, 18034.819651803315, 18246.817531824512, 18331.45005649833, 18433.354832580797, 18455.630887382446, 15893.205339733198, 15909.363625455315, 16002.759958600398, 16115.0, 16191.190440478165, 16095.517134485985, 16246.0, 16331.836681633507, 16343.346266149221, 16452.177391130157, 13169.407376667881, 13413.329333533089, 13447.663808404868, 13591.728165436834, 13633.931830340785, 13897.791533126809, 13933.442662293395, 13993.370298336396, 14078.859211408164, 14125.576232713034], "value": 9818.48424407728, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1376.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 105.0, 105.0, 105.0, 105.0, 108.0, 105.0, 105.0, 110.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 114.0, 114.0, 113.0, 119.0, 116.0, 117.0, 115.0, 115.0, 141.0], "value": 118.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 164.0, 163.0, 163.0, 163.0, 162.0, 162.0, 163.0, 165.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 79.0, 75.0, 74.0, 73.0, 74.0, 73.0, 73.0, 78.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 79.0, 78.0, 78.0, 78.0, 77.0, 77.0, 79.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 115.0, 116.0, 113.0, 113.0, 114.0, 113.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 151.0, 151.0, 150.0, 149.0, 150.0, 148.0, 149.0, 148.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 94.0, 93.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 97.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 44.0, 43.0, 45.0, 45.0, 45.0, 48.0, 43.0, 44.0, 43.0], "value": 44.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 38.0, 38.0, 37.0, 37.0, 37.0, 38.0, 37.0, 37.0], "value": 37.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 72.0, 73.0, 74.0, 76.0, 71.0, 72.0, 72.0, 71.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 107.0, 102.0, 104.0, 110.0, 108.0, 113.0, 108.0, 108.0, 103.0], "value": 106.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 154.0, 152.0, 160.0, 151.0, 163.0, 152.0, 160.0, 156.0, 152.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 58.0, 59.0, 62.0, 63.0, 63.0, 61.0, 59.0, 71.0, 60.0], "value": 61.8, "unit": "score"}]}]} 12:48:53 INFO - Return code: 0 12:48:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:48:54 INFO - # TBPL SUCCESS # 12:48:54 INFO - Running post-action listener: _package_coverage_data 12:48:54 INFO - Running post-action listener: _resource_record_post_action 12:48:54 INFO - [mozharness: 2017-09-20 19:48:54.024683Z] Finished run-tests step (success) 12:48:54 INFO - Running post-run listener: _resource_record_post_run 12:48:54 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 329912320; Read time: 0; Write time: 176892 12:48:54 INFO - TinderboxPrint: CPU usage
13.1% 12:48:54 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 12:48:54 INFO - TinderboxPrint: I/O write bytes / time
329,912,320 / 176,892 12:48:54 INFO - TinderboxPrint: CPU idle
8,213.8 (86.9%) 12:48:54 INFO - TinderboxPrint: CPU user
1,187.5 (12.6%) 12:48:54 INFO - TinderboxPrint: Swap in / out
0 / 0 12:48:54 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13127680; Read time: 0; Write time: 1552 12:48:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:48:54 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 0; Write bytes: 316784640; Read time: 0; Write time: 175340 12:48:54 INFO - Running post-run listener: _upload_blobber_files 12:48:54 INFO - Blob upload gear active. 12:48:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:48:54 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 12:48:54 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'] 12:48:54 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 12:48:55 INFO - (blobuploader) - INFO - Open directory for files ... 12:48:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:48:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:48:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:48:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:48:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:48:56 INFO - (blobuploader) - INFO - Done attempting. 12:48:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:48:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:48:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:48:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:48:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:48:59 INFO - (blobuploader) - INFO - Done attempting. 12:48:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:48:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:48:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:49:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:00 INFO - (blobuploader) - INFO - Done attempting. 12:49:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:49:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:49:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:01 INFO - (blobuploader) - INFO - Done attempting. 12:49:01 INFO - (blobuploader) - INFO - Iteration through files over. 12:49:01 INFO - Return code: 0 12:49:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:49:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:49:01 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e00d2c20f90fb18b887ae24700b8a809489491f5eddaa552081f3039a4615586a76ab58c9f496af096cf64bae8de94b7ca8b84060df4c4cdc94e33eebb57d077", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81a960a5794d687b76ff13556a2d073b9f605f698a7b4cb7aa2f35ca75a55c50734c5896979717849ad5bd3d31584e617b563128264a0cbe02b63f9ed7f8df0f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a66b1a9e30a4b2cbdc219959a723ec872d4f5cc05f09144f08ebedd24dc2e949c6d3b141e879f3e2b319e6f19f19ea18d47b4ac86d126af0def3ccacd5cd3c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58bfe9d13516040031d826522deff0aac7a2dfc5948be7c81d54dd2bd07e87c7d3005cd2aa6b783ee6d6faf74a54ff99d0bcc0f7e148927cdd844fd002f923f1"} 12:49:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:49:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:49:01 INFO - Contents: 12:49:01 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e00d2c20f90fb18b887ae24700b8a809489491f5eddaa552081f3039a4615586a76ab58c9f496af096cf64bae8de94b7ca8b84060df4c4cdc94e33eebb57d077", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81a960a5794d687b76ff13556a2d073b9f605f698a7b4cb7aa2f35ca75a55c50734c5896979717849ad5bd3d31584e617b563128264a0cbe02b63f9ed7f8df0f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a66b1a9e30a4b2cbdc219959a723ec872d4f5cc05f09144f08ebedd24dc2e949c6d3b141e879f3e2b319e6f19f19ea18d47b4ac86d126af0def3ccacd5cd3c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58bfe9d13516040031d826522deff0aac7a2dfc5948be7c81d54dd2bd07e87c7d3005cd2aa6b783ee6d6faf74a54ff99d0bcc0f7e148927cdd844fd002f923f1"} 12:49:01 INFO - Running post-run listener: copy_logs_to_upload_dir 12:49:01 INFO - Copying logs to upload dir... 12:49:01 INFO - mkdir: /builds/slave/test/build/upload/logs 12:49:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.183344 ========= master_lag: -0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-20 12:49:02.028752) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 12:49:02.033048) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e00d2c20f90fb18b887ae24700b8a809489491f5eddaa552081f3039a4615586a76ab58c9f496af096cf64bae8de94b7ca8b84060df4c4cdc94e33eebb57d077", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81a960a5794d687b76ff13556a2d073b9f605f698a7b4cb7aa2f35ca75a55c50734c5896979717849ad5bd3d31584e617b563128264a0cbe02b63f9ed7f8df0f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a66b1a9e30a4b2cbdc219959a723ec872d4f5cc05f09144f08ebedd24dc2e949c6d3b141e879f3e2b319e6f19f19ea18d47b4ac86d126af0def3ccacd5cd3c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58bfe9d13516040031d826522deff0aac7a2dfc5948be7c81d54dd2bd07e87c7d3005cd2aa6b783ee6d6faf74a54ff99d0bcc0f7e148927cdd844fd002f923f1"} build_url:https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011367 build_url: 'https://queue.taskcluster.net/v1/task/VRcOoJ32QBqWk7yTyN305Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e00d2c20f90fb18b887ae24700b8a809489491f5eddaa552081f3039a4615586a76ab58c9f496af096cf64bae8de94b7ca8b84060df4c4cdc94e33eebb57d077", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81a960a5794d687b76ff13556a2d073b9f605f698a7b4cb7aa2f35ca75a55c50734c5896979717849ad5bd3d31584e617b563128264a0cbe02b63f9ed7f8df0f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a66b1a9e30a4b2cbdc219959a723ec872d4f5cc05f09144f08ebedd24dc2e949c6d3b141e879f3e2b319e6f19f19ea18d47b4ac86d126af0def3ccacd5cd3c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58bfe9d13516040031d826522deff0aac7a2dfc5948be7c81d54dd2bd07e87c7d3005cd2aa6b783ee6d6faf74a54ff99d0bcc0f7e148927cdd844fd002f923f1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 12:49:02.065750) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 12:49:02.066243) ========= 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=9f7544b6a75ada7b6b576032000001a7-1505934467.166703-1262064465 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004554 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 12:49:02.146765) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 12:49:02.147082) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 12:49:02.147409) ========= ========= Total master_lag: 0.21 =========