builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-051 starttime: 1506644065.14 results: success (0) revision: 6dea0ee45b66b850c1ec62301724a67db901f81a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:25.141278) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:25.141750) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:25.142033) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:25.204520) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:25.204894) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-28 17:14: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% 32.6M=0s 2017-09-28 17:14:25 (32.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.223377 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:25.457362) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:25.457714) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016547 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:25.510204) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:25.513553) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6dea0ee45b66b850c1ec62301724a67db901f81a --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 6dea0ee45b66b850c1ec62301724a67db901f81a --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=5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-28 17:14:25,569 truncating revision to first 12 chars 2017-09-28 17:14:25,569 Setting DEBUG logging. 2017-09-28 17:14:25,569 attempt 1/10 2017-09-28 17:14:25,569 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6dea0ee45b66?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-28 17:14:25,980 unpacking tar archive at: mozilla-central-6dea0ee45b66/testing/mozharness/ program finished with exit code 0 elapsedTime=0.704888 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:26.250533) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:26.250850) ========= script_repo_revision: 6dea0ee45b66b850c1ec62301724a67db901f81a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:26.251216) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:26.251480) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 17:14:26.268849) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-28 17:14:26.269235) ========= /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=5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:14:26 INFO - MultiFileLogger online at 20170928 17:14:26 in /builds/slave/test 17:14: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 17:14:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:14:26 INFO - {'append_to_log': False, 17:14:26 INFO - 'base_work_dir': '/builds/slave/test', 17:14:26 INFO - 'blob_upload_branch': 'Firefox', 17:14:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:14:26 INFO - 'branch': 'Firefox', 17:14:26 INFO - 'buildbot_json_path': 'buildprops.json', 17:14:26 INFO - 'code_coverage': False, 17:14:26 INFO - 'config_files': ('talos/linux_config.py',), 17:14:26 INFO - 'default_actions': ('clobber', 17:14:26 INFO - 'read-buildbot-config', 17:14:26 INFO - 'download-and-extract', 17:14:26 INFO - 'populate-webroot', 17:14:26 INFO - 'create-virtualenv', 17:14:26 INFO - 'install', 17:14:26 INFO - 'setup-mitmproxy', 17:14:26 INFO - 'run-tests'), 17:14:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:14:26 INFO - 'disable_ccov_upload': False, 17:14:26 INFO - 'disable_stylo': False, 17:14:26 INFO - 'download_minidump_stackwalk': True, 17:14:26 INFO - 'download_symbols': 'ondemand', 17:14:26 INFO - 'enable_stylo': False, 17:14:26 INFO - 'enable_webrender': False, 17:14:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 17:14:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:14:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:14:26 INFO - 'gecko_profile': False, 17:14:26 INFO - 'gecko_profile_interval': 0, 17:14:26 INFO - 'installer_path': 'installer.exe', 17:14:26 INFO - 'log_level': 'info', 17:14:26 INFO - 'log_name': 'talos', 17:14:26 INFO - 'log_to_console': True, 17:14:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:14:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:14:26 INFO - 'opt_config_files': (), 17:14:26 INFO - 'pip_index': False, 17:14:26 INFO - 'suite': 'dromaeojs-e10s', 17:14:26 INFO - 'system_bits': '32', 17:14:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:14:26 INFO - 'title': 'talos-linux64-ix-051', 17:14:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:14:26 INFO - 'use_talos_json': True, 17:14:26 INFO - 'verify': 'False', 17:14:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:14:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:14:26 INFO - 'work_dir': 'build'} 17:14:26 INFO - [mozharness: 2017-09-29 00:14:26.462137Z] Running clobber step. 17:14:26 INFO - Running pre-action listener: _resource_record_pre_action 17:14:26 INFO - Running main action method: clobber 17:14:26 INFO - rmtree: /builds/slave/test/build 17:14:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:14:27 INFO - Running post-action listener: _resource_record_post_action 17:14:27 INFO - [mozharness: 2017-09-29 00:14:27.457443Z] Finished clobber step (success) 17:14:27 INFO - [mozharness: 2017-09-29 00:14:27.457540Z] Running read-buildbot-config step. 17:14:27 INFO - Running pre-action listener: _resource_record_pre_action 17:14:27 INFO - Running main action method: read_buildbot_config 17:14:27 INFO - Using buildbot properties: 17:14:27 INFO - { 17:14:27 INFO - "project": "", 17:14:27 INFO - "product": "firefox", 17:14:27 INFO - "who": "kwierso@gmail.com", 17:14:27 INFO - "installer_path": "public/build/target.tar.bz2", 17:14:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 17:14:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:14:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 17:14:27 INFO - "stage_platform": "linux64-pgo", 17:14:27 INFO - "basedir": "/builds/slave/test", 17:14:27 INFO - "buildnumber": 216, 17:14:27 INFO - "platform": "ubuntu64_hw", 17:14:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:14:27 INFO - "slavebuilddir": "test", 17:14:27 INFO - "taskId": "NZYMjkUnTbqJqBhnITEdIQ", 17:14:27 INFO - "branch": "mozilla-central", 17:14:27 INFO - "script_repo_revision": "production", 17:14:27 INFO - "revision": "6dea0ee45b66b850c1ec62301724a67db901f81a", 17:14:27 INFO - "slavename": "talos-linux64-ix-051", 17:14:27 INFO - "repo_path": "mozilla-central" 17:14:27 INFO - } 17:14:27 INFO - Finding installer, test and symbols from parent task. 17:14:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NZYMjkUnTbqJqBhnITEdIQ'}, attempt #1 17:14:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NZYMjkUnTbqJqBhnITEdIQ'}, attempt #1 17:14:28 INFO - Task dependencies: crASE74RRjm1WOcvyLYyvw 17:14:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw'}, attempt #1 17:14:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.tar.bz2 17:14:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.test_packages.json 17:14:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.crashreporter-symbols.zip 17:14:28 INFO - Running post-action listener: _resource_record_post_action 17:14:28 INFO - [mozharness: 2017-09-29 00:14:28.908065Z] Finished read-buildbot-config step (success) 17:14:28 INFO - [mozharness: 2017-09-29 00:14:28.908282Z] Running download-and-extract step. 17:14:28 INFO - Running pre-action listener: _resource_record_pre_action 17:14:28 INFO - Running main action method: download_and_extract 17:14:28 INFO - mkdir: /builds/slave/test/build/tests 17:14:28 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')]} 17:14:28 INFO - trying https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.test_packages.json 17:14:28 INFO - Downloading https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:14:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:14:29 INFO - Downloaded 989 bytes. 17:14:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:14:29 INFO - Using the following test package requirements: 17:14:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:14:29 INFO - u'common': [u'target.common.tests.zip'], 17:14:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:14:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:14:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:14:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:14:29 INFO - u'mozbase': [u'target.common.tests.zip'], 17:14:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:14:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:14:29 INFO - u'web-platform': [u'target.common.tests.zip', 17:14:29 INFO - u'target.web-platform.tests.tar.gz'], 17:14:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:14:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 17:14:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.common.tests.zip 17:14:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:14:29 INFO - Fetch https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.common.tests.zip into memory 17:14:39 INFO - Content-Length response header: 55935993 17:14:39 INFO - Bytes received: 55935993 17:14:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.talos.tests.zip 17:14:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:14:49 INFO - Fetch https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.talos.tests.zip into memory 17:14:51 INFO - Content-Length response header: 14521355 17:14:51 INFO - Bytes received: 14521355 17:14: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')]} 17:14:51 INFO - trying https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.tar.bz2 17:14:51 INFO - Downloading https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:14:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:14:56 INFO - Downloaded 62403377 bytes. 17:14:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.tar.bz2 17:14:56 INFO - mkdir: /builds/slave/test/properties 17:14:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:14:56 INFO - Writing to file /builds/slave/test/properties/build_url 17:14:56 INFO - Contents: 17:14:56 INFO - build_url:https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.tar.bz2 17:14:56 INFO - Running post-action listener: _resource_record_post_action 17:14:56 INFO - Running post-action listener: find_tests_for_verification 17:14:56 INFO - Running post-action listener: set_extra_try_arguments 17:14:56 INFO - [mozharness: 2017-09-29 00:14:56.478824Z] Finished download-and-extract step (success) 17:14:56 INFO - [mozharness: 2017-09-29 00:14:56.479067Z] Running populate-webroot step. 17:14:56 INFO - Running pre-action listener: _resource_record_pre_action 17:14:56 INFO - Running main action method: populate_webroot 17:14:56 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:14:56 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:14:56 INFO - 'tests': ['tresize', 17:14:56 INFO - 'tcanvasmark']}, 17:14:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:14:56 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:14:56 INFO - 'tests': ['dromaeo_css', 17:14:56 INFO - 'kraken']}, 17:14:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:14:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:14:56 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:14:56 INFO - 'talos_options': ['--disable-stylo'], 17:14:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:14:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:14:56 INFO - 'tests': ['damp', 'tps']}, 17:14:56 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:14:56 INFO - 'talos_options': ['--disable-stylo'], 17:14:56 INFO - 'tests': ['damp', 'tps']}, 17:14:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:14:56 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:14:56 INFO - 'tests': ['dromaeo_dom']}, 17:14:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:14:56 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:14:56 INFO - 'tests': ['basic_compositor_video', 17:14:56 INFO - 'glvideo']}, 17:14:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:14:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:14:56 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:14:56 INFO - 'talos_options': ['--disable-stylo'], 17:14:56 INFO - 'tests': ['ts_paint_webext', 17:14:56 INFO - 'tp5o_webext']}, 17:14:56 INFO - 'other-e10s': {'tests': ['a11yr', 17:14:56 INFO - 'ts_paint', 17:14:56 INFO - 'tpaint', 17:14:56 INFO - 'sessionrestore', 17:14:56 INFO - 'sessionrestore_many_windows', 17:14:56 INFO - 'sessionrestore_no_auto_restore', 17:14:56 INFO - 'tabpaint', 17:14:56 INFO - 'cpstartup']}, 17:14:56 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:14:56 INFO - 'tests': ['a11yr', 17:14:56 INFO - 'ts_paint', 17:14:56 INFO - 'tpaint', 17:14:56 INFO - 'sessionrestore', 17:14:56 INFO - 'sessionrestore_many_windows', 17:14:56 INFO - 'sessionrestore_no_auto_restore', 17:14:56 INFO - 'tabpaint', 17:14:56 INFO - 'cpstartup']}, 17:14:56 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 17:14:56 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 17:14:56 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:14:56 INFO - 'tests': ['perf_reftest_singletons']}, 17:14:56 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:14:56 INFO - 'tests': ['perf_reftest']}, 17:14:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:14:56 INFO - 'tsvgr_opacity', 17:14:56 INFO - 'tart', 17:14:56 INFO - 'tscrollx', 17:14:56 INFO - 'tsvg_static']}, 17:14:56 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 17:14:56 INFO - 'tests': ['tsvgx', 17:14:56 INFO - 'tsvgr_opacity', 17:14:56 INFO - 'tart', 17:14:56 INFO - 'tscrollx', 17:14:56 INFO - 'tsvg_static']}, 17:14:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:14:56 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:14:56 INFO - 'talos_options': ['--disable-stylo'], 17:14:56 INFO - 'tests': ['tp5o']}, 17:14:56 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:14:56 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:14:56 INFO - 'talos_options': ['--mitmproxy', 17:14:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:14:56 INFO - '--firstNonBlankPaint'], 17:14:56 INFO - 'tests': ['tp6_google', 17:14:56 INFO - 'tp6_youtube', 17:14:56 INFO - 'tp6_amazon', 17:14:56 INFO - 'tp6_facebook']}, 17:14:56 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:14:56 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:14:56 INFO - 'talos_options': ['--disable-stylo', 17:14:56 INFO - '--mitmproxy', 17:14:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:14:56 INFO - '--firstNonBlankPaint'], 17:14:56 INFO - 'tests': ['tp6_google', 17:14:56 INFO - 'tp6_youtube', 17:14:56 INFO - 'tp6_amazon', 17:14:56 INFO - 'tp6_facebook']}, 17:14:56 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:14:56 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 17:14:56 INFO - 'talos_options': ['--stylo-threads=1', 17:14:56 INFO - '--mitmproxy', 17:14:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:14:56 INFO - '--firstNonBlankPaint'], 17:14:56 INFO - 'tests': ['tp6_google', 17:14:56 INFO - 'tp6_youtube', 17:14:56 INFO - 'tp6_amazon', 17:14:56 INFO - 'tp6_facebook']}, 17:14:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:14:56 INFO - 'talos_options': ['--xperf_path', 17:14:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:14:56 INFO - 'tests': ['tp5n']}, 17:14:56 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 17:14:56 INFO - 'talos_options': ['--disable-stylo', 17:14:56 INFO - '--xperf_path', 17:14:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:14:56 INFO - 'tests': ['tp5n']}}} 17:14:56 INFO - Running post-action listener: _resource_record_post_action 17:14:56 INFO - [mozharness: 2017-09-29 00:14:56.495976Z] Finished populate-webroot step (success) 17:14:56 INFO - [mozharness: 2017-09-29 00:14:56.496065Z] Running create-virtualenv step. 17:14:56 INFO - Running pre-action listener: _resource_record_pre_action 17:14:56 INFO - Running main action method: create_virtualenv 17:14:56 INFO - Creating virtualenv /builds/slave/test/build/venv 17:14:56 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:14:56 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 17:14:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:14:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:14:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:14:57 INFO - Installing distribute.............................................................................................................................................................................................done. 17:14:57 INFO - Installing pip...............done. 17:14:57 INFO - Return code: 0 17:14:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:14:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:14:58 INFO - Reading from file tmpfile_stdout 17:14:58 INFO - Output received: 17:14:58 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 17:14:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:14:58 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')]} 17:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:58 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')]} 17:14:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:58 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 0x27881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2950b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2af59c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af2d60>, '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 0x2af3a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af3ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', '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 17:14:58 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 17:14:58 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 17:14:58 INFO - Using env: {'DISPLAY': ':0', 17:14:58 INFO - 'HOME': '/home/cltbld', 17:14:58 INFO - 'LANG': 'en_US.UTF-8', 17:14:58 INFO - 'LANGUAGE': 'en_US:en', 17:14:58 INFO - 'LOGNAME': 'cltbld', 17:14:58 INFO - 'MAIL': '/var/mail/cltbld', 17:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:58 INFO - 'MOZ_NO_REMOTE': '1', 17:14:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:58 INFO - 'NO_EM_RESTART': '1', 17:14:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:58 INFO - 'PWD': '/builds/slave/test', 17:14:58 INFO - 'SHELL': '/bin/bash', 17:14:58 INFO - 'SHLVL': '1', 17:14:58 INFO - 'TERM': 'linux', 17:14:58 INFO - 'TMOUT': '86400', 17:14:58 INFO - 'USER': 'cltbld', 17:14:58 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', 17:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:58 INFO - '_': '/tools/buildbot/bin/python'} 17:14:59 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:14:59 INFO - Downloading/unpacking pip>=1.5 17:14:59 INFO - Running setup.py egg_info for package pip 17:14:59 INFO - warning: no files found matching 'pip/cacert.pem' 17:14:59 INFO - warning: no files found matching '*.html' under directory 'docs' 17:14:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:14:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:14:59 INFO - Installing collected packages: pip 17:14:59 INFO - Found existing installation: pip 1.1 17:14:59 INFO - Uninstalling pip: 17:14:59 INFO - Successfully uninstalled pip 17:14:59 INFO - Running setup.py install for pip 17:14:59 INFO - warning: no files found matching 'pip/cacert.pem' 17:14:59 INFO - warning: no files found matching '*.html' under directory 'docs' 17:14:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 17:14:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 17:14:59 INFO - Installing pip script to /builds/slave/test/build/venv/bin 17:14:59 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 17:14:59 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 17:14:59 INFO - Successfully installed pip 17:14:59 INFO - Cleaning up... 17:14:59 INFO - Return code: 0 17:14:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:14: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')]} 17:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14: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')]} 17:14:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:59 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 0x27881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2950b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2af59c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af2d60>, '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 0x2af3a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af3ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', '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 17:14:59 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 17:14:59 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 17:14:59 INFO - Using env: {'DISPLAY': ':0', 17:14:59 INFO - 'HOME': '/home/cltbld', 17:14:59 INFO - 'LANG': 'en_US.UTF-8', 17:14:59 INFO - 'LANGUAGE': 'en_US:en', 17:14:59 INFO - 'LOGNAME': 'cltbld', 17:14:59 INFO - 'MAIL': '/var/mail/cltbld', 17:14:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:59 INFO - 'MOZ_NO_REMOTE': '1', 17:14:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:59 INFO - 'NO_EM_RESTART': '1', 17:14:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:59 INFO - 'PWD': '/builds/slave/test', 17:14:59 INFO - 'SHELL': '/bin/bash', 17:14:59 INFO - 'SHLVL': '1', 17:14:59 INFO - 'TERM': 'linux', 17:14:59 INFO - 'TMOUT': '86400', 17:14:59 INFO - 'USER': 'cltbld', 17:14:59 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', 17:14:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:59 INFO - '_': '/tools/buildbot/bin/python'} 17:14:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:59 INFO - Downloading/unpacking psutil>=3.1.1 17:14:59 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 17:14:59 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 17:14:59 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 17:14:59 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 17:15:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:15:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:15:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:15:00 INFO - Installing collected packages: psutil 17:15:00 INFO - Running setup.py install for psutil 17:15:00 INFO - building 'psutil._psutil_linux' extension 17:15:00 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 17:15:00 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 17:15:01 INFO - building 'psutil._psutil_posix' extension 17:15:01 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 17:15:01 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 17:15:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:15:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:15:01 INFO - Successfully installed psutil 17:15:01 INFO - Cleaning up... 17:15:01 INFO - Return code: 0 17:15:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:15:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:15:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:15:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2950b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2af59c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af2d60>, '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 0x2af3a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af3ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', '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 17:15:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:15:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:15:01 INFO - Using env: {'DISPLAY': ':0', 17:15:01 INFO - 'HOME': '/home/cltbld', 17:15:01 INFO - 'LANG': 'en_US.UTF-8', 17:15:01 INFO - 'LANGUAGE': 'en_US:en', 17:15:01 INFO - 'LOGNAME': 'cltbld', 17:15:01 INFO - 'MAIL': '/var/mail/cltbld', 17:15:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:01 INFO - 'MOZ_NO_REMOTE': '1', 17:15:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:01 INFO - 'NO_EM_RESTART': '1', 17:15:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:01 INFO - 'PWD': '/builds/slave/test', 17:15:01 INFO - 'SHELL': '/bin/bash', 17:15:01 INFO - 'SHLVL': '1', 17:15:01 INFO - 'TERM': 'linux', 17:15:01 INFO - 'TMOUT': '86400', 17:15:01 INFO - 'USER': 'cltbld', 17:15:01 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', 17:15:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:01 INFO - '_': '/tools/buildbot/bin/python'} 17:15:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:15:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:15:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:15:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:15:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:15:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:15:02 INFO - Installing collected packages: mozsystemmonitor 17:15:02 INFO - Running setup.py install for mozsystemmonitor 17:15:02 INFO - Successfully installed mozsystemmonitor 17:15:02 INFO - Cleaning up... 17:15:02 INFO - Return code: 0 17:15:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:15: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')]} 17:15:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:15: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')]} 17:15:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:15:02 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 0x27881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2950b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2af59c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af2d60>, '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 0x2af3a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af3ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', '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 17:15:02 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 17:15:02 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 17:15:02 INFO - Using env: {'DISPLAY': ':0', 17:15:02 INFO - 'HOME': '/home/cltbld', 17:15:02 INFO - 'LANG': 'en_US.UTF-8', 17:15:02 INFO - 'LANGUAGE': 'en_US:en', 17:15:02 INFO - 'LOGNAME': 'cltbld', 17:15:02 INFO - 'MAIL': '/var/mail/cltbld', 17:15:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:02 INFO - 'MOZ_NO_REMOTE': '1', 17:15:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:02 INFO - 'NO_EM_RESTART': '1', 17:15:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:02 INFO - 'PWD': '/builds/slave/test', 17:15:02 INFO - 'SHELL': '/bin/bash', 17:15:02 INFO - 'SHLVL': '1', 17:15:02 INFO - 'TERM': 'linux', 17:15:02 INFO - 'TMOUT': '86400', 17:15:02 INFO - 'USER': 'cltbld', 17:15:02 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', 17:15:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:02 INFO - '_': '/tools/buildbot/bin/python'} 17:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:03 INFO - Downloading/unpacking jsonschema==2.5.1 17:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:15:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:15:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:15:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:15:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:15: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 17:15: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 17:15: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 17:15: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 17:15:04 INFO - Downloading functools32-3.2.3-2.tar.gz 17:15:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:15:04 INFO - warning: no files found matching '*.txt' 17:15:04 INFO - no previously-included directories found matching 'build' 17:15:04 INFO - no previously-included directories found matching 'dist' 17:15:04 INFO - no previously-included directories found matching '.git*' 17:15:04 INFO - Installing collected packages: jsonschema, functools32 17:15:04 INFO - Running setup.py install for functools32 17:15:04 INFO - warning: no files found matching '*.txt' 17:15:04 INFO - no previously-included directories found matching 'build' 17:15:04 INFO - no previously-included directories found matching 'dist' 17:15:04 INFO - no previously-included directories found matching '.git*' 17:15:04 INFO - Successfully installed jsonschema functools32 17:15:04 INFO - Cleaning up... 17:15:04 INFO - Return code: 0 17:15:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:15:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x27881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2950b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2af59c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af2d60>, '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 0x2af3a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af3ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', '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 17:15:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:15:04 INFO - Using env: {'DISPLAY': ':0', 17:15:04 INFO - 'HOME': '/home/cltbld', 17:15:04 INFO - 'LANG': 'en_US.UTF-8', 17:15:04 INFO - 'LANGUAGE': 'en_US:en', 17:15:04 INFO - 'LOGNAME': 'cltbld', 17:15:04 INFO - 'MAIL': '/var/mail/cltbld', 17:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:04 INFO - 'MOZ_NO_REMOTE': '1', 17:15:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:04 INFO - 'NO_EM_RESTART': '1', 17:15:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:04 INFO - 'PWD': '/builds/slave/test', 17:15:04 INFO - 'SHELL': '/bin/bash', 17:15:04 INFO - 'SHLVL': '1', 17:15:04 INFO - 'TERM': 'linux', 17:15:04 INFO - 'TMOUT': '86400', 17:15:04 INFO - 'USER': 'cltbld', 17:15:04 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', 17:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:04 INFO - '_': '/tools/buildbot/bin/python'} 17:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:15:04 INFO - Cleaning up... 17:15:04 INFO - Return code: 0 17:15:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:15:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x27881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2950b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2af59c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af2d60>, '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 0x2af3a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af3ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', '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 17:15:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:15:04 INFO - Using env: {'DISPLAY': ':0', 17:15:04 INFO - 'HOME': '/home/cltbld', 17:15:04 INFO - 'LANG': 'en_US.UTF-8', 17:15:04 INFO - 'LANGUAGE': 'en_US:en', 17:15:04 INFO - 'LOGNAME': 'cltbld', 17:15:04 INFO - 'MAIL': '/var/mail/cltbld', 17:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:04 INFO - 'MOZ_NO_REMOTE': '1', 17:15:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:04 INFO - 'NO_EM_RESTART': '1', 17:15:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:04 INFO - 'PWD': '/builds/slave/test', 17:15:04 INFO - 'SHELL': '/bin/bash', 17:15:04 INFO - 'SHLVL': '1', 17:15:04 INFO - 'TERM': 'linux', 17:15:04 INFO - 'TMOUT': '86400', 17:15:04 INFO - 'USER': 'cltbld', 17:15:04 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', 17:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:04 INFO - '_': '/tools/buildbot/bin/python'} 17:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:04 INFO - Downloading/unpacking blobuploader==1.2.4 17:15: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 17:15: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 17:15: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 17:15: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 17:15:06 INFO - Downloading blobuploader-1.2.4.tar.gz 17:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:15:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:15: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 17:15: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 17:15: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 17:15: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 17:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:15:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:15: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 17:15: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 17:15: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 17:15: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 17:15:06 INFO - Downloading docopt-0.6.1.tar.gz 17:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:15:06 INFO - Installing collected packages: blobuploader, requests, docopt 17:15:06 INFO - Running setup.py install for blobuploader 17:15:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:15:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:15:06 INFO - Running setup.py install for requests 17:15:07 INFO - Running setup.py install for docopt 17:15:07 INFO - Successfully installed blobuploader requests docopt 17:15:07 INFO - Cleaning up... 17:15:07 INFO - Return code: 0 17:15:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:15:07 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 0x27881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2950b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2af59c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af2d60>, '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 0x2af3a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af3ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', '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 17:15:07 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 17:15:07 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 17:15:07 INFO - Using env: {'DISPLAY': ':0', 17:15:07 INFO - 'HOME': '/home/cltbld', 17:15:07 INFO - 'LANG': 'en_US.UTF-8', 17:15:07 INFO - 'LANGUAGE': 'en_US:en', 17:15:07 INFO - 'LOGNAME': 'cltbld', 17:15:07 INFO - 'MAIL': '/var/mail/cltbld', 17:15:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:07 INFO - 'MOZ_NO_REMOTE': '1', 17:15:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:07 INFO - 'NO_EM_RESTART': '1', 17:15:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:07 INFO - 'PWD': '/builds/slave/test', 17:15:07 INFO - 'SHELL': '/bin/bash', 17:15:07 INFO - 'SHLVL': '1', 17:15:07 INFO - 'TERM': 'linux', 17:15:07 INFO - 'TMOUT': '86400', 17:15:07 INFO - 'USER': 'cltbld', 17:15:07 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', 17:15:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:07 INFO - '_': '/tools/buildbot/bin/python'} 17:15:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:15:07 INFO - Running setup.py (path:/tmp/pip-OlwENs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:15:07 INFO - Running setup.py (path:/tmp/pip-7SE5E9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:15:07 INFO - Running setup.py (path:/tmp/pip-xNzAYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:15:07 INFO - Running setup.py (path:/tmp/pip-uY1eI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:15:07 INFO - Running setup.py (path:/tmp/pip-A3KYDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:15:07 INFO - Running setup.py (path:/tmp/pip-Kg6DMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:15:07 INFO - Running setup.py (path:/tmp/pip-v6pL2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:15:07 INFO - Running setup.py (path:/tmp/pip-xt1WCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:15:07 INFO - Running setup.py (path:/tmp/pip-Fe8PWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:15:08 INFO - Running setup.py (path:/tmp/pip-1mW_xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:15:08 INFO - Running setup.py (path:/tmp/pip-c2rdQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:15:08 INFO - Running setup.py (path:/tmp/pip-GGOWyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:15:08 INFO - Running setup.py (path:/tmp/pip-VMlZ8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:15:08 INFO - Running setup.py (path:/tmp/pip-fgsGaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:15:08 INFO - Running setup.py (path:/tmp/pip-9C1bDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:15:08 INFO - Running setup.py (path:/tmp/pip-kvtnJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:15:08 INFO - Running setup.py (path:/tmp/pip-7LJhSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:15:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:15:08 INFO - Running setup.py install for manifestparser 17:15:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:15:08 INFO - Running setup.py install for mozcrash 17:15:08 INFO - Running setup.py install for mozdebug 17:15:08 INFO - Running setup.py install for mozdevice 17:15:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:15:09 INFO - Running setup.py install for mozfile 17:15:09 INFO - Running setup.py install for mozhttpd 17:15:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:15:09 INFO - Running setup.py install for mozinfo 17:15:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:15:09 INFO - Running setup.py install for mozInstall 17:15:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:15:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:15:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:15:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:15:09 INFO - Running setup.py install for mozleak 17:15:09 INFO - Running setup.py install for mozlog 17:15:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:15:09 INFO - Running setup.py install for moznetwork 17:15:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:15:09 INFO - Running setup.py install for mozprocess 17:15:09 INFO - Running setup.py install for mozprofile 17:15:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:15:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:15:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:15:09 INFO - Running setup.py install for mozrunner 17:15:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:15:10 INFO - Running setup.py install for mozscreenshot 17:15:10 INFO - Running setup.py install for moztest 17:15:10 INFO - Running setup.py install for mozversion 17:15:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:15:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:15:10 INFO - Cleaning up... 17:15:10 INFO - Return code: 0 17:15:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:15:10 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 0x27881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2950b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2af59c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af2d60>, '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 0x2af3a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af3ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', '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 17:15:10 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 17:15:10 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 17:15:10 INFO - Using env: {'DISPLAY': ':0', 17:15:10 INFO - 'HOME': '/home/cltbld', 17:15:10 INFO - 'LANG': 'en_US.UTF-8', 17:15:10 INFO - 'LANGUAGE': 'en_US:en', 17:15:10 INFO - 'LOGNAME': 'cltbld', 17:15:10 INFO - 'MAIL': '/var/mail/cltbld', 17:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:10 INFO - 'MOZ_NO_REMOTE': '1', 17:15:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:10 INFO - 'NO_EM_RESTART': '1', 17:15:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:10 INFO - 'PWD': '/builds/slave/test', 17:15:10 INFO - 'SHELL': '/bin/bash', 17:15:10 INFO - 'SHLVL': '1', 17:15:10 INFO - 'TERM': 'linux', 17:15:10 INFO - 'TMOUT': '86400', 17:15:10 INFO - 'USER': 'cltbld', 17:15:10 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', 17:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:10 INFO - '_': '/tools/buildbot/bin/python'} 17:15:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:15:10 INFO - Running setup.py (path:/tmp/pip-9nkNm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:15:10 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)) 17:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:15:10 INFO - Running setup.py (path:/tmp/pip-mKg4zG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:15:10 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)) 17:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:15:10 INFO - Running setup.py (path:/tmp/pip-1Y8Ime-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:15:10 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)) 17:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:15:10 INFO - Running setup.py (path:/tmp/pip-7rdkbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:15:10 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)) 17:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:15:10 INFO - Running setup.py (path:/tmp/pip-0PJPmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:15:10 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)) 17:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:15:10 INFO - Running setup.py (path:/tmp/pip-eOEvnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:15:11 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)) 17:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:15:11 INFO - Running setup.py (path:/tmp/pip-sq55ex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:15:11 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)) 17:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:15:11 INFO - Running setup.py (path:/tmp/pip-6vXBRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:15:11 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)) 17:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:15:11 INFO - Running setup.py (path:/tmp/pip-Emla_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:15:11 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)) 17:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:15:11 INFO - Running setup.py (path:/tmp/pip-zT9rzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:15:11 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)) 17:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:15:11 INFO - Running setup.py (path:/tmp/pip-Bo3IKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:15:11 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)) 17:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:15:11 INFO - Running setup.py (path:/tmp/pip-SrMGda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:15:11 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)) 17:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:15:11 INFO - Running setup.py (path:/tmp/pip-ixLu7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:15:11 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)) 17:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:15:11 INFO - Running setup.py (path:/tmp/pip-BtC7rs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:15:11 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)) 17:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:15:11 INFO - Running setup.py (path:/tmp/pip-VPUDj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:15:11 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)) 17:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:15:11 INFO - Running setup.py (path:/tmp/pip-fSmOvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:15:11 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)) 17:15:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:15:11 INFO - Running setup.py (path:/tmp/pip-knGENZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:15:11 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)) 17:15:11 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)) 17:15:11 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)) 17:15:11 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)) 17:15:11 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)) 17:15:11 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)) 17:15:11 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)) 17:15:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:15:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:15:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:15:12 INFO - Downloading blessings-1.6.tar.gz 17:15:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:15:13 INFO - Installing collected packages: blessings 17:15:13 INFO - Running setup.py install for blessings 17:15:13 INFO - Successfully installed blessings 17:15:13 INFO - Cleaning up... 17:15:13 INFO - Return code: 0 17:15:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:15:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:15:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:15:13 INFO - Reading from file tmpfile_stdout 17:15:13 INFO - Current package versions: 17:15:13 INFO - argparse == 1.2.1 17:15:13 INFO - blessings == 1.6 17:15:13 INFO - blobuploader == 1.2.4 17:15:13 INFO - docopt == 0.6.1 17:15:13 INFO - functools32 == 3.2.3-2 17:15:13 INFO - jsonschema == 2.5.1 17:15:13 INFO - manifestparser == 1.1 17:15:13 INFO - mozInstall == 1.13 17:15:13 INFO - mozcrash == 1.0 17:15:13 INFO - mozdebug == 0.1 17:15:13 INFO - mozdevice == 0.51 17:15:13 INFO - mozfile == 1.2 17:15:13 INFO - mozhttpd == 0.7 17:15:13 INFO - mozinfo == 0.10 17:15:13 INFO - mozleak == 0.1 17:15:13 INFO - mozlog == 3.5 17:15:13 INFO - moznetwork == 0.27 17:15:13 INFO - mozprocess == 0.25 17:15:13 INFO - mozprofile == 0.28 17:15:13 INFO - mozrunner == 6.13 17:15:13 INFO - mozscreenshot == 0.1 17:15:13 INFO - mozsystemmonitor == 0.3 17:15:13 INFO - moztest == 0.8 17:15:13 INFO - mozversion == 1.4 17:15:13 INFO - psutil == 3.1.1 17:15:13 INFO - requests == 1.2.3 17:15:13 INFO - wsgiref == 0.1.2 17:15:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:15:13 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')]} 17:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:15:13 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')]} 17:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:15:13 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 0x27881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2950b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2af59c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af2d60>, '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 0x2af3a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af3ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', '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 17:15:13 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 17:15:13 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 17:15:13 INFO - Using env: {'DISPLAY': ':0', 17:15:13 INFO - 'HOME': '/home/cltbld', 17:15:13 INFO - 'LANG': 'en_US.UTF-8', 17:15:13 INFO - 'LANGUAGE': 'en_US:en', 17:15:13 INFO - 'LOGNAME': 'cltbld', 17:15:13 INFO - 'MAIL': '/var/mail/cltbld', 17:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:13 INFO - 'MOZ_NO_REMOTE': '1', 17:15:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:13 INFO - 'NO_EM_RESTART': '1', 17:15:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:13 INFO - 'PWD': '/builds/slave/test', 17:15:13 INFO - 'SHELL': '/bin/bash', 17:15:13 INFO - 'SHLVL': '1', 17:15:13 INFO - 'TERM': 'linux', 17:15:13 INFO - 'TMOUT': '86400', 17:15:13 INFO - 'USER': 'cltbld', 17:15:13 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', 17:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:13 INFO - '_': '/tools/buildbot/bin/python'} 17:15:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:13 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)) 17:15:13 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)) 17:15:13 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)) 17:15:13 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)) 17:15:13 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)) 17:15:13 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)) 17:15:13 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)) 17:15:13 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)) 17:15:13 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)) 17:15:13 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:15:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:15:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:15:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:15:14 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)) 17:15:14 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)) 17:15:14 INFO - Installing collected packages: simplejson 17:15:14 INFO - Running setup.py install for simplejson 17:15:14 INFO - building 'simplejson._speedups' extension 17:15:14 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 17:15:15 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 17:15:15 INFO - Successfully installed simplejson 17:15:15 INFO - Cleaning up... 17:15:15 INFO - Return code: 0 17:15:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:15:15 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')]} 17:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:15:15 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')]} 17:15:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:15:15 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 0x27881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2950b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2af59c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2af2d60>, '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 0x2af3a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af3ef0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', '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 17:15:15 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 17:15:15 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 17:15:15 INFO - Using env: {'DISPLAY': ':0', 17:15:15 INFO - 'HOME': '/home/cltbld', 17:15:15 INFO - 'LANG': 'en_US.UTF-8', 17:15:15 INFO - 'LANGUAGE': 'en_US:en', 17:15:15 INFO - 'LOGNAME': 'cltbld', 17:15:15 INFO - 'MAIL': '/var/mail/cltbld', 17:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:15 INFO - 'MOZ_NO_REMOTE': '1', 17:15:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:15 INFO - 'NO_EM_RESTART': '1', 17:15:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:15 INFO - 'PWD': '/builds/slave/test', 17:15:15 INFO - 'SHELL': '/bin/bash', 17:15:15 INFO - 'SHLVL': '1', 17:15:15 INFO - 'TERM': 'linux', 17:15:15 INFO - 'TMOUT': '86400', 17:15:15 INFO - 'USER': 'cltbld', 17:15:15 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', 17:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:15 INFO - '_': '/tools/buildbot/bin/python'} 17:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:15:16 INFO - Cleaning up... 17:15:16 INFO - Return code: 0 17:15:16 INFO - Running post-action listener: _resource_record_post_action 17:15:16 INFO - Running post-action listener: _start_resource_monitoring 17:15:16 INFO - Starting resource monitoring. 17:15:16 INFO - [mozharness: 2017-09-29 00:15:16.082354Z] Finished create-virtualenv step (success) 17:15:16 INFO - [mozharness: 2017-09-29 00:15:16.082875Z] Running install step. 17:15:16 INFO - Running pre-action listener: _resource_record_pre_action 17:15:16 INFO - Running main action method: install 17:15:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:15:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:15:16 INFO - Reading from file tmpfile_stdout 17:15:16 INFO - Detecting whether we're running mozinstall >=1.0... 17:15:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:15:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:15:16 INFO - Reading from file tmpfile_stdout 17:15:16 INFO - Output received: 17:15:16 INFO - Usage: mozinstall [options] installer 17:15:16 INFO - Options: 17:15:16 INFO - -h, --help show this help message and exit 17:15:16 INFO - -d DEST, --destination=DEST 17:15:16 INFO - Directory to install application into. [default: 17:15:16 INFO - "/builds/slave/test"] 17:15:16 INFO - --app=APP Application being installed. [default: firefox] 17:15:16 INFO - mkdir: /builds/slave/test/build/application 17:15:16 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'] 17:15:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:15:34 INFO - Reading from file tmpfile_stdout 17:15:34 INFO - Output received: 17:15:34 INFO - /builds/slave/test/build/application/firefox/firefox 17:15:34 INFO - Running post-action listener: _resource_record_post_action 17:15:34 INFO - [mozharness: 2017-09-29 00:15:34.046074Z] Finished install step (success) 17:15:34 INFO - [mozharness: 2017-09-29 00:15:34.046326Z] Running setup-mitmproxy step. 17:15:34 INFO - Running pre-action listener: _resource_record_pre_action 17:15:34 INFO - Running main action method: setup_mitmproxy 17:15:34 INFO - Skipping: mitmproxy is not required 17:15:34 INFO - Running post-action listener: _resource_record_post_action 17:15:34 INFO - [mozharness: 2017-09-29 00:15:34.047454Z] Finished setup-mitmproxy step (success) 17:15:34 INFO - [mozharness: 2017-09-29 00:15:34.047690Z] Running run-tests step. 17:15:34 INFO - Running pre-action listener: _resource_record_pre_action 17:15:34 INFO - Running pre-action listener: _set_gcov_prefix 17:15:34 INFO - Running main action method: run_tests 17:15:34 WARNING - Try message not found. 17:15:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:15:34 INFO - Python 2.7.3 17:15:34 INFO - Return code: 0 17:15:34 INFO - grabbing minidump binary from tooltool 17:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:34 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 0x2af2d60>, '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 0x2af3a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2af3ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:15:34 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 17:15:34 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 17:15:34 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 17:15:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:15:34 INFO - Return code: 0 17:15:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:15:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:15:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:15:34 INFO - ENV: RUST_BACKTRACE is now full 17:15:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:15:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:15:34 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/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 17:15:34 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/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 17:15:34 INFO - Using env: {'DISPLAY': ':0', 17:15:34 INFO - 'HOME': '/home/cltbld', 17:15:34 INFO - 'LANG': 'en_US.UTF-8', 17:15:34 INFO - 'LANGUAGE': 'en_US:en', 17:15:34 INFO - 'LOGNAME': 'cltbld', 17:15:34 INFO - 'MAIL': '/var/mail/cltbld', 17:15:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:15:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:15:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:34 INFO - 'MOZ_NO_REMOTE': '1', 17:15:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:15:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:34 INFO - 'NO_EM_RESTART': '1', 17:15:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:34 INFO - 'PWD': '/builds/slave/test', 17:15:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:15:34 INFO - 'RUST_BACKTRACE': 'full', 17:15:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:15:34 INFO - 'SHELL': '/bin/bash', 17:15:34 INFO - 'SHLVL': '1', 17:15:34 INFO - 'TERM': 'linux', 17:15:34 INFO - 'TMOUT': '86400', 17:15:34 INFO - 'USER': 'cltbld', 17:15:34 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', 17:15:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:34 INFO - '_': '/tools/buildbot/bin/python'} 17:15:34 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/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 17:15:34 INFO - mozversion application_buildid: 20170928224933 17:15:34 INFO - mozversion application_changeset: 6dea0ee45b66b850c1ec62301724a67db901f81a 17:15:34 INFO - mozversion application_display_name: Nightly 17:15:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:15:34 INFO - mozversion application_name: Firefox 17:15:34 INFO - mozversion application_remotingname: firefox 17:15:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:15:34 INFO - mozversion application_vendor: Mozilla 17:15:34 INFO - mozversion application_version: 58.0a1 17:15:34 INFO - mozversion platform_buildid: 20170928224933 17:15:34 INFO - mozversion platform_changeset: 6dea0ee45b66b850c1ec62301724a67db901f81a 17:15:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:15:34 INFO - mozversion platform_version: 58.0a1 17:15:34 INFO - using testdate: 1506644134 17:15:34 INFO - actual date: 1506644134 17:15:34 INFO - starting webserver on 'localhost:51025' 17:15:34 INFO - SUITE-START | Running 2 tests 17:15:34 INFO - TEST-START | dromaeo_css 17:15:34 INFO - Initialising browser for dromaeo_css test... 17:15:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51025/getInfo.html -profile /tmp/tmphxuPh8/profile 17:15:34 INFO - TEST-INFO | started process 4549 (/builds/slave/test/build/application/firefox/firefox http://localhost:51025/getInfo.html) 17:15:39 INFO - TEST-INFO | 4549: exit 0 17:15:39 INFO - Browser initialized. 17:15:39 INFO - Running cycle 1/1 for dromaeo_css test... 17:15:39 INFO - Using env: {'DISPLAY': ':0', 17:15:39 INFO - 'HOME': '/home/cltbld', 17:15:39 INFO - 'JSGC_DISABLE_POISONING': '1', 17:15:39 INFO - 'LANG': 'en_US.UTF-8', 17:15:39 INFO - 'LANGUAGE': 'en_US:en', 17:15:39 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:15:39 INFO - 'LOGNAME': 'cltbld', 17:15:39 INFO - 'MAIL': '/var/mail/cltbld', 17:15:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:15:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:15:39 INFO - 'MOZ_CRASHREPORTER': '1', 17:15:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:39 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:15:39 INFO - 'MOZ_NO_REMOTE': '1', 17:15:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:15:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:39 INFO - 'NO_EM_RESTART': '1', 17:15:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:39 INFO - 'PWD': '/builds/slave/test', 17:15:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:15:39 INFO - 'RUST_BACKTRACE': 'full', 17:15:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:15:39 INFO - 'SHELL': '/bin/bash', 17:15:39 INFO - 'SHLVL': '1', 17:15:39 INFO - 'TERM': 'linux', 17:15:39 INFO - 'TMOUT': '86400', 17:15:39 INFO - 'USER': 'cltbld', 17:15:39 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', 17:15:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:39 INFO - '_': '/tools/buildbot/bin/python'} 17:15:39 INFO - TEST-INFO | started process 4788 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphxuPh8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:15:40 INFO - PID 4788 | 17:15:40 INFO - PID 4788 | (/builds/slave/test/build/application/firefox/firefox:4838): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:15:40 INFO - PID 4788 | 17:15:50 INFO - PID 4788 | RSS: Main: 145043456 17:15:50 INFO - PID 4788 | 17:18:48 INFO - PID 4788 | Cycle 1(1): loaded http://localhost:51025/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51025/tests/dromaeo/cssquery-ext.html) 17:18:49 INFO - PID 4788 | RSS: Main: 142843904 17:18:49 INFO - PID 4788 | 17:21:51 INFO - PID 4788 | Cycle 1(1): loaded http://localhost:51025/tests/dromaeo/cssquery-ext.html (next: http://localhost:51025/tests/dromaeo/cssquery-jquery.html) 17:21:51 INFO - PID 4788 | RSS: Main: 143446016 17:21:51 INFO - PID 4788 | 17:24:53 INFO - PID 4788 | Cycle 1(1): loaded http://localhost:51025/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51025/tests/dromaeo/cssquery-mootools.html) 17:24:53 INFO - PID 4788 | RSS: Main: 145416192 17:24:53 INFO - PID 4788 | 17:27:55 INFO - PID 4788 | Cycle 1(1): loaded http://localhost:51025/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51025/tests/dromaeo/cssquery-prototype.html) 17:27:56 INFO - PID 4788 | RSS: Main: 145076224 17:27:56 INFO - PID 4788 | 17:30:59 INFO - PID 4788 | Cycle 1(1): loaded http://localhost:51025/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51025/tests/dromaeo/cssquery-yui.html) 17:30:59 INFO - PID 4788 | RSS: Main: 148074496 17:30:59 INFO - PID 4788 | 17:34:01 INFO - PID 4788 | Cycle 1(1): loaded http://localhost:51025/tests/dromaeo/cssquery-yui.html (next: http://localhost:51025/tests/dromaeo/cssquery-dojo.html) 17:34:01 INFO - PID 4788 | RSS: Main: 149282816 17:34:01 INFO - PID 4788 | 17:34:01 INFO - PID 4788 | __start_tp_report 17:34:01 INFO - PID 4788 | _x_x_mozilla_page_load 17:34:01 INFO - PID 4788 | _x_x_mozilla_page_load_details 17:34:01 INFO - PID 4788 | |i|pagename|runs| 17:34:01 INFO - PID 4788 | |0;dojo.html;3883.6310550497706;3886.8833934981953;3887.8833634990924;3914.2954268231724;3918.2555314490246;6153.630782153063;6181.381861813822;6243.594166379182;6299.685015749205;6344.460720838729;6272.623642581449;6333.271673757507;6338.619682819034;6366.554341196118;6367.713452894619;5687.544996400278;5688.288963879515;5688.488036076752;5702.572307076985;5778.739956701958;5780.682062486561;5796.420357964191;5822;5833.183354330397;5834.620749651281;5730.856728581798;5767.480926716595;5842.152887831262;5857.589968702193;5859.355470898219;772.3544067233955;5682.573806964494;5685.886282274373;5699.373068962411;5732.885342293131;5609.270794796691;5649.887002259932;5689.374168841466;5706.429357064302;5742.167385729088;6255.7184926677955;6265.686715664198;6304.243490781089;6374.235091788969;6383.425491705722;6789.864202715968;6801.21785994612;6830.863382732317;6917.9308206917785;6922.930770692329;6878;6885.655717214119;6972.33762792539;6973.616451095188;6984.825379365506;6302.87394252117;6380.808575742735;6388.105665206875;6393.648349340784;6397.648129352884;7016.26329235423;7079.469039822034;7085;7092.4680648951535;7093.538919970186;6692.230393504712;6745.190577130677;6752.493562982796;6794.422474089659;6797.456203503708;6806.693698783567;6822.249552549216;6843.076184715099;6861;6926.515143939876;6660.400563949268;6763.864722705518;6778.8644227116165;6815.079964204868;6817.9659101705165;6749.156355455568;6761.4590832733265;6824.078749368771;6832.282610325944;6840.521163518506;4990.226514890198;5031.169856973632;5032.7735251913755;5034.546890779848;5036.269740887624;4506.481754598263;4529.162105010583;4566.634669226454;4584.289435137559;4588.082383523309;6396.232452105684;6444.871102577922;6451.774187903495;6466.062420948936;6466.579672321284;6849.726010959605;6854.074699915447;6875.931240687629;6952.165740111118;6960.582365058112;6249.187605611332;6309.400606942333;6331.556791024584;6344.333844946306;6349.587276826992;5506.201600767866;5509.834704958857;5516.696581688046;5521.1442226454965;5524.944750552523;7016.193137789118;7168.462365322622;7173.605451700207;7186.281371862772;7190.137183538009;6803;6814.591124532544;6905.447564194852;6906.343897329745;6936.514443988873;7155.141383034069;7210.531315464478;7230.313120253568;7277.526960747535;7289.671964761493;6666.533342666161;6726.764563240263;6760.3239676032;6761.628110453972;6857.2114206866845;6822.965885170543;6823.624700641314;6869.484788640972;6924.688389022607;6924.93075069263;6617.239017513048;6622.940329547249;6687.598744075371;6705.362990515893;6708.966455167693;6563.474922006323;6578.901316480161;6600.768973085919;6657.533972622064;6721.93278067213;6440.323766004502;6471.805845824632;6498.252700939453;6506.869862602816;6518.087467754613;6574.901376479453;6578.144841170809;6589.934100659124;6651.201855777338;6665.266820649822;6381.675770176565;6400.679966001589;6409.166808314889;6477.319881412573;6526.967365163144 17:34:01 INFO - PID 4788 | |1;ext.html;937.3204426790576;945.0407836046414;980.7106903463477;1004.1815920025183;1004.4274763384869;43347.13305733883;44454;44468;44494;44542.10915781686;40186.39720404189;40309.7984510077;40546.59453405465;40784;40832.18335633293;7273.5635861848195;7408.59252741099;7413.406927445818;7413.777586672409;7418.295261950106;7392.519486233405;7493.363064139555;7502.699892004313;7503.399728021773;7508.061492313461;8301.501909885395;8469.983601967773;8477.279431248351;8480.66077356905;8507.404481686284;8398.076211616717;8413.747587572383;8441.493510389335;8459.746207613782;8467.237948584627;8040.638171282248;8250.876236856451;8310.501369917763;8342.62458189383;8344.8331033379;39576;39809.20381592381;40012.39981400281;40062.59937400647;40070;3677.411614141751;3688.133288677159;3701.9444708329397;3710.9257814843822;3713.851445942159;33679.32641347184;33899;33966.83016584926;33987.32025359479;34017.48973765395;5445.074337362658;5461.508464238238;5473.370562385297;5491.533219676332;5506.063969125218;231038;233127;233849;233851;234980;52092.47907520952;52098;52137.2179417309;52219.99999999924;52274.215886761725;55624.72187639036;55789.442105578426;55908.72045639746;55913.7204313984;55967;16649.667006659798;17240;17302.480925572254;17373;17473.563160920825;14048.438062477591;14127.717445651235;14132.576022719335;14188.574342769733;14202.573922782332;14895.92552037233;15290.159041252615;15399.153046582545;15409.691806164039;15525.22373881324;5940.376260492672;5981.906332783578;5986.2217911671205;6045.0353635067895;6051.939480605226;1099.2360309584922;1102.4818335382358;1110.9222354435274;1116.810142275815;1118.4407796101948;25299.62050569243;25437.236882893543;25504.74495255061;25618.74381256201;25729.871350643127;1205.427421974555;1234.4136535145913;1235.042841797596;1240.138104017717;1247.9188852724544;13606.795898061537;13682.52111176104;13890.722185556233;13956.511522096678;14125.223112728692;281673;283246;283561;284646;284822;45098;45947.310790338175;46315.536844631126;57333.42666573364;57438.425615743305;1510.5770384292416;1520.6739961759029;1521.5511424129909;1538.415402147177;1546.3891762753874;30446;30591.2352191197;30982.690173098883;31026.069217923497;31028.844855775576;40730;40737.1852562953;40918.386224206064;40918.79540602278;40978.385324220755;245470;245646;246123;246309;246494;34923;35777.82111089428;35802.10494737652;35806.10484737902;35862.28275434529;43477;44363.55636443723;44859;45150.32274515951;45224.095518090115;33753.493697594065;34503.65496345004;34799.4780078294;34898.82550587231;34984;32475.512867306537;34220;34320.82839585886;34570.30859382748;34603.826980864935;39952.800235998635;40155.59844401519;40161.1967760649;40167.598324016384;40339;33680.66319336775;33680.83159584186;33803.15492112717;34120.48819267763;34152.48771268483;25851.482970340865;25959.870200648875;25977.480450390507;25979.61030584505;26512 17:34:01 INFO - PID 4788 | |2;jquery.html;639.1299272345225;643.9377232832003;645.7478438214118;647.9790046924314;654.509118161379;23208.65187022192;23503.412414689643;23556.29331120065;24053.639195412085;24444.633330500033;26830;27281.727182728166;27298;27340.863295683543;27348.042818501308;6019.699015049252;6595.53831231814;6711.630860302681;6732.83167920801;6740.6629668516625;4513.435820522434;6682.031105489701;6711.328867113299;6721.462283017371;6722.966385168067;6676.198856137246;6692.598444093345;6693.39759421652;6703.095082163894;6704.2625311215725;6662.300458451866;6723;6729.831754206184;6732.798016059526;6734.090897728793;6203.31763506014;6219.129321894939;6225.782097626561;6249.593776404565;6261.78083767066;28661.57007644887;29692.8515357424;29730;30059;30125.548116778267;2284.520250747345;2297.1500544798364;2309.8036666883336;2323.604987152188;2326.860388376686;19460.22159113634;20107.195712171495;20150.29473968404;20152;20173;2540.453802432486;2577.420080481895;2623.462190250994;2625.698044724862;2643.814932954694;138704;143758.28120859328;157235;165341.9999999988;166234;15698.450554230547;16188.190590470429;16504.587385315222;16509.752353714703;16878.57803554921;25684.48631027369;25854.482910342063;25876;25889.611655825178;25923.870380647975;10659.733506662396;10968.561257549609;10968.780624387467;10969.122470202365;11114.666560003212;10042.949785251172;10083.3949963001;10141.695749127539;10154.334342997372;10203.7449063774;9424.293178011676;9603.9039609603;9666.903330966601;9709.223262139012;9977.208660708884;2951.4539810135193;2970.3910698306795;2977.657569379549;2984.865681044358;2985.910422687323;1083.3716444462193;1096.5942601237593;1109.1016276815806;1109.4452773613193;1112.8108221602347;21268;21557;21629.026693798816;21636.45908852291;21777.56444871094;1165.7027457998156;1165.8426112474708;1166.8658104318113;1167.480471190312;1178.192937837584;11789.469473873696;12008.579699710468;12101.455434505468;12110.636680899586;12119.636410907688;525937;528023;528778;532467;549754;137020;137156.31421842828;140015;140675;140676;1139.9968028135354;1155.8786327435666;1270.6569226308845;1285.6914340558199;1325.8740419660116;22425.439364015376;22597.887010564842;22647.547049059256;22855.31434056981;23235.186768463023;66521.66739166273;66609;66920.33079669336;67009.32990670031;68439.31560684329;83663.5816820912;84405;84718.57640711757;84839;85744.57127714322;16037.679246414773;16325;16433.589160271087;16492.17539123063;16514.33942642329;24898.12856550012;25243.242702718948;25254.368640784127;25372.74627253704;25441.363965901;20095.296664616668;20469.2835750748;20510.487237819172;20618.484537886674;20646.896765516078;16854.831451685328;17020;17706.291748329924;17936;18729.438116856516;20641.793582063987;20886.1645534183;21052.57894842064;21091.050902709107;21202.57594848125;15837.524874253791;15887.205639718199;15923.283452244908;16055.839441605434;16102.838971610134;20047.899760501103;20097.09563069636;20175.697364539836;20259.18963241454;20259.898700506405 17:34:01 INFO - PID 4788 | |3;mootools.html;1450.5213279617726;1486.9107853528797;1496.214487394118;1509.6678730679246;1515.5983664328955;16765;17479.82520174798;17489.65020699585;17513.124343782823;17525.123743812823;16843.663126737458;16885.746713799304;16895.324187032536;16993.49019529413;17039.488815335528;2813.704561021094;2846.4876322261985;2858.6140870982463;2870.784691148162;2871.3252385689348;2742.657167854018;2844.232057344516;2854.8001639885215;2861.413410250904;2862.255813488487;2766.1563223216913;2783.707710690379;2806.803523753338;2809.157252824155;2860.1133648568907;2814.9296267593268;2833.5749637554327;2859.6997315281906;2863.0981240909045;2875.884964601434;2710.390162213506;2765.958510622342;2783.1372274594737;2800.775937924961;2800.8599570021415;16416.09711465869;16501.669966600723;16550.50348489535;16553.08958007309;16572.917135414365;8527.573621318972;8743.387962842604;8812.339074569369;8831.470111793313;8832.8675069874;5453.100238460689;5547.528460080898;5608.298962629671;5686.3744988051285;5722.771089156428;1909.446260584429;1926.6532024235632;1930.1217945834612;1956.4913122588086;1983.1175127068461;30681;31263.687363126304;31350.686493135232;31357.216069598213;31364.215894602818;7447.627618619155;7449.515781474187;7468.103827540621;7663.6168191590195;7679.308862202317;20817.479563011046;20848.37454876356;20950.161993520393;20971.58056838885;20971.79028209729;8597.097304783032;8601;8809.295256379473;8874.334424918157;8940.374173807772;7815.92184078152;7824.843503129906;7851.05787305527;7888.092869320101;7960.880586791202;5809.82570522885;5877.617954832922;5913.142594323799;5956.583039187302;6065.6663883486835;1704.1734758642049;1705.360489816319;1723.1987125986477;1744.3894636876992;1749.790025196959;947.1002547579827;948.5257371314343;965.3483898368573;977.6871401151706;978.0464047553578;8348.33213342931;8480.236778689825;8547.74356769298;8553.700620478254;8558.144185581392;761.6268028665913;763.282514436428;787.5471603827707;794.7575989323312;802.297989259398;5264.499872512105;5513.917291240635;5571.247881536022;5920.970395148083;6192.566520343533;24472.755272447404;25151.496970060864;25226.495470090864;25339.493210135694;25457.363565911;23727.762722372554;24720.876395617906;24808.25575232746;24851.13021044291;25144.24567262985;12776.169548979473;12826.486940522274;12972.09195356316;12994.935025324812;13012.609621711175;9165.633374665205;9193.35646504765;9208.401453905475;9273.443593384418;9281.071892810665;20681.793182067988;21020.789792101885;21263.149474020865;21389.679154813002;21620.13519459204;21592.244271450425;21849;21866.781332186474;22043.66934496016;22455.214067507557;12611.93694031524;12980.091393602357;13014.349282535646;13063.150895191782;13064.869351306364;15318.846811532188;15434.073955562702;15481;15519.60323570884;15653.21733913277;11759.588414405454;11843.763124737628;12125.393730313273;12190.2076365036;12413.75172496527;11370.20408571392;11522.76954460923;11754.70613234676;11813.881861181279;11955.64133076009;11055.447227638426;11100.555977761122;11170.329780213213;11231.382273974683;11239;8699.478031318142;8825.779355516164;8840.513771742615;8855.822883542165;8901.643934242558;8580.970283566012;8639.784005399915;8688.435251708619;8762.904636920384;8772.38593298463 17:34:01 INFO - PID 4788 | |4;prototype.html;558.8854284871602;569.7749388991349;576.2623841482904;583.3029529711995;588.1207594646003;6143.569950103494;6184.690765461726;6219.8445038874;6302.495800335973;6304.432601065903;6145.5390845686525;6172.845678858031;6185.494909530521;6197.81406557804;6212;4894.216925291954;5069.391672999245;5073.23901414788;5074.970011995195;5125.333706618135;5025.1708468102715;5036.194208926572;5050.191969284915;5051.964347308793;5054.393472783253;5001.424836143836;5002.474740152268;5003.424606170301;5019.222020586815;5038.9592215152925;5007.574356179729;5037.722925239111;5042.647014708972;5057.671251368649;5081.745912704387;4606;4607.976960115211;4623.797812568722;4636.466806317283;4644.210484217691;6086;6092.634441933498;6095.927384689183;6187.349463816325;6236.812895613139;5852.970735146297;5863.149843272744;5868.65021045162;5885.411458854123;5898.941010589882;6603.438707709851;6632.204135503771;6661.666916654196;6665.133532640774;6673.4661227101715;4869.853904382874;5055.772490237948;5082.847514574569;5100.285959965608;5136.922946155811;6651.534392592521;6680.93319066808;6812.659367031629;6911.617676464727;6962.895556566656;5534.612577119644;5591.6365436246515;5641.182028605829;5654.660720356792;5670.603057785916;5695.886082278331;5722.170285308607;5736.422483816984;5753.712314384264;5758.366579676316;6068.271807383054;6079.726412311456;6085.817425477243;6130.325664176937;6150.354212807591;6645.1361323027695;6662.800115996528;6675.3324667533825;6738;6768.492363072789;1974.8123928226796;1981.8216360527622;1984.176566724822;2006.6555407876758;2014.9899250503947;1448.4423496953539;1491.127690301186;1494.618872187585;1517.9544613661608;1547.9690406192037;5953.851153721105;6109.14471973924;6122.632642041492;6159.538034647329;6242;6677.265500794909;6685.732570697118;6713.26154123046;6743.527953043338;6753.6830318087505;6184.134221209034;6187.81436556904;6197.659128747872;6210.782622608187;6217.378262173746;3513.2797776455764;3514.262004978932;3535.964640353615;3547.2331581366575;3571.035820328497;6913;6971.3028697129885;7033.788986330418;7044.647767611599;7071.681774320065;6755.898661520081;6810.795676129724;6852.314768523208;6878;6893.448524118058;6935.653217339112;6991.405730512964;7039.999999999897;7110.431165506747;7114.395276401564;6633.70148343316;6660.333966603494;6665.866682666417;6712.765053223114;6716.582030469125;6560;6586.967065164644;6606.702698378681;6637.435817955335;6646.9002964956535;6938.306169383021;6942.132233470817;6959.478039147186;6966.442684585322;6984.825379365353;5548.306461692288;5561.3882472927135;5624.128260119688;5624.184493248538;5639.943600564106;6564.376384243489;6570.080188773671;6692.364225398579;6712.765053223114;6758.662066896733;6325.272593651604;6423.9036414452885;6496.837579060561;6533.18335208099;6537.05212744159;6443.291237963726;6443.387878151568;6475.481961443167;6506.056621789909;6509;6238.469730072813;6304.779332723333;6320.6839658016;6323.747050117944;6400.9039864403;6506.674666266762;6571.572847765071;6572.474202063919;6588.8023359299295;6590.308017658077;6284;6306.621602703853;6389.201349831271;6405.263394709668;6411.551191416743 17:34:01 INFO - PID 4788 | |5;yui.html;963.2342287881138;992.7815880506288;1000.3697670467604;1005.7334806276338;1020.0717347214037;19843.404697859052;20872.478188045287;21169.894150529264;21182.470438239012;21208.57582848344;19397.127129279183;19509.317173898915;19574.11916463759;19638.312659056937;19695.31066412676;1011.1910471622709;1058.3015209961427;1059.2108878885203;1059.3590877519105;1061.1351748325128;1079.8218293981483;1081.0378762900984;1084.9186311026665;1087;1088.2382332367374;1116.1405717597445;1136.6135513925265;1154.5843496341352;1160.1878684920548;1160.3270103340085;1131.3042478875514;1145.2670291013758;1147.4090843215752;1148.798960181969;1150.8503894493747;796.9641366138479;820.5938060659952;824.6907409721355;829.4484168028254;830.1532436914374;20935.581288374146;21132.471688207763;21175.788242117535;21195.68206476904;21239.15043398262;22662.546749064928;22855;23642.17252396175;23754.762452375428;23766;16900.323987040625;17039.65920681592;17167;17390.130493475277;17425.39011134604;3587.587427445825;3592.6227746086806;3601.7118630509494;3619;3620.5655321361605;133768;165964;166133.16933415257;166950;167154;17955.820441795677;17957;18103.366382176562;18214.180361883748;18429.354972575897;35528;36185.276294473966;36418.63581364152;36451.27097458036;36704;9937.751556211153;9942.950285248528;9946.254030947563;9949.950250248847;9972.60109595608;4799.448063472675;4844.2007068833955;4860.538248866352;4900.950990490121;4905.852824415202;6062.878742425127;6194.132821405007;6218.657973811393;6219.47134493564;6277.466415354655;1120.098320851706;1155;1157.9942100289445;1161.361251311775;1163.4706208675202;729.5914287998738;764.0487592946816;778.8831675248758;780.4692808889896;781.82408957984;13378.799318010238;13607.31963401826;13839.792403113961;13900.860991390158;13909.513167039106;724.2829598697357;726.9927300727031;734.5519233267703;744.3412579866748;750.0324581290183;6412.230532336056;6471.870562588721;6476.6114033158165;6526.379993900572;6550.934490655032;161199;162961;164056;165310;166119;136734;139864;143525.28237358746;143616;143717.28141359225;114445;115881.4205928965;118702;122158.38920805517;122502.38748806377;12114.333711645591;12267.754644906874;12291.201071930655;12294.385280736105;12383.133180677267;57136.1429578565;57377;57440;57942.4205757937;58995.11507327479;74555.25444745483;74800.62599686967;75474.62262688651;75732;76068;16525.421610244066;16587.419440319532;16750.58123546921;16849.494515164566;17016.829831701525;18275.36036238725;18418.539536511693;18446.35437759672;18508.907455462635;18532.814671853648;16233.188340582687;16936;17143.399981000606;17170.22733976949;17289.394871179447;17944.37194698179;18116;18188.09059546991;18228.45314640563;18362.632747345244;17629.911850441178;17953.551161221076;18041.548961276072;18130.18414171392;18147.45557633273;12867.871321287043;13104.606861794162;13250.403731831899;13276.203427794364;13371.933140334237;14143.64640883986;14199.574012779633;14206.431742730176;14214.644633884236;14365.712685746435 17:34:01 INFO - PID 4788 | __end_tp_report 17:34:01 INFO - PID 4788 | __start_cc_report 17:34:01 INFO - PID 4788 | _x_x_mozilla_cycle_collect,274 17:34:01 INFO - PID 4788 | __end_cc_report 17:34:01 INFO - PID 4788 | __startTimestamp1506645241406__endTimestamp 17:34:01 INFO - PID 4788 | 17:34:01 INFO - PID 4788 | ------- Summary: start ------- 17:34:01 INFO - PID 4788 | Number of tests: 6 17:34:01 INFO - PID 4788 | 17:34:01 INFO - PID 4788 | [#0] dojo.html Cycles:165 Average:6285.00 Median:6518.09 stddev:859.77 (13.2%) stddev-sans-first:841.51 17:34:01 INFO - PID 4788 | Values: 3883.6 3886.9 3887.9 3914.3 3918.3 6153.6 6181.4 6243.6 6299.7 6344.5 6272.6 6333.3 6338.6 6366.6 6367.7 5687.5 5688.3 5688.5 5702.6 5778.7 5780.7 5796.4 5822.0 5833.2 5834.6 5730.9 5767.5 5842.2 5857.6 5859.4 772.4 5682.6 5685.9 5699.4 5732.9 5609.3 5649.9 5689.4 5706.4 5742.2 6255.7 6265.7 6304.2 6374.2 6383.4 6789.9 6801.2 6830.9 6917.9 6922.9 6878.0 6885.7 6972.3 6973.6 6984.8 6302.9 6380.8 6388.1 6393.6 6397.6 7016.3 7079.5 7085.0 7092.5 7093.5 6692.2 6745.2 6752.5 6794.4 6797.5 6806.7 6822.2 6843.1 6861.0 6926.5 6660.4 6763.9 6778.9 6815.1 6818.0 6749.2 6761.5 6824.1 6832.3 6840.5 4990.2 5031.2 5032.8 5034.5 5036.3 4506.5 4529.2 4566.6 4584.3 4588.1 6396.2 6444.9 6451.8 6466.1 6466.6 6849.7 6854.1 6875.9 6952.2 6960.6 6249.2 6309.4 6331.6 6344.3 6349.6 5506.2 5509.8 5516.7 5521.1 5524.9 7016.2 7168.5 7173.6 7186.3 7190.1 6803.0 6814.6 6905.4 6906.3 6936.5 7155.1 7210.5 7230.3 7277.5 7289.7 6666.5 6726.8 6760.3 6761.6 6857.2 6823.0 6823.6 6869.5 6924.7 6924.9 6617.2 6622.9 6687.6 6705.4 6709.0 6563.5 6578.9 6600.8 6657.5 6721.9 6440.3 6471.8 6498.3 6506.9 6518.1 6574.9 6578.1 6589.9 6651.2 6665.3 6381.7 6400.7 6409.2 6477.3 6527.0 17:34:01 INFO - PID 4788 | 17:34:01 INFO - PID 4788 | [#1] ext.html Cycles:180 Average:42963.92 Median:35834.19 stddev:66300.91 (185.0%) stddev-sans-first:66411.81 17:34:01 INFO - PID 4788 | Values: 937.3 945.0 980.7 1004.2 1004.4 43347.1 44454.0 44468.0 44494.0 44542.1 40186.4 40309.8 40546.6 40784.0 40832.2 7273.6 7408.6 7413.4 7413.8 7418.3 7392.5 7493.4 7502.7 7503.4 7508.1 8301.5 8470.0 8477.3 8480.7 8507.4 8398.1 8413.7 8441.5 8459.7 8467.2 8040.6 8250.9 8310.5 8342.6 8344.8 39576.0 39809.2 40012.4 40062.6 40070.0 3677.4 3688.1 3701.9 3710.9 3713.9 33679.3 33899.0 33966.8 33987.3 34017.5 5445.1 5461.5 5473.4 5491.5 5506.1 231038.0 233127.0 233849.0 233851.0 234980.0 52092.5 52098.0 52137.2 52220.0 52274.2 55624.7 55789.4 55908.7 55913.7 55967.0 16649.7 17240.0 17302.5 17373.0 17473.6 14048.4 14127.7 14132.6 14188.6 14202.6 14895.9 15290.2 15399.2 15409.7 15525.2 5940.4 5981.9 5986.2 6045.0 6051.9 1099.2 1102.5 1110.9 1116.8 1118.4 25299.6 25437.2 25504.7 25618.7 25729.9 1205.4 1234.4 1235.0 1240.1 1247.9 13606.8 13682.5 13890.7 13956.5 14125.2 281673.0 283246.0 283561.0 284646.0 284822.0 45098.0 45947.3 46315.5 57333.4 57438.4 1510.6 1520.7 1521.6 1538.4 1546.4 30446.0 30591.2 30982.7 31026.1 31028.8 40730.0 40737.2 40918.4 40918.8 40978.4 245470.0 245646.0 246123.0 246309.0 246494.0 34923.0 35777.8 35802.1 35806.1 35862.3 43477.0 44363.6 44859.0 45150.3 45224.1 33753.5 34503.7 34799.5 34898.8 34984.0 32475.5 34220.0 34320.8 34570.3 34603.8 39952.8 40155.6 40161.2 40167.6 40339.0 33680.7 33680.8 33803.2 34120.5 34152.5 25851.5 25959.9 25977.5 25979.6 26512.0 17:34:01 INFO - PID 4788 | 17:34:01 INFO - PID 4788 | [#2] jquery.html Cycles:180 Average:38594.16 Median:2303.48 stddev:90532.11 (3930.2%) stddev-sans-first:90741.23 17:34:01 INFO - PID 4788 | Values: 639.1 643.9 645.7 648.0 654.5 23208.7 23503.4 23556.3 24053.6 24444.6 26830.0 27281.7 27298.0 27340.9 27348.0 6019.7 6595.5 6711.6 6732.8 6740.7 4513.4 6682.0 6711.3 6721.5 6723.0 6676.2 6692.6 6693.4 6703.1 6704.3 6662.3 6723.0 6729.8 6732.8 6734.1 6203.3 6219.1 6225.8 6249.6 6261.8 28661.6 29692.9 29730.0 30059.0 30125.5 2284.5 2297.2 2309.8 2323.6 2326.9 19460.2 20107.2 20150.3 20152.0 20173.0 2540.5 2577.4 2623.5 2625.7 2643.8 138704.0 143758.3 157235.0 165342.0 166234.0 15698.5 16188.2 16504.6 16509.8 16878.6 25684.5 25854.5 25876.0 25889.6 25923.9 10659.7 10968.6 10968.8 10969.1 11114.7 10042.9 10083.4 10141.7 10154.3 10203.7 9424.3 9603.9 9666.9 9709.2 9977.2 2951.5 2970.4 2977.7 2984.9 2985.9 1083.4 1096.6 1109.1 1109.4 1112.8 21268.0 21557.0 21629.0 21636.5 21777.6 1165.7 1165.8 1166.9 1167.5 1178.2 11789.5 12008.6 12101.5 12110.6 12119.6 525937.0 528023.0 528778.0 532467.0 549754.0 137020.0 137156.3 140015.0 140675.0 140676.0 1140.0 1155.9 1270.7 1285.7 1325.9 22425.4 22597.9 22647.5 22855.3 23235.2 66521.7 66609.0 66920.3 67009.3 68439.3 83663.6 84405.0 84718.6 84839.0 85744.6 16037.7 16325.0 16433.6 16492.2 16514.3 24898.1 25243.2 25254.4 25372.7 25441.4 20095.3 20469.3 20510.5 20618.5 20646.9 16854.8 17020.0 17706.3 17936.0 18729.4 20641.8 20886.2 21052.6 21091.1 21202.6 15837.5 15887.2 15923.3 16055.8 16102.8 20047.9 20097.1 20175.7 20259.2 20259.9 17:34:01 INFO - PID 4788 | 17:34:01 INFO - PID 4788 | [#3] mootools.html Cycles:180 Average:10514.07 Median:2792.24 stddev:7797.39 (279.3%) stddev-sans-first:7789.53 17:34:01 INFO - PID 4788 | Values: 1450.5 1486.9 1496.2 1509.7 1515.6 16765.0 17479.8 17489.7 17513.1 17525.1 16843.7 16885.7 16895.3 16993.5 17039.5 2813.7 2846.5 2858.6 2870.8 2871.3 2742.7 2844.2 2854.8 2861.4 2862.3 2766.2 2783.7 2806.8 2809.2 2860.1 2814.9 2833.6 2859.7 2863.1 2875.9 2710.4 2766.0 2783.1 2800.8 2800.9 16416.1 16501.7 16550.5 16553.1 16572.9 8527.6 8743.4 8812.3 8831.5 8832.9 5453.1 5547.5 5608.3 5686.4 5722.8 1909.4 1926.7 1930.1 1956.5 1983.1 30681.0 31263.7 31350.7 31357.2 31364.2 7447.6 7449.5 7468.1 7663.6 7679.3 20817.5 20848.4 20950.2 20971.6 20971.8 8597.1 8601.0 8809.3 8874.3 8940.4 7815.9 7824.8 7851.1 7888.1 7960.9 5809.8 5877.6 5913.1 5956.6 6065.7 1704.2 1705.4 1723.2 1744.4 1749.8 947.1 948.5 965.3 977.7 978.0 8348.3 8480.2 8547.7 8553.7 8558.1 761.6 763.3 787.5 794.8 802.3 5264.5 5513.9 5571.2 5921.0 6192.6 24472.8 25151.5 25226.5 25339.5 25457.4 23727.8 24720.9 24808.3 24851.1 25144.2 12776.2 12826.5 12972.1 12994.9 13012.6 9165.6 9193.4 9208.4 9273.4 9281.1 20681.8 21020.8 21263.1 21389.7 21620.1 21592.2 21849.0 21866.8 22043.7 22455.2 12611.9 12980.1 13014.3 13063.2 13064.9 15318.8 15434.1 15481.0 15519.6 15653.2 11759.6 11843.8 12125.4 12190.2 12413.8 11370.2 11522.8 11754.7 11813.9 11955.6 11055.4 11100.6 11170.3 11231.4 11239.0 8699.5 8825.8 8840.5 8855.8 8901.6 8581.0 8639.8 8688.4 8762.9 8772.4 17:34:01 INFO - PID 4788 | 17:34:01 INFO - PID 4788 | [#4] prototype.html Cycles:180 Average:5668.26 Median:6197.74 stddev:1522.98 (24.6%) stddev-sans-first:1478.19 17:34:01 INFO - PID 4788 | Values: 558.9 569.8 576.3 583.3 588.1 6143.6 6184.7 6219.8 6302.5 6304.4 6145.5 6172.8 6185.5 6197.8 6212.0 4894.2 5069.4 5073.2 5075.0 5125.3 5025.2 5036.2 5050.2 5052.0 5054.4 5001.4 5002.5 5003.4 5019.2 5039.0 5007.6 5037.7 5042.6 5057.7 5081.7 4606.0 4608.0 4623.8 4636.5 4644.2 6086.0 6092.6 6095.9 6187.3 6236.8 5853.0 5863.1 5868.7 5885.4 5898.9 6603.4 6632.2 6661.7 6665.1 6673.5 4869.9 5055.8 5082.8 5100.3 5136.9 6651.5 6680.9 6812.7 6911.6 6962.9 5534.6 5591.6 5641.2 5654.7 5670.6 5695.9 5722.2 5736.4 5753.7 5758.4 6068.3 6079.7 6085.8 6130.3 6150.4 6645.1 6662.8 6675.3 6738.0 6768.5 1974.8 1981.8 1984.2 2006.7 2015.0 1448.4 1491.1 1494.6 1518.0 1548.0 5953.9 6109.1 6122.6 6159.5 6242.0 6677.3 6685.7 6713.3 6743.5 6753.7 6184.1 6187.8 6197.7 6210.8 6217.4 3513.3 3514.3 3536.0 3547.2 3571.0 6913.0 6971.3 7033.8 7044.6 7071.7 6755.9 6810.8 6852.3 6878.0 6893.4 6935.7 6991.4 7040.0 7110.4 7114.4 6633.7 6660.3 6665.9 6712.8 6716.6 6560.0 6587.0 6606.7 6637.4 6646.9 6938.3 6942.1 6959.5 6966.4 6984.8 5548.3 5561.4 5624.1 5624.2 5639.9 6564.4 6570.1 6692.4 6712.8 6758.7 6325.3 6423.9 6496.8 6533.2 6537.1 6443.3 6443.4 6475.5 6506.1 6509.0 6238.5 6304.8 6320.7 6323.7 6400.9 6506.7 6571.6 6572.5 6588.8 6590.3 6284.0 6306.6 6389.2 6405.3 6411.6 17:34:01 INFO - PID 4788 | 17:34:01 INFO - PID 4788 | [#5] yui.html Cycles:180 Average:29316.59 Median:18123.09 stddev:44566.51 (245.9%) stddev-sans-first:44640.68 17:34:01 INFO - PID 4788 | Values: 963.2 992.8 1000.4 1005.7 1020.1 19843.4 20872.5 21169.9 21182.5 21208.6 19397.1 19509.3 19574.1 19638.3 19695.3 1011.2 1058.3 1059.2 1059.4 1061.1 1079.8 1081.0 1084.9 1087.0 1088.2 1116.1 1136.6 1154.6 1160.2 1160.3 1131.3 1145.3 1147.4 1148.8 1150.9 797.0 820.6 824.7 829.4 830.2 20935.6 21132.5 21175.8 21195.7 21239.2 22662.5 22855.0 23642.2 23754.8 23766.0 16900.3 17039.7 17167.0 17390.1 17425.4 3587.6 3592.6 3601.7 3619.0 3620.6 133768.0 165964.0 166133.2 166950.0 167154.0 17955.8 17957.0 18103.4 18214.2 18429.4 35528.0 36185.3 36418.6 36451.3 36704.0 9937.8 9943.0 9946.3 9950.0 9972.6 4799.4 4844.2 4860.5 4901.0 4905.9 6062.9 6194.1 6218.7 6219.5 6277.5 1120.1 1155.0 1158.0 1161.4 1163.5 729.6 764.0 778.9 780.5 781.8 13378.8 13607.3 13839.8 13900.9 13909.5 724.3 727.0 734.6 744.3 750.0 6412.2 6471.9 6476.6 6526.4 6550.9 161199.0 162961.0 164056.0 165310.0 166119.0 136734.0 139864.0 143525.3 143616.0 143717.3 114445.0 115881.4 118702.0 122158.4 122502.4 12114.3 12267.8 12291.2 12294.4 12383.1 57136.1 57377.0 57440.0 57942.4 58995.1 74555.3 74800.6 75474.6 75732.0 76068.0 16525.4 16587.4 16750.6 16849.5 17016.8 18275.4 18418.5 18446.4 18508.9 18532.8 16233.2 16936.0 17143.4 17170.2 17289.4 17944.4 18116.0 18188.1 18228.5 18362.6 17629.9 17953.6 18041.5 18130.2 18147.5 12867.9 13104.6 13250.4 13276.2 13371.9 14143.6 14199.6 14206.4 14214.6 14365.7 17:34:01 INFO - PID 4788 | -------- Summary: end -------- 17:34:01 INFO - PID 4788 | 17:34:01 INFO - TEST-INFO | 4788: exit 0 17:34:01 INFO - TEST-OK | dromaeo_css | took 1107511ms 17:34:01 INFO - TEST-START | kraken 17:34:01 INFO - Initialising browser for kraken test... 17:34:01 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51025/getInfo.html -profile /tmp/tmp5PqdNd/profile 17:34:01 INFO - TEST-INFO | started process 5157 (/builds/slave/test/build/application/firefox/firefox http://localhost:51025/getInfo.html) 17:34:07 INFO - TEST-INFO | 5157: exit 0 17:34:07 INFO - Browser initialized. 17:34:07 INFO - Running cycle 1/1 for kraken test... 17:34:07 INFO - Using env: {'DISPLAY': ':0', 17:34:07 INFO - 'HOME': '/home/cltbld', 17:34:07 INFO - 'JSGC_DISABLE_POISONING': '1', 17:34:07 INFO - 'LANG': 'en_US.UTF-8', 17:34:07 INFO - 'LANGUAGE': 'en_US:en', 17:34:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:34:07 INFO - 'LOGNAME': 'cltbld', 17:34:07 INFO - 'MAIL': '/var/mail/cltbld', 17:34:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:34:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:34:07 INFO - 'MOZ_CRASHREPORTER': '1', 17:34:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:34:07 INFO - 'MOZ_NO_REMOTE': '1', 17:34:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:34:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:34:07 INFO - 'NO_EM_RESTART': '1', 17:34:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:34:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:07 INFO - 'PWD': '/builds/slave/test', 17:34:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:34:07 INFO - 'RUST_BACKTRACE': 'full', 17:34:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:34:07 INFO - 'SHELL': '/bin/bash', 17:34:07 INFO - 'SHLVL': '1', 17:34:07 INFO - 'TERM': 'linux', 17:34:07 INFO - 'TMOUT': '86400', 17:34:07 INFO - 'USER': 'cltbld', 17:34:07 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818', 17:34:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:07 INFO - '_': '/tools/buildbot/bin/python'} 17:34:07 INFO - TEST-INFO | started process 5397 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5PqdNd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:34:08 INFO - PID 5397 | 17:34:08 INFO - PID 5397 | (/builds/slave/test/build/application/firefox/firefox:5447): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:34:08 INFO - PID 5397 | 17:34:18 INFO - PID 5397 | RSS: Main: 145170432 17:34:18 INFO - PID 5397 | 17:34:21 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51025/tests/kraken/driver.html?testName=audio-beat-detection) 17:34:22 INFO - PID 5397 | RSS: Main: 150495232 17:34:22 INFO - PID 5397 | 17:34:24 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51025/tests/kraken/driver.html?testName=audio-dft) 17:34:25 INFO - PID 5397 | RSS: Main: 151351296 17:34:25 INFO - PID 5397 | 17:34:28 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51025/tests/kraken/driver.html?testName=audio-fft) 17:34:28 INFO - PID 5397 | RSS: Main: 147628032 17:34:28 INFO - PID 5397 | 17:34:30 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51025/tests/kraken/driver.html?testName=audio-oscillator) 17:34:31 INFO - PID 5397 | RSS: Main: 148271104 17:34:31 INFO - PID 5397 | 17:34:33 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51025/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:34:34 INFO - PID 5397 | RSS: Main: 148512768 17:34:34 INFO - PID 5397 | 17:34:37 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51025/tests/kraken/driver.html?testName=imaging-darkroom) 17:34:37 INFO - PID 5397 | RSS: Main: 150503424 17:34:37 INFO - PID 5397 | 17:34:41 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51025/tests/kraken/driver.html?testName=imaging-desaturate) 17:34:41 INFO - PID 5397 | RSS: Main: 151580672 17:34:41 INFO - PID 5397 | 17:34:44 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51025/tests/kraken/driver.html?testName=json-parse-financial) 17:34:45 INFO - PID 5397 | RSS: Main: 150986752 17:34:45 INFO - PID 5397 | 17:34:46 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51025/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:34:47 INFO - PID 5397 | RSS: Main: 151130112 17:34:47 INFO - PID 5397 | 17:34:49 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:34:49 INFO - PID 5397 | RSS: Main: 151089152 17:34:49 INFO - PID 5397 | 17:34:51 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:34:52 INFO - PID 5397 | RSS: Main: 148353024 17:34:52 INFO - PID 5397 | 17:34:54 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:34:55 INFO - PID 5397 | RSS: Main: 148135936 17:34:55 INFO - PID 5397 | 17:34:58 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:34:58 INFO - PID 5397 | RSS: Main: 148807680 17:34:58 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | Cycle 1(1): loaded http://localhost:51025/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51025/tests/kraken/driver.html?testName=ai-astar) 17:35:00 INFO - PID 5397 | RSS: Main: 150077440 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | __start_tp_report 17:35:00 INFO - PID 5397 | _x_x_mozilla_page_load 17:35:00 INFO - PID 5397 | _x_x_mozilla_page_load_details 17:35:00 INFO - PID 5397 | |i|pagename|runs| 17:35:00 INFO - PID 5397 | |0;ai-astar;106;107;105;106;106;107;106;106;106;107 17:35:00 INFO - PID 5397 | |1;audio-beat-detection;113;115;115;115;112;113;112;113;113;115 17:35:00 INFO - PID 5397 | |2;audio-dft;164;162;163;162;162;161;163;162;169;164 17:35:00 INFO - PID 5397 | |3;audio-fft;73;73;73;75;76;74;79;73;79;73 17:35:00 INFO - PID 5397 | |4;audio-oscillator;78;78;77;77;78;77;77;126;77;77 17:35:00 INFO - PID 5397 | |5;imaging-gaussian-blur;113;113;114;114;114;113;113;113;113;115 17:35:00 INFO - PID 5397 | |6;imaging-darkroom;150;149;149;149;150;149;151;151;150;150 17:35:00 INFO - PID 5397 | |7;imaging-desaturate;97;95;94;94;95;94;95;94;95;95 17:35:00 INFO - PID 5397 | |8;json-parse-financial;47;46;46;44;45;46;46;45;45;43 17:35:00 INFO - PID 5397 | |9;json-stringify-tinderbox;36;37;36;36;38;36;37;37;36;38 17:35:00 INFO - PID 5397 | |10;stanford-crypto-aes;70;69;72;69;68;69;74;73;71;68 17:35:00 INFO - PID 5397 | |11;stanford-crypto-ccm;110;106;101;101;113;110;107;107;104;109 17:35:00 INFO - PID 5397 | |12;stanford-crypto-pbkdf2;150;150;154;148;156;149;147;153;149;157 17:35:00 INFO - PID 5397 | |13;stanford-crypto-sha256-iterative;63;61;61;58;59;60;60;59;58;57 17:35:00 INFO - PID 5397 | __end_tp_report 17:35:00 INFO - PID 5397 | __start_cc_report 17:35:00 INFO - PID 5397 | _x_x_mozilla_cycle_collect,724 17:35:00 INFO - PID 5397 | __end_cc_report 17:35:00 INFO - PID 5397 | __startTimestamp1506645300630__endTimestamp 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | ------- Summary: start ------- 17:35:00 INFO - PID 5397 | Number of tests: 14 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#0] ai-astar Cycles:10 Average:106.20 Median:106.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 17:35:00 INFO - PID 5397 | Values: 106.0 107.0 105.0 106.0 106.0 107.0 106.0 106.0 106.0 107.0 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#1] audio-beat-detection Cycles:10 Average:113.60 Median:114.00 stddev:1.26 (1.1%) stddev-sans-first:1.32 17:35:00 INFO - PID 5397 | Values: 113.0 115.0 115.0 115.0 112.0 113.0 112.0 113.0 113.0 115.0 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:2.25 (1.4%) stddev-sans-first:2.37 17:35:00 INFO - PID 5397 | Values: 164.0 162.0 163.0 162.0 162.0 161.0 163.0 162.0 169.0 164.0 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#3] audio-fft Cycles:10 Average:74.80 Median:74.50 stddev:2.44 (3.3%) stddev-sans-first:2.50 17:35:00 INFO - PID 5397 | Values: 73.0 73.0 73.0 75.0 76.0 74.0 79.0 73.0 79.0 73.0 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#4] audio-oscillator Cycles:10 Average:82.20 Median:77.00 stddev:15.40 (20.0%) stddev-sans-first:16.26 17:35:00 INFO - PID 5397 | Values: 78.0 78.0 77.0 77.0 78.0 77.0 77.0 126.0 77.0 77.0 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:113.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 17:35:00 INFO - PID 5397 | Values: 113.0 113.0 114.0 114.0 114.0 113.0 113.0 113.0 113.0 115.0 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#6] imaging-darkroom Cycles:10 Average:149.80 Median:150.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 17:35:00 INFO - PID 5397 | Values: 150.0 149.0 149.0 149.0 150.0 149.0 151.0 151.0 150.0 150.0 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.92 (1.0%) stddev-sans-first:0.53 17:35:00 INFO - PID 5397 | Values: 97.0 95.0 94.0 94.0 95.0 94.0 95.0 94.0 95.0 95.0 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#8] json-parse-financial Cycles:10 Average:45.30 Median:46.00 stddev:1.16 (2.5%) stddev-sans-first:1.05 17:35:00 INFO - PID 5397 | Values: 47.0 46.0 46.0 44.0 45.0 46.0 46.0 45.0 45.0 43.0 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#9] json-stringify-tinderbox Cycles:10 Average:36.70 Median:37.00 stddev:0.82 (2.2%) stddev-sans-first:0.83 17:35:00 INFO - PID 5397 | Values: 36.0 37.0 36.0 36.0 38.0 36.0 37.0 37.0 36.0 38.0 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#10] stanford-crypto-aes Cycles:10 Average:70.30 Median:70.50 stddev:2.11 (3.0%) stddev-sans-first:2.24 17:35:00 INFO - PID 5397 | Values: 70.0 69.0 72.0 69.0 68.0 69.0 74.0 73.0 71.0 68.0 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#11] stanford-crypto-ccm Cycles:10 Average:106.80 Median:108.00 stddev:3.94 (3.6%) stddev-sans-first:4.00 17:35:00 INFO - PID 5397 | Values: 110.0 106.0 101.0 101.0 113.0 110.0 107.0 107.0 104.0 109.0 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.30 Median:151.50 stddev:3.47 (2.3%) stddev-sans-first:3.64 17:35:00 INFO - PID 5397 | Values: 150.0 150.0 154.0 148.0 156.0 149.0 147.0 153.0 149.0 157.0 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.60 Median:60.00 stddev:1.78 (3.0%) stddev-sans-first:1.39 17:35:00 INFO - PID 5397 | Values: 63.0 61.0 61.0 58.0 59.0 60.0 60.0 59.0 58.0 57.0 17:35:00 INFO - PID 5397 | -------- Summary: end -------- 17:35:00 INFO - PID 5397 | 17:35:00 INFO - PID 5397 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:35:01 INFO - TEST-INFO | 5397: exit 0 17:35:01 INFO - TEST-OK | kraken | took 59360ms 17:35:01 INFO - SUITE-END | took 1166s 17:35:01 INFO - Completed test suite (00:19:26) 17:35:01 INFO - javascript benchmark 17:35:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9144.891429370291, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3883.6310550497706, 3886.8833934981953, 3887.8833634990924, 3914.2954268231724, 3918.2555314490246, 6153.630782153063, 6181.381861813822, 6243.594166379182, 6299.685015749205, 6344.460720838729, 6272.623642581449, 6333.271673757507, 6338.619682819034, 6366.554341196118, 6367.713452894619, 5687.544996400278, 5688.288963879515, 5688.488036076752, 5702.572307076985, 5778.739956701958, 5780.682062486561, 5796.420357964191, 5822.0, 5833.183354330397, 5834.620749651281, 5730.856728581798, 5767.480926716595, 5842.152887831262, 5857.589968702193, 5859.355470898219, 772.3544067233955, 5682.573806964494, 5685.886282274373, 5699.373068962411, 5732.885342293131, 5609.270794796691, 5649.887002259932, 5689.374168841466, 5706.429357064302, 5742.167385729088, 6255.7184926677955, 6265.686715664198, 6304.243490781089, 6374.235091788969, 6383.425491705722, 6789.864202715968, 6801.21785994612, 6830.863382732317, 6917.9308206917785, 6922.930770692329, 6878.0, 6885.655717214119, 6972.33762792539, 6973.616451095188, 6984.825379365506, 6302.87394252117, 6380.808575742735, 6388.105665206875, 6393.648349340784, 6397.648129352884, 7016.26329235423, 7079.469039822034, 7085.0, 7092.4680648951535, 7093.538919970186, 6692.230393504712, 6745.190577130677, 6752.493562982796, 6794.422474089659, 6797.456203503708, 6806.693698783567, 6822.249552549216, 6843.076184715099, 6861.0, 6926.515143939876, 6660.400563949268, 6763.864722705518, 6778.8644227116165, 6815.079964204868, 6817.9659101705165, 6749.156355455568, 6761.4590832733265, 6824.078749368771, 6832.282610325944, 6840.521163518506, 4990.226514890198, 5031.169856973632, 5032.7735251913755, 5034.546890779848, 5036.269740887624, 4506.481754598263, 4529.162105010583, 4566.634669226454, 4584.289435137559, 4588.082383523309, 6396.232452105684, 6444.871102577922, 6451.774187903495, 6466.062420948936, 6466.579672321284, 6849.726010959605, 6854.074699915447, 6875.931240687629, 6952.165740111118, 6960.582365058112, 6249.187605611332, 6309.400606942333, 6331.556791024584, 6344.333844946306, 6349.587276826992, 5506.201600767866, 5509.834704958857, 5516.696581688046, 5521.1442226454965, 5524.944750552523, 7016.193137789118, 7168.462365322622, 7173.605451700207, 7186.281371862772, 7190.137183538009, 6803.0, 6814.591124532544, 6905.447564194852, 6906.343897329745, 6936.514443988873, 7155.141383034069, 7210.531315464478, 7230.313120253568, 7277.526960747535, 7289.671964761493, 6666.533342666161, 6726.764563240263, 6760.3239676032, 6761.628110453972, 6857.2114206866845, 6822.965885170543, 6823.624700641314, 6869.484788640972, 6924.688389022607, 6924.93075069263, 6617.239017513048, 6622.940329547249, 6687.598744075371, 6705.362990515893, 6708.966455167693, 6563.474922006323, 6578.901316480161, 6600.768973085919, 6657.533972622064, 6721.93278067213, 6440.323766004502, 6471.805845824632, 6498.252700939453, 6506.869862602816, 6518.087467754613, 6574.901376479453, 6578.144841170809, 6589.934100659124, 6651.201855777338, 6665.266820649822, 6381.675770176565, 6400.679966001589, 6409.166808314889, 6477.319881412573, 6526.967365163144], "value": 6228.539554087538, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [937.3204426790576, 945.0407836046414, 980.7106903463477, 1004.1815920025183, 1004.4274763384869, 43347.13305733883, 44454.0, 44468.0, 44494.0, 44542.10915781686, 40186.39720404189, 40309.7984510077, 40546.59453405465, 40784.0, 40832.18335633293, 7273.5635861848195, 7408.59252741099, 7413.406927445818, 7413.777586672409, 7418.295261950106, 7392.519486233405, 7493.363064139555, 7502.699892004313, 7503.399728021773, 7508.061492313461, 8301.501909885395, 8469.983601967773, 8477.279431248351, 8480.66077356905, 8507.404481686284, 8398.076211616717, 8413.747587572383, 8441.493510389335, 8459.746207613782, 8467.237948584627, 8040.638171282248, 8250.876236856451, 8310.501369917763, 8342.62458189383, 8344.8331033379, 39576.0, 39809.20381592381, 40012.39981400281, 40062.59937400647, 40070.0, 3677.411614141751, 3688.133288677159, 3701.9444708329397, 3710.9257814843822, 3713.851445942159, 33679.32641347184, 33899.0, 33966.83016584926, 33987.32025359479, 34017.48973765395, 5445.074337362658, 5461.508464238238, 5473.370562385297, 5491.533219676332, 5506.063969125218, 231038.0, 233127.0, 233849.0, 233851.0, 234980.0, 52092.47907520952, 52098.0, 52137.2179417309, 52219.99999999924, 52274.215886761725, 55624.72187639036, 55789.442105578426, 55908.72045639746, 55913.7204313984, 55967.0, 16649.667006659798, 17240.0, 17302.480925572254, 17373.0, 17473.563160920825, 14048.438062477591, 14127.717445651235, 14132.576022719335, 14188.574342769733, 14202.573922782332, 14895.92552037233, 15290.159041252615, 15399.153046582545, 15409.691806164039, 15525.22373881324, 5940.376260492672, 5981.906332783578, 5986.2217911671205, 6045.0353635067895, 6051.939480605226, 1099.2360309584922, 1102.4818335382358, 1110.9222354435274, 1116.810142275815, 1118.4407796101948, 25299.62050569243, 25437.236882893543, 25504.74495255061, 25618.74381256201, 25729.871350643127, 1205.427421974555, 1234.4136535145913, 1235.042841797596, 1240.138104017717, 1247.9188852724544, 13606.795898061537, 13682.52111176104, 13890.722185556233, 13956.511522096678, 14125.223112728692, 281673.0, 283246.0, 283561.0, 284646.0, 284822.0, 45098.0, 45947.310790338175, 46315.536844631126, 57333.42666573364, 57438.425615743305, 1510.5770384292416, 1520.6739961759029, 1521.5511424129909, 1538.415402147177, 1546.3891762753874, 30446.0, 30591.2352191197, 30982.690173098883, 31026.069217923497, 31028.844855775576, 40730.0, 40737.1852562953, 40918.386224206064, 40918.79540602278, 40978.385324220755, 245470.0, 245646.0, 246123.0, 246309.0, 246494.0, 34923.0, 35777.82111089428, 35802.10494737652, 35806.10484737902, 35862.28275434529, 43477.0, 44363.55636443723, 44859.0, 45150.32274515951, 45224.095518090115, 33753.493697594065, 34503.65496345004, 34799.4780078294, 34898.82550587231, 34984.0, 32475.512867306537, 34220.0, 34320.82839585886, 34570.30859382748, 34603.826980864935, 39952.800235998635, 40155.59844401519, 40161.1967760649, 40167.598324016384, 40339.0, 33680.66319336775, 33680.83159584186, 33803.15492112717, 34120.48819267763, 34152.48771268483, 25851.482970340865, 25959.870200648875, 25977.480450390507, 25979.61030584505, 26512.0], "value": 18611.35345948719, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [639.1299272345225, 643.9377232832003, 645.7478438214118, 647.9790046924314, 654.509118161379, 23208.65187022192, 23503.412414689643, 23556.29331120065, 24053.639195412085, 24444.633330500033, 26830.0, 27281.727182728166, 27298.0, 27340.863295683543, 27348.042818501308, 6019.699015049252, 6595.53831231814, 6711.630860302681, 6732.83167920801, 6740.6629668516625, 4513.435820522434, 6682.031105489701, 6711.328867113299, 6721.462283017371, 6722.966385168067, 6676.198856137246, 6692.598444093345, 6693.39759421652, 6703.095082163894, 6704.2625311215725, 6662.300458451866, 6723.0, 6729.831754206184, 6732.798016059526, 6734.090897728793, 6203.31763506014, 6219.129321894939, 6225.782097626561, 6249.593776404565, 6261.78083767066, 28661.57007644887, 29692.8515357424, 29730.0, 30059.0, 30125.548116778267, 2284.520250747345, 2297.1500544798364, 2309.8036666883336, 2323.604987152188, 2326.860388376686, 19460.22159113634, 20107.195712171495, 20150.29473968404, 20152.0, 20173.0, 2540.453802432486, 2577.420080481895, 2623.462190250994, 2625.698044724862, 2643.814932954694, 138704.0, 143758.28120859328, 157235.0, 165341.9999999988, 166234.0, 15698.450554230547, 16188.190590470429, 16504.587385315222, 16509.752353714703, 16878.57803554921, 25684.48631027369, 25854.482910342063, 25876.0, 25889.611655825178, 25923.870380647975, 10659.733506662396, 10968.561257549609, 10968.780624387467, 10969.122470202365, 11114.666560003212, 10042.949785251172, 10083.3949963001, 10141.695749127539, 10154.334342997372, 10203.7449063774, 9424.293178011676, 9603.9039609603, 9666.903330966601, 9709.223262139012, 9977.208660708884, 2951.4539810135193, 2970.3910698306795, 2977.657569379549, 2984.865681044358, 2985.910422687323, 1083.3716444462193, 1096.5942601237593, 1109.1016276815806, 1109.4452773613193, 1112.8108221602347, 21268.0, 21557.0, 21629.026693798816, 21636.45908852291, 21777.56444871094, 1165.7027457998156, 1165.8426112474708, 1166.8658104318113, 1167.480471190312, 1178.192937837584, 11789.469473873696, 12008.579699710468, 12101.455434505468, 12110.636680899586, 12119.636410907688, 525937.0, 528023.0, 528778.0, 532467.0, 549754.0, 137020.0, 137156.31421842828, 140015.0, 140675.0, 140676.0, 1139.9968028135354, 1155.8786327435666, 1270.6569226308845, 1285.6914340558199, 1325.8740419660116, 22425.439364015376, 22597.887010564842, 22647.547049059256, 22855.31434056981, 23235.186768463023, 66521.66739166273, 66609.0, 66920.33079669336, 67009.32990670031, 68439.31560684329, 83663.5816820912, 84405.0, 84718.57640711757, 84839.0, 85744.57127714322, 16037.679246414773, 16325.0, 16433.589160271087, 16492.17539123063, 16514.33942642329, 24898.12856550012, 25243.242702718948, 25254.368640784127, 25372.74627253704, 25441.363965901, 20095.296664616668, 20469.2835750748, 20510.487237819172, 20618.484537886674, 20646.896765516078, 16854.831451685328, 17020.0, 17706.291748329924, 17936.0, 18729.438116856516, 20641.793582063987, 20886.1645534183, 21052.57894842064, 21091.050902709107, 21202.57594848125, 15837.524874253791, 15887.205639718199, 15923.283452244908, 16055.839441605434, 16102.838971610134, 20047.899760501103, 20097.09563069636, 20175.697364539836, 20259.18963241454, 20259.898700506405], "value": 13044.03012925647, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.5213279617726, 1486.9107853528797, 1496.214487394118, 1509.6678730679246, 1515.5983664328955, 16765.0, 17479.82520174798, 17489.65020699585, 17513.124343782823, 17525.123743812823, 16843.663126737458, 16885.746713799304, 16895.324187032536, 16993.49019529413, 17039.488815335528, 2813.704561021094, 2846.4876322261985, 2858.6140870982463, 2870.784691148162, 2871.3252385689348, 2742.657167854018, 2844.232057344516, 2854.8001639885215, 2861.413410250904, 2862.255813488487, 2766.1563223216913, 2783.707710690379, 2806.803523753338, 2809.157252824155, 2860.1133648568907, 2814.9296267593268, 2833.5749637554327, 2859.6997315281906, 2863.0981240909045, 2875.884964601434, 2710.390162213506, 2765.958510622342, 2783.1372274594737, 2800.775937924961, 2800.8599570021415, 16416.09711465869, 16501.669966600723, 16550.50348489535, 16553.08958007309, 16572.917135414365, 8527.573621318972, 8743.387962842604, 8812.339074569369, 8831.470111793313, 8832.8675069874, 5453.100238460689, 5547.528460080898, 5608.298962629671, 5686.3744988051285, 5722.771089156428, 1909.446260584429, 1926.6532024235632, 1930.1217945834612, 1956.4913122588086, 1983.1175127068461, 30681.0, 31263.687363126304, 31350.686493135232, 31357.216069598213, 31364.215894602818, 7447.627618619155, 7449.515781474187, 7468.103827540621, 7663.6168191590195, 7679.308862202317, 20817.479563011046, 20848.37454876356, 20950.161993520393, 20971.58056838885, 20971.79028209729, 8597.097304783032, 8601.0, 8809.295256379473, 8874.334424918157, 8940.374173807772, 7815.92184078152, 7824.843503129906, 7851.05787305527, 7888.092869320101, 7960.880586791202, 5809.82570522885, 5877.617954832922, 5913.142594323799, 5956.583039187302, 6065.6663883486835, 1704.1734758642049, 1705.360489816319, 1723.1987125986477, 1744.3894636876992, 1749.790025196959, 947.1002547579827, 948.5257371314343, 965.3483898368573, 977.6871401151706, 978.0464047553578, 8348.33213342931, 8480.236778689825, 8547.74356769298, 8553.700620478254, 8558.144185581392, 761.6268028665913, 763.282514436428, 787.5471603827707, 794.7575989323312, 802.297989259398, 5264.499872512105, 5513.917291240635, 5571.247881536022, 5920.970395148083, 6192.566520343533, 24472.755272447404, 25151.496970060864, 25226.495470090864, 25339.493210135694, 25457.363565911, 23727.762722372554, 24720.876395617906, 24808.25575232746, 24851.13021044291, 25144.24567262985, 12776.169548979473, 12826.486940522274, 12972.09195356316, 12994.935025324812, 13012.609621711175, 9165.633374665205, 9193.35646504765, 9208.401453905475, 9273.443593384418, 9281.071892810665, 20681.793182067988, 21020.789792101885, 21263.149474020865, 21389.679154813002, 21620.13519459204, 21592.244271450425, 21849.0, 21866.781332186474, 22043.66934496016, 22455.214067507557, 12611.93694031524, 12980.091393602357, 13014.349282535646, 13063.150895191782, 13064.869351306364, 15318.846811532188, 15434.073955562702, 15481.0, 15519.60323570884, 15653.21733913277, 11759.588414405454, 11843.763124737628, 12125.393730313273, 12190.2076365036, 12413.75172496527, 11370.20408571392, 11522.76954460923, 11754.70613234676, 11813.881861181279, 11955.64133076009, 11055.447227638426, 11100.555977761122, 11170.329780213213, 11231.382273974683, 11239.0, 8699.478031318142, 8825.779355516164, 8840.513771742615, 8855.822883542165, 8901.643934242558, 8580.970283566012, 8639.784005399915, 8688.435251708619, 8762.904636920384, 8772.38593298463], "value": 7316.015432776458, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.8854284871602, 569.7749388991349, 576.2623841482904, 583.3029529711995, 588.1207594646003, 6143.569950103494, 6184.690765461726, 6219.8445038874, 6302.495800335973, 6304.432601065903, 6145.5390845686525, 6172.845678858031, 6185.494909530521, 6197.81406557804, 6212.0, 4894.216925291954, 5069.391672999245, 5073.23901414788, 5074.970011995195, 5125.333706618135, 5025.1708468102715, 5036.194208926572, 5050.191969284915, 5051.964347308793, 5054.393472783253, 5001.424836143836, 5002.474740152268, 5003.424606170301, 5019.222020586815, 5038.9592215152925, 5007.574356179729, 5037.722925239111, 5042.647014708972, 5057.671251368649, 5081.745912704387, 4606.0, 4607.976960115211, 4623.797812568722, 4636.466806317283, 4644.210484217691, 6086.0, 6092.634441933498, 6095.927384689183, 6187.349463816325, 6236.812895613139, 5852.970735146297, 5863.149843272744, 5868.65021045162, 5885.411458854123, 5898.941010589882, 6603.438707709851, 6632.204135503771, 6661.666916654196, 6665.133532640774, 6673.4661227101715, 4869.853904382874, 5055.772490237948, 5082.847514574569, 5100.285959965608, 5136.922946155811, 6651.534392592521, 6680.93319066808, 6812.659367031629, 6911.617676464727, 6962.895556566656, 5534.612577119644, 5591.6365436246515, 5641.182028605829, 5654.660720356792, 5670.603057785916, 5695.886082278331, 5722.170285308607, 5736.422483816984, 5753.712314384264, 5758.366579676316, 6068.271807383054, 6079.726412311456, 6085.817425477243, 6130.325664176937, 6150.354212807591, 6645.1361323027695, 6662.800115996528, 6675.3324667533825, 6738.0, 6768.492363072789, 1974.8123928226796, 1981.8216360527622, 1984.176566724822, 2006.6555407876758, 2014.9899250503947, 1448.4423496953539, 1491.127690301186, 1494.618872187585, 1517.9544613661608, 1547.9690406192037, 5953.851153721105, 6109.14471973924, 6122.632642041492, 6159.538034647329, 6242.0, 6677.265500794909, 6685.732570697118, 6713.26154123046, 6743.527953043338, 6753.6830318087505, 6184.134221209034, 6187.81436556904, 6197.659128747872, 6210.782622608187, 6217.378262173746, 3513.2797776455764, 3514.262004978932, 3535.964640353615, 3547.2331581366575, 3571.035820328497, 6913.0, 6971.3028697129885, 7033.788986330418, 7044.647767611599, 7071.681774320065, 6755.898661520081, 6810.795676129724, 6852.314768523208, 6878.0, 6893.448524118058, 6935.653217339112, 6991.405730512964, 7039.999999999897, 7110.431165506747, 7114.395276401564, 6633.70148343316, 6660.333966603494, 6665.866682666417, 6712.765053223114, 6716.582030469125, 6560.0, 6586.967065164644, 6606.702698378681, 6637.435817955335, 6646.9002964956535, 6938.306169383021, 6942.132233470817, 6959.478039147186, 6966.442684585322, 6984.825379365353, 5548.306461692288, 5561.3882472927135, 5624.128260119688, 5624.184493248538, 5639.943600564106, 6564.376384243489, 6570.080188773671, 6692.364225398579, 6712.765053223114, 6758.662066896733, 6325.272593651604, 6423.9036414452885, 6496.837579060561, 6533.18335208099, 6537.05212744159, 6443.291237963726, 6443.387878151568, 6475.481961443167, 6506.056621789909, 6509.0, 6238.469730072813, 6304.779332723333, 6320.6839658016, 6323.747050117944, 6400.9039864403, 6506.674666266762, 6571.572847765071, 6572.474202063919, 6588.8023359299295, 6590.308017658077, 6284.0, 6306.621602703853, 6389.201349831271, 6405.263394709668, 6411.551191416743], "value": 5246.404640472017, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [963.2342287881138, 992.7815880506288, 1000.3697670467604, 1005.7334806276338, 1020.0717347214037, 19843.404697859052, 20872.478188045287, 21169.894150529264, 21182.470438239012, 21208.57582848344, 19397.127129279183, 19509.317173898915, 19574.11916463759, 19638.312659056937, 19695.31066412676, 1011.1910471622709, 1058.3015209961427, 1059.2108878885203, 1059.3590877519105, 1061.1351748325128, 1079.8218293981483, 1081.0378762900984, 1084.9186311026665, 1087.0, 1088.2382332367374, 1116.1405717597445, 1136.6135513925265, 1154.5843496341352, 1160.1878684920548, 1160.3270103340085, 1131.3042478875514, 1145.2670291013758, 1147.4090843215752, 1148.798960181969, 1150.8503894493747, 796.9641366138479, 820.5938060659952, 824.6907409721355, 829.4484168028254, 830.1532436914374, 20935.581288374146, 21132.471688207763, 21175.788242117535, 21195.68206476904, 21239.15043398262, 22662.546749064928, 22855.0, 23642.17252396175, 23754.762452375428, 23766.0, 16900.323987040625, 17039.65920681592, 17167.0, 17390.130493475277, 17425.39011134604, 3587.587427445825, 3592.6227746086806, 3601.7118630509494, 3619.0, 3620.5655321361605, 133768.0, 165964.0, 166133.16933415257, 166950.0, 167154.0, 17955.820441795677, 17957.0, 18103.366382176562, 18214.180361883748, 18429.354972575897, 35528.0, 36185.276294473966, 36418.63581364152, 36451.27097458036, 36704.0, 9937.751556211153, 9942.950285248528, 9946.254030947563, 9949.950250248847, 9972.60109595608, 4799.448063472675, 4844.2007068833955, 4860.538248866352, 4900.950990490121, 4905.852824415202, 6062.878742425127, 6194.132821405007, 6218.657973811393, 6219.47134493564, 6277.466415354655, 1120.098320851706, 1155.0, 1157.9942100289445, 1161.361251311775, 1163.4706208675202, 729.5914287998738, 764.0487592946816, 778.8831675248758, 780.4692808889896, 781.82408957984, 13378.799318010238, 13607.31963401826, 13839.792403113961, 13900.860991390158, 13909.513167039106, 724.2829598697357, 726.9927300727031, 734.5519233267703, 744.3412579866748, 750.0324581290183, 6412.230532336056, 6471.870562588721, 6476.6114033158165, 6526.379993900572, 6550.934490655032, 161199.0, 162961.0, 164056.0, 165310.0, 166119.0, 136734.0, 139864.0, 143525.28237358746, 143616.0, 143717.28141359225, 114445.0, 115881.4205928965, 118702.0, 122158.38920805517, 122502.38748806377, 12114.333711645591, 12267.754644906874, 12291.201071930655, 12294.385280736105, 12383.133180677267, 57136.1429578565, 57377.0, 57440.0, 57942.4205757937, 58995.11507327479, 74555.25444745483, 74800.62599686967, 75474.62262688651, 75732.0, 76068.0, 16525.421610244066, 16587.419440319532, 16750.58123546921, 16849.494515164566, 17016.829831701525, 18275.36036238725, 18418.539536511693, 18446.35437759672, 18508.907455462635, 18532.814671853648, 16233.188340582687, 16936.0, 17143.399981000606, 17170.22733976949, 17289.394871179447, 17944.37194698179, 18116.0, 18188.09059546991, 18228.45314640563, 18362.632747345244, 17629.911850441178, 17953.551161221076, 18041.548961276072, 18130.18414171392, 18147.45557633273, 12867.871321287043, 13104.606861794162, 13250.403731831899, 13276.203427794364, 13371.933140334237, 14143.64640883986, 14199.574012779633, 14206.431742730176, 14214.644633884236, 14365.712685746435], "value": 10077.024220580684, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1368.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 107.0, 105.0, 106.0, 106.0, 107.0, 106.0, 106.0, 106.0, 107.0], "value": 106.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 115.0, 115.0, 112.0, 113.0, 112.0, 113.0, 113.0, 115.0], "value": 113.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 163.0, 162.0, 162.0, 161.0, 163.0, 162.0, 169.0, 164.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 75.0, 76.0, 74.0, 79.0, 73.0, 79.0, 73.0], "value": 74.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 77.0, 77.0, 78.0, 77.0, 77.0, 126.0, 77.0, 77.0], "value": 82.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 114.0, 114.0, 113.0, 113.0, 113.0, 113.0, 115.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 149.0, 149.0, 150.0, 149.0, 151.0, 151.0, 150.0, 150.0], "value": 149.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 46.0, 46.0, 44.0, 45.0, 46.0, 46.0, 45.0, 45.0, 43.0], "value": 45.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 37.0, 36.0, 36.0, 38.0, 36.0, 37.0, 37.0, 36.0, 38.0], "value": 36.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 72.0, 69.0, 68.0, 69.0, 74.0, 73.0, 71.0, 68.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 101.0, 101.0, 113.0, 110.0, 107.0, 107.0, 104.0, 109.0], "value": 106.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 154.0, 148.0, 156.0, 149.0, 147.0, 153.0, 149.0, 157.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 58.0, 59.0, 60.0, 60.0, 59.0, 58.0, 57.0], "value": 59.6, "unit": "score"}]}]} 17:35:01 INFO - Return code: 0 17:35:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:35:01 INFO - # TBPL SUCCESS # 17:35:01 INFO - Running post-action listener: _package_coverage_data 17:35:01 INFO - Running post-action listener: _resource_record_post_action 17:35:01 INFO - [mozharness: 2017-09-29 00:35:01.419437Z] Finished run-tests step (success) 17:35:01 INFO - Running post-run listener: _resource_record_post_run 17:35:01 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 458752; Write bytes: 416014336; Read time: 60; Write time: 294240 17:35:01 INFO - TinderboxPrint: CPU usage
13.1% 17:35:01 INFO - TinderboxPrint: I/O read bytes / time
458,752 / 60 17:35:01 INFO - TinderboxPrint: I/O write bytes / time
416,014,336 / 294,240 17:35:01 INFO - TinderboxPrint: CPU idle
8,211.7 (86.9%) 17:35:01 INFO - TinderboxPrint: CPU user
1,187.3 (12.6%) 17:35:01 INFO - TinderboxPrint: Swap in / out
0 / 0 17:35:01 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 110481408; Read time: 0; Write time: 74560 17:35:01 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:35:01 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 458752; Write bytes: 305532928; Read time: 60; Write time: 219680 17:35:02 INFO - Running post-run listener: _upload_blobber_files 17:35:02 INFO - Blob upload gear active. 17:35:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:35:02 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 17:35:02 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'] 17:35:02 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 17:35:02 INFO - (blobuploader) - INFO - Open directory for files ... 17:35:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:35:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:35:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:03 INFO - (blobuploader) - INFO - Done attempting. 17:35:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:35:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:35:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:04 INFO - (blobuploader) - INFO - Done attempting. 17:35:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:35:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:35:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:07 INFO - (blobuploader) - INFO - Done attempting. 17:35:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:35:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:35:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:08 INFO - (blobuploader) - INFO - Done attempting. 17:35:08 INFO - (blobuploader) - INFO - Iteration through files over. 17:35:08 INFO - Return code: 0 17:35:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:35:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:35:08 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/888c56614b40b80f639102cc27de26cbbb2a6c3e3caf24f3ece971002627aa48a8017438f61828dbc962523075e2134f7060bf820db21d04dc89d05775bea10a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3379ca3e1afaa432dd345e8fcc9247a78a210fb045059a55c7f0288ca0cfbbf81cbe385d9635d5db53de7b01c72106801dc0a0aa6ee3dde120e1fd24e5df03a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b47d787b3c4cff9fba9f4753e3cec1bea89939ab4c740a5385a9ac5c52c5f4d28e4abbfff09d919bca2589fccda8d30cb949877fdffa168d012e3eb57c05bde", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0732d9a6146baa518472aa455e1345119013a7414e6a8d5d4dcc0f55d15c42880997faf3fb4204bb7b08e9cb838f1f9eb1c8590bb195c858d4d3c1aed248b596"} 17:35:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:35:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:35:08 INFO - Contents: 17:35:08 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/888c56614b40b80f639102cc27de26cbbb2a6c3e3caf24f3ece971002627aa48a8017438f61828dbc962523075e2134f7060bf820db21d04dc89d05775bea10a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3379ca3e1afaa432dd345e8fcc9247a78a210fb045059a55c7f0288ca0cfbbf81cbe385d9635d5db53de7b01c72106801dc0a0aa6ee3dde120e1fd24e5df03a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b47d787b3c4cff9fba9f4753e3cec1bea89939ab4c740a5385a9ac5c52c5f4d28e4abbfff09d919bca2589fccda8d30cb949877fdffa168d012e3eb57c05bde", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0732d9a6146baa518472aa455e1345119013a7414e6a8d5d4dcc0f55d15c42880997faf3fb4204bb7b08e9cb838f1f9eb1c8590bb195c858d4d3c1aed248b596"} 17:35:08 INFO - Running post-run listener: copy_logs_to_upload_dir 17:35:08 INFO - Copying logs to upload dir... 17:35:08 INFO - mkdir: /builds/slave/test/build/upload/logs 17:35:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.552756 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-28 17:35:08.900352) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 17:35:08.903390) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818 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/888c56614b40b80f639102cc27de26cbbb2a6c3e3caf24f3ece971002627aa48a8017438f61828dbc962523075e2134f7060bf820db21d04dc89d05775bea10a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3379ca3e1afaa432dd345e8fcc9247a78a210fb045059a55c7f0288ca0cfbbf81cbe385d9635d5db53de7b01c72106801dc0a0aa6ee3dde120e1fd24e5df03a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b47d787b3c4cff9fba9f4753e3cec1bea89939ab4c740a5385a9ac5c52c5f4d28e4abbfff09d919bca2589fccda8d30cb949877fdffa168d012e3eb57c05bde", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0732d9a6146baa518472aa455e1345119013a7414e6a8d5d4dcc0f55d15c42880997faf3fb4204bb7b08e9cb838f1f9eb1c8590bb195c858d4d3c1aed248b596"} build_url:https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011372 build_url: 'https://queue.taskcluster.net/v1/task/crASE74RRjm1WOcvyLYyvw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/888c56614b40b80f639102cc27de26cbbb2a6c3e3caf24f3ece971002627aa48a8017438f61828dbc962523075e2134f7060bf820db21d04dc89d05775bea10a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e3379ca3e1afaa432dd345e8fcc9247a78a210fb045059a55c7f0288ca0cfbbf81cbe385d9635d5db53de7b01c72106801dc0a0aa6ee3dde120e1fd24e5df03a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b47d787b3c4cff9fba9f4753e3cec1bea89939ab4c740a5385a9ac5c52c5f4d28e4abbfff09d919bca2589fccda8d30cb949877fdffa168d012e3eb57c05bde", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0732d9a6146baa518472aa455e1345119013a7414e6a8d5d4dcc0f55d15c42880997faf3fb4204bb7b08e9cb838f1f9eb1c8590bb195c858d4d3c1aed248b596"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 17:35:08.935214) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 17:35:08.935549) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1506642520.716458-361585818 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 17:35:09.017013) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 17:35:09.017325) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 17:35:09.017620) ========= ========= Total master_lag: 0.27 =========