builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-089 starttime: 1505124927.27 results: success (0) revision: f9a5e9ed62103c84e4cde915f4d08f1ce71be83e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-11 03:15:27.267141) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-11 03:15:27.267614) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-11 03:15:27.267934) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-11 03:15:27.286065) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:15:27.286461) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-11 03:15:27-- 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% 93.9M=0s 2017-09-11 03:15:27 (93.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123276 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:15:27.426818) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:15:27.427170) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018007 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:15:27.477404) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-11 03:15:27.477743) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f9a5e9ed62103c84e4cde915f4d08f1ce71be83e --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 f9a5e9ed62103c84e4cde915f4d08f1ce71be83e --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=8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-11 03:15:27,531 truncating revision to first 12 chars 2017-09-11 03:15:27,531 Setting DEBUG logging. 2017-09-11 03:15:27,531 attempt 1/10 2017-09-11 03:15:27,531 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f9a5e9ed6210?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-11 03:15:28,870 unpacking tar archive at: mozilla-central-f9a5e9ed6210/testing/mozharness/ program finished with exit code 0 elapsedTime=1.685959 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-11 03:15:29.180415) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-11 03:15:29.180739) ========= script_repo_revision: f9a5e9ed62103c84e4cde915f4d08f1ce71be83e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-11 03:15:29.181122) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-11 03:15:29.181401) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-11 03:15:29.194906) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-11 03:15:29.195266) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:15:29 INFO - MultiFileLogger online at 20170911 03:15:29 in /builds/slave/test 03:15:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:15:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:15:29 INFO - {'append_to_log': False, 03:15:29 INFO - 'base_work_dir': '/builds/slave/test', 03:15:29 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:15:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:15:29 INFO - 'branch': 'Firefox-Non-PGO', 03:15:29 INFO - 'buildbot_json_path': 'buildprops.json', 03:15:29 INFO - 'code_coverage': False, 03:15:29 INFO - 'config_files': ('talos/linux_config.py',), 03:15:29 INFO - 'default_actions': ('clobber', 03:15:29 INFO - 'read-buildbot-config', 03:15:29 INFO - 'download-and-extract', 03:15:29 INFO - 'populate-webroot', 03:15:29 INFO - 'create-virtualenv', 03:15:29 INFO - 'install', 03:15:29 INFO - 'setup-mitmproxy', 03:15:29 INFO - 'run-tests'), 03:15:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:15:29 INFO - 'disable_ccov_upload': False, 03:15:29 INFO - 'disable_stylo': False, 03:15:29 INFO - 'download_minidump_stackwalk': True, 03:15:29 INFO - 'download_symbols': 'ondemand', 03:15:29 INFO - 'enable_stylo': False, 03:15:29 INFO - 'enable_webrender': False, 03:15:29 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:15:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:15:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:15:29 INFO - 'gecko_profile': False, 03:15:29 INFO - 'gecko_profile_interval': 0, 03:15:29 INFO - 'installer_path': 'installer.exe', 03:15:29 INFO - 'log_level': 'info', 03:15:29 INFO - 'log_name': 'talos', 03:15:29 INFO - 'log_to_console': True, 03:15:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:15:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:15:29 INFO - 'opt_config_files': (), 03:15:29 INFO - 'pip_index': False, 03:15:29 INFO - 'suite': 'dromaeojs-e10s', 03:15:29 INFO - 'system_bits': '32', 03:15:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:15:29 INFO - 'title': 'talos-linux64-ix-089', 03:15:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:15:29 INFO - 'use_talos_json': True, 03:15:29 INFO - 'verify': 'False', 03:15:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:15:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:15:29 INFO - 'work_dir': 'build'} 03:15:29 INFO - [mozharness: 2017-09-11 10:15:29.377705Z] Running clobber step. 03:15:29 INFO - Running pre-action listener: _resource_record_pre_action 03:15:29 INFO - Running main action method: clobber 03:15:29 INFO - rmtree: /builds/slave/test/build 03:15:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:15:30 INFO - Running post-action listener: _resource_record_post_action 03:15:30 INFO - [mozharness: 2017-09-11 10:15:30.388443Z] Finished clobber step (success) 03:15:30 INFO - [mozharness: 2017-09-11 10:15:30.388540Z] Running read-buildbot-config step. 03:15:30 INFO - Running pre-action listener: _resource_record_pre_action 03:15:30 INFO - Running main action method: read_buildbot_config 03:15:30 INFO - Using buildbot properties: 03:15:30 INFO - { 03:15:30 INFO - "project": "", 03:15:30 INFO - "product": "firefox", 03:15:30 INFO - "who": "archaeopteryx@coole-files.de", 03:15:30 INFO - "installer_path": "public/build/target.tar.bz2", 03:15:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:15:30 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:15:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:15:30 INFO - "stage_platform": "linux64", 03:15:30 INFO - "basedir": "/builds/slave/test", 03:15:30 INFO - "buildnumber": 1135, 03:15:30 INFO - "platform": "ubuntu64_hw", 03:15:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:15:30 INFO - "slavebuilddir": "test", 03:15:30 INFO - "taskId": "cM6FToHDQWWm5fm_tQPv0g", 03:15:30 INFO - "branch": "mozilla-central", 03:15:30 INFO - "script_repo_revision": "production", 03:15:30 INFO - "revision": "f9a5e9ed62103c84e4cde915f4d08f1ce71be83e", 03:15:30 INFO - "slavename": "talos-linux64-ix-089", 03:15:30 INFO - "repo_path": "mozilla-central" 03:15:30 INFO - } 03:15:30 INFO - Finding installer, test and symbols from parent task. 03:15:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cM6FToHDQWWm5fm_tQPv0g'}, attempt #1 03:15:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cM6FToHDQWWm5fm_tQPv0g'}, attempt #1 03:15:31 INFO - Task dependencies: TV-2xJbFQNaqNCVPY33lrg 03:15:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg'}, attempt #1 03:15:31 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.tar.bz2 03:15:31 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.test_packages.json 03:15:31 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.crashreporter-symbols.zip 03:15:31 INFO - Running post-action listener: _resource_record_post_action 03:15:31 INFO - [mozharness: 2017-09-11 10:15:31.823309Z] Finished read-buildbot-config step (success) 03:15:31 INFO - [mozharness: 2017-09-11 10:15:31.823527Z] Running download-and-extract step. 03:15:31 INFO - Running pre-action listener: _resource_record_pre_action 03:15:31 INFO - Running main action method: download_and_extract 03:15:31 INFO - mkdir: /builds/slave/test/build/tests 03:15:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:31 INFO - trying https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.test_packages.json 03:15:31 INFO - Downloading https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:15:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:15:32 INFO - Downloaded 989 bytes. 03:15:32 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:15:32 INFO - Using the following test package requirements: 03:15:32 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:15:32 INFO - u'common': [u'target.common.tests.zip'], 03:15:32 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:15:32 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:15:32 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:15:32 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:15:32 INFO - u'mozbase': [u'target.common.tests.zip'], 03:15:32 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:15:32 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:15:32 INFO - u'web-platform': [u'target.common.tests.zip', 03:15:32 INFO - u'target.web-platform.tests.tar.gz'], 03:15:32 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:15:32 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:15:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.common.tests.zip 03:15:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:15:32 INFO - Fetch https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.common.tests.zip into memory 03:15:34 INFO - Content-Length response header: 55028367 03:15:34 INFO - Bytes received: 55028367 03:15:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.talos.tests.zip 03:15:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:15:45 INFO - Fetch https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.talos.tests.zip into memory 03:15:46 INFO - Content-Length response header: 14497963 03:15:46 INFO - Bytes received: 14497963 03:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:46 INFO - trying https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.tar.bz2 03:15:46 INFO - Downloading https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:15:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:15:47 INFO - Downloaded 62877338 bytes. 03:15:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.tar.bz2 03:15:47 INFO - mkdir: /builds/slave/test/properties 03:15:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:15:47 INFO - Writing to file /builds/slave/test/properties/build_url 03:15:47 INFO - Contents: 03:15:47 INFO - build_url:https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.tar.bz2 03:15:47 INFO - Running post-action listener: _resource_record_post_action 03:15:47 INFO - Running post-action listener: find_tests_for_verification 03:15:47 INFO - Running post-action listener: set_extra_try_arguments 03:15:47 INFO - [mozharness: 2017-09-11 10:15:47.617460Z] Finished download-and-extract step (success) 03:15:47 INFO - [mozharness: 2017-09-11 10:15:47.617573Z] Running populate-webroot step. 03:15:47 INFO - Running pre-action listener: _resource_record_pre_action 03:15:47 INFO - Running main action method: populate_webroot 03:15:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:15:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:15:47 INFO - 'tests': ['tresize', 03:15:47 INFO - 'tcanvasmark']}, 03:15:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:15:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:15:47 INFO - 'tests': ['dromaeo_css', 03:15:47 INFO - 'kraken']}, 03:15:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:15:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:15:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:15:47 INFO - 'talos_options': ['--disable-stylo'], 03:15:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:15:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:15:47 INFO - 'tests': ['damp', 'tps']}, 03:15:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:15:47 INFO - 'talos_options': ['--disable-stylo'], 03:15:47 INFO - 'tests': ['damp', 'tps']}, 03:15:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:15:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:15:47 INFO - 'tests': ['dromaeo_dom']}, 03:15:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:15:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:15:47 INFO - 'tests': ['basic_compositor_video', 03:15:47 INFO - 'glvideo']}, 03:15:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:15:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:15:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:15:47 INFO - 'talos_options': ['--disable-stylo'], 03:15:47 INFO - 'tests': ['ts_paint_webext', 03:15:47 INFO - 'tp5o_webext']}, 03:15:47 INFO - 'other-e10s': {'tests': ['a11yr', 03:15:47 INFO - 'ts_paint', 03:15:47 INFO - 'tpaint', 03:15:47 INFO - 'sessionrestore', 03:15:47 INFO - 'sessionrestore_many_windows', 03:15:47 INFO - 'sessionrestore_no_auto_restore', 03:15:47 INFO - 'tabpaint']}, 03:15:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:15:47 INFO - 'tests': ['a11yr', 03:15:47 INFO - 'ts_paint', 03:15:47 INFO - 'tpaint', 03:15:47 INFO - 'sessionrestore', 03:15:47 INFO - 'sessionrestore_many_windows', 03:15:47 INFO - 'sessionrestore_no_auto_restore', 03:15:47 INFO - 'tabpaint']}, 03:15:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:15:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:15:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:15:47 INFO - 'tests': ['bloom_basic_singleton']}, 03:15:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:15:47 INFO - 'tests': ['bloom_basic']}, 03:15:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:15:47 INFO - 'tsvgr_opacity', 03:15:47 INFO - 'tart', 03:15:47 INFO - 'tscrollx', 03:15:47 INFO - 'tsvg_static']}, 03:15:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:15:47 INFO - 'tests': ['tsvgx', 03:15:47 INFO - 'tsvgr_opacity', 03:15:47 INFO - 'tart', 03:15:47 INFO - 'tscrollx', 03:15:47 INFO - 'tsvg_static']}, 03:15:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:15:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:15:47 INFO - 'talos_options': ['--disable-stylo'], 03:15:47 INFO - 'tests': ['tp5o']}, 03:15:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:15:47 INFO - 'talos_options': ['--mitmproxy', 03:15:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:15:47 INFO - '--firstNonBlankPaint'], 03:15:47 INFO - 'tests': ['tp6_google', 03:15:47 INFO - 'tp6_youtube', 03:15:47 INFO - 'tp6_amazon', 03:15:47 INFO - 'tp6_facebook']}, 03:15:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:15:47 INFO - 'talos_options': ['--disable-stylo', 03:15:47 INFO - '--mitmproxy', 03:15:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:15:47 INFO - '--firstNonBlankPaint'], 03:15:47 INFO - 'tests': ['tp6_google', 03:15:47 INFO - 'tp6_youtube', 03:15:47 INFO - 'tp6_amazon', 03:15:47 INFO - 'tp6_facebook']}, 03:15:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:15:47 INFO - 'talos_options': ['--stylo-threads=1', 03:15:47 INFO - '--mitmproxy', 03:15:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:15:47 INFO - '--firstNonBlankPaint'], 03:15:47 INFO - 'tests': ['tp6_google', 03:15:47 INFO - 'tp6_youtube', 03:15:47 INFO - 'tp6_amazon', 03:15:47 INFO - 'tp6_facebook']}, 03:15:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:15:47 INFO - 'talos_options': ['--xperf_path', 03:15:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:15:47 INFO - 'tests': ['tp5n']}, 03:15:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:15:47 INFO - 'talos_options': ['--disable-stylo', 03:15:47 INFO - '--xperf_path', 03:15:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:15:47 INFO - 'tests': ['tp5n']}}} 03:15:47 INFO - Running post-action listener: _resource_record_post_action 03:15:47 INFO - [mozharness: 2017-09-11 10:15:47.633921Z] Finished populate-webroot step (success) 03:15:47 INFO - [mozharness: 2017-09-11 10:15:47.634029Z] Running create-virtualenv step. 03:15:47 INFO - Running pre-action listener: _resource_record_pre_action 03:15:47 INFO - Running main action method: create_virtualenv 03:15:47 INFO - Creating virtualenv /builds/slave/test/build/venv 03:15:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:15:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:15:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:15:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:15:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:15:48 INFO - Installing distribute.............................................................................................................................................................................................done. 03:15:48 INFO - Installing pip...............done. 03:15:48 INFO - Return code: 0 03:15:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:15:48 INFO - Reading from file tmpfile_stdout 03:15:48 INFO - Output received: 03:15:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:15:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c81090>, '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 0x2c81d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7bf90>, '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': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:15:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:15:48 INFO - Using env: {'DISPLAY': ':0', 03:15:48 INFO - 'HOME': '/home/cltbld', 03:15:48 INFO - 'LANG': 'en_US.UTF-8', 03:15:48 INFO - 'LANGUAGE': 'en_US:en', 03:15:48 INFO - 'LOGNAME': 'cltbld', 03:15:48 INFO - 'MAIL': '/var/mail/cltbld', 03:15:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:48 INFO - 'MOZ_NO_REMOTE': '1', 03:15:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:48 INFO - 'NO_EM_RESTART': '1', 03:15:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:48 INFO - 'PWD': '/builds/slave/test', 03:15:48 INFO - 'SHELL': '/bin/bash', 03:15:48 INFO - 'SHLVL': '1', 03:15:48 INFO - 'TERM': 'linux', 03:15:48 INFO - 'TMOUT': '86400', 03:15:48 INFO - 'USER': 'cltbld', 03:15:48 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 03:15:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:48 INFO - '_': '/tools/buildbot/bin/python'} 03:15:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:15:50 INFO - Downloading/unpacking pip>=1.5 03:15:50 INFO - Running setup.py egg_info for package pip 03:15:50 INFO - warning: no files found matching 'pip/cacert.pem' 03:15:50 INFO - warning: no files found matching '*.html' under directory 'docs' 03:15:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:15:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:15:50 INFO - Installing collected packages: pip 03:15:50 INFO - Found existing installation: pip 1.1 03:15:50 INFO - Uninstalling pip: 03:15:50 INFO - Successfully uninstalled pip 03:15:50 INFO - Running setup.py install for pip 03:15:50 INFO - warning: no files found matching 'pip/cacert.pem' 03:15:50 INFO - warning: no files found matching '*.html' under directory 'docs' 03:15:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:15:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:15:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:15:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:15:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:15:50 INFO - Successfully installed pip 03:15:50 INFO - Cleaning up... 03:15:50 INFO - Return code: 0 03:15:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:50 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 0x29111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c81090>, '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 0x2c81d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7bf90>, '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': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:15:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:15:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:15:50 INFO - Using env: {'DISPLAY': ':0', 03:15:50 INFO - 'HOME': '/home/cltbld', 03:15:50 INFO - 'LANG': 'en_US.UTF-8', 03:15:50 INFO - 'LANGUAGE': 'en_US:en', 03:15:50 INFO - 'LOGNAME': 'cltbld', 03:15:50 INFO - 'MAIL': '/var/mail/cltbld', 03:15:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:50 INFO - 'MOZ_NO_REMOTE': '1', 03:15:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:50 INFO - 'NO_EM_RESTART': '1', 03:15:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:50 INFO - 'PWD': '/builds/slave/test', 03:15:50 INFO - 'SHELL': '/bin/bash', 03:15:50 INFO - 'SHLVL': '1', 03:15:50 INFO - 'TERM': 'linux', 03:15:50 INFO - 'TMOUT': '86400', 03:15:50 INFO - 'USER': 'cltbld', 03:15:50 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 03:15:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:50 INFO - '_': '/tools/buildbot/bin/python'} 03:15:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:50 INFO - Downloading/unpacking psutil>=3.1.1 03:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:15:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:15:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:15:51 INFO - Installing collected packages: psutil 03:15:51 INFO - Running setup.py install for psutil 03:15:51 INFO - building 'psutil._psutil_linux' extension 03:15:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:15:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:15:51 INFO - building 'psutil._psutil_posix' extension 03:15:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:15:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:15:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:15:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:15:52 INFO - Successfully installed psutil 03:15:52 INFO - Cleaning up... 03:15:52 INFO - Return code: 0 03:15:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c81090>, '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 0x2c81d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7bf90>, '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': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:15:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:15:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:15:52 INFO - Using env: {'DISPLAY': ':0', 03:15:52 INFO - 'HOME': '/home/cltbld', 03:15:52 INFO - 'LANG': 'en_US.UTF-8', 03:15:52 INFO - 'LANGUAGE': 'en_US:en', 03:15:52 INFO - 'LOGNAME': 'cltbld', 03:15:52 INFO - 'MAIL': '/var/mail/cltbld', 03:15:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:52 INFO - 'MOZ_NO_REMOTE': '1', 03:15:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:52 INFO - 'NO_EM_RESTART': '1', 03:15:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:52 INFO - 'PWD': '/builds/slave/test', 03:15:52 INFO - 'SHELL': '/bin/bash', 03:15:52 INFO - 'SHLVL': '1', 03:15:52 INFO - 'TERM': 'linux', 03:15:52 INFO - 'TMOUT': '86400', 03:15:52 INFO - 'USER': 'cltbld', 03:15:52 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 03:15:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:52 INFO - '_': '/tools/buildbot/bin/python'} 03:15:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:15:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:15:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:15:53 INFO - Installing collected packages: mozsystemmonitor 03:15:53 INFO - Running setup.py install for mozsystemmonitor 03:15:53 INFO - Successfully installed mozsystemmonitor 03:15:53 INFO - Cleaning up... 03:15:54 INFO - Return code: 0 03:15:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c81090>, '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 0x2c81d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7bf90>, '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': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:15:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:15:54 INFO - Using env: {'DISPLAY': ':0', 03:15:54 INFO - 'HOME': '/home/cltbld', 03:15:54 INFO - 'LANG': 'en_US.UTF-8', 03:15:54 INFO - 'LANGUAGE': 'en_US:en', 03:15:54 INFO - 'LOGNAME': 'cltbld', 03:15:54 INFO - 'MAIL': '/var/mail/cltbld', 03:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:54 INFO - 'MOZ_NO_REMOTE': '1', 03:15:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:54 INFO - 'NO_EM_RESTART': '1', 03:15:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:54 INFO - 'PWD': '/builds/slave/test', 03:15:54 INFO - 'SHELL': '/bin/bash', 03:15:54 INFO - 'SHLVL': '1', 03:15:54 INFO - 'TERM': 'linux', 03:15:54 INFO - 'TMOUT': '86400', 03:15:54 INFO - 'USER': 'cltbld', 03:15:54 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 03:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:54 INFO - '_': '/tools/buildbot/bin/python'} 03:15:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:55 INFO - Downloading/unpacking jsonschema==2.5.1 03:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:15:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:56 INFO - Downloading functools32-3.2.3-2.tar.gz 03:15:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:15:56 INFO - warning: no files found matching '*.txt' 03:15:56 INFO - no previously-included directories found matching 'build' 03:15:56 INFO - no previously-included directories found matching 'dist' 03:15:56 INFO - no previously-included directories found matching '.git*' 03:15:56 INFO - Installing collected packages: jsonschema, functools32 03:15:56 INFO - Running setup.py install for functools32 03:15:57 INFO - warning: no files found matching '*.txt' 03:15:57 INFO - no previously-included directories found matching 'build' 03:15:57 INFO - no previously-included directories found matching 'dist' 03:15:57 INFO - no previously-included directories found matching '.git*' 03:15:57 INFO - Successfully installed jsonschema functools32 03:15:57 INFO - Cleaning up... 03:15:57 INFO - Return code: 0 03:15:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x29111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c81090>, '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 0x2c81d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7bf90>, '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': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:15:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:15:57 INFO - Using env: {'DISPLAY': ':0', 03:15:57 INFO - 'HOME': '/home/cltbld', 03:15:57 INFO - 'LANG': 'en_US.UTF-8', 03:15:57 INFO - 'LANGUAGE': 'en_US:en', 03:15:57 INFO - 'LOGNAME': 'cltbld', 03:15:57 INFO - 'MAIL': '/var/mail/cltbld', 03:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:57 INFO - 'MOZ_NO_REMOTE': '1', 03:15:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:57 INFO - 'NO_EM_RESTART': '1', 03:15:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:57 INFO - 'PWD': '/builds/slave/test', 03:15:57 INFO - 'SHELL': '/bin/bash', 03:15:57 INFO - 'SHLVL': '1', 03:15:57 INFO - 'TERM': 'linux', 03:15:57 INFO - 'TMOUT': '86400', 03:15:57 INFO - 'USER': 'cltbld', 03:15:57 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 03:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:57 INFO - '_': '/tools/buildbot/bin/python'} 03:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:15:57 INFO - Cleaning up... 03:15:57 INFO - Return code: 0 03:15:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c81090>, '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 0x2c81d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7bf90>, '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': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:15:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:15:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:15:57 INFO - Using env: {'DISPLAY': ':0', 03:15:57 INFO - 'HOME': '/home/cltbld', 03:15:57 INFO - 'LANG': 'en_US.UTF-8', 03:15:57 INFO - 'LANGUAGE': 'en_US:en', 03:15:57 INFO - 'LOGNAME': 'cltbld', 03:15:57 INFO - 'MAIL': '/var/mail/cltbld', 03:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:57 INFO - 'MOZ_NO_REMOTE': '1', 03:15:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:57 INFO - 'NO_EM_RESTART': '1', 03:15:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:57 INFO - 'PWD': '/builds/slave/test', 03:15:57 INFO - 'SHELL': '/bin/bash', 03:15:57 INFO - 'SHLVL': '1', 03:15:57 INFO - 'TERM': 'linux', 03:15:57 INFO - 'TMOUT': '86400', 03:15:57 INFO - 'USER': 'cltbld', 03:15:57 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 03:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:57 INFO - '_': '/tools/buildbot/bin/python'} 03:15:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:57 INFO - Downloading/unpacking blobuploader==1.2.4 03:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:58 INFO - Downloading blobuploader-1.2.4.tar.gz 03:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:15:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:15:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:15:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:15: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 03:15: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 03:15: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 03:15: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 03:15:59 INFO - Downloading docopt-0.6.1.tar.gz 03:15:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:15:59 INFO - Installing collected packages: blobuploader, requests, docopt 03:15:59 INFO - Running setup.py install for blobuploader 03:15:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:15:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:15:59 INFO - Running setup.py install for requests 03:15:59 INFO - Running setup.py install for docopt 03:15:59 INFO - Successfully installed blobuploader requests docopt 03:15:59 INFO - Cleaning up... 03:15:59 INFO - Return code: 0 03:15:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c81090>, '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 0x2c81d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7bf90>, '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': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:15:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:15:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:15:59 INFO - Using env: {'DISPLAY': ':0', 03:15:59 INFO - 'HOME': '/home/cltbld', 03:15:59 INFO - 'LANG': 'en_US.UTF-8', 03:15:59 INFO - 'LANGUAGE': 'en_US:en', 03:15:59 INFO - 'LOGNAME': 'cltbld', 03:15:59 INFO - 'MAIL': '/var/mail/cltbld', 03:15:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:59 INFO - 'MOZ_NO_REMOTE': '1', 03:15:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:15:59 INFO - 'NO_EM_RESTART': '1', 03:15:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:15:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:59 INFO - 'PWD': '/builds/slave/test', 03:15:59 INFO - 'SHELL': '/bin/bash', 03:15:59 INFO - 'SHLVL': '1', 03:15:59 INFO - 'TERM': 'linux', 03:15:59 INFO - 'TMOUT': '86400', 03:15:59 INFO - 'USER': 'cltbld', 03:15:59 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 03:15:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:59 INFO - '_': '/tools/buildbot/bin/python'} 03:16:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:16:00 INFO - Running setup.py (path:/tmp/pip-czjLsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:16:00 INFO - Running setup.py (path:/tmp/pip-hBD0un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:16:00 INFO - Running setup.py (path:/tmp/pip-CCOtBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:16:00 INFO - Running setup.py (path:/tmp/pip-3Ftb7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:16:00 INFO - Running setup.py (path:/tmp/pip-3fluc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:16:00 INFO - Running setup.py (path:/tmp/pip-BZON23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:16:00 INFO - Running setup.py (path:/tmp/pip-Luv790-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:16:00 INFO - Running setup.py (path:/tmp/pip-jL7Fsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:16:00 INFO - Running setup.py (path:/tmp/pip-d0dxtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:16:00 INFO - Running setup.py (path:/tmp/pip-pSCQ9q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:16:00 INFO - Running setup.py (path:/tmp/pip-uDRPZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:16:00 INFO - Running setup.py (path:/tmp/pip-j1noRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:16:00 INFO - Running setup.py (path:/tmp/pip-JkJRxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:16:00 INFO - Running setup.py (path:/tmp/pip-FUMrCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:01 INFO - Running setup.py (path:/tmp/pip-WrbubA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:16:01 INFO - Running setup.py (path:/tmp/pip-7aNxJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:16:01 INFO - Running setup.py (path:/tmp/pip-Lw1QQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:16:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:16:01 INFO - Running setup.py install for manifestparser 03:16:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:16:01 INFO - Running setup.py install for mozcrash 03:16:01 INFO - Running setup.py install for mozdebug 03:16:01 INFO - Running setup.py install for mozdevice 03:16:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:16:01 INFO - Running setup.py install for mozfile 03:16:01 INFO - Running setup.py install for mozhttpd 03:16:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:16:01 INFO - Running setup.py install for mozinfo 03:16:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:16:01 INFO - Running setup.py install for mozInstall 03:16:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:16:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:16:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:16:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:16:02 INFO - Running setup.py install for mozleak 03:16:02 INFO - Running setup.py install for mozlog 03:16:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:16:02 INFO - Running setup.py install for moznetwork 03:16:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:16:02 INFO - Running setup.py install for mozprocess 03:16:02 INFO - Running setup.py install for mozprofile 03:16:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:16:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:16:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:16:02 INFO - Running setup.py install for mozrunner 03:16:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:16:02 INFO - Running setup.py install for mozscreenshot 03:16:02 INFO - Running setup.py install for moztest 03:16:02 INFO - Running setup.py install for mozversion 03:16:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:16:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:16:02 INFO - Cleaning up... 03:16:03 INFO - Return code: 0 03:16:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:03 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 0x29111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c81090>, '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 0x2c81d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7bf90>, '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': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:16:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:16:03 INFO - Using env: {'DISPLAY': ':0', 03:16:03 INFO - 'HOME': '/home/cltbld', 03:16:03 INFO - 'LANG': 'en_US.UTF-8', 03:16:03 INFO - 'LANGUAGE': 'en_US:en', 03:16:03 INFO - 'LOGNAME': 'cltbld', 03:16:03 INFO - 'MAIL': '/var/mail/cltbld', 03:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:03 INFO - 'MOZ_NO_REMOTE': '1', 03:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:03 INFO - 'NO_EM_RESTART': '1', 03:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:03 INFO - 'PWD': '/builds/slave/test', 03:16:03 INFO - 'SHELL': '/bin/bash', 03:16:03 INFO - 'SHLVL': '1', 03:16:03 INFO - 'TERM': 'linux', 03:16:03 INFO - 'TMOUT': '86400', 03:16:03 INFO - 'USER': 'cltbld', 03:16:03 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 03:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:03 INFO - '_': '/tools/buildbot/bin/python'} 03:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:16:03 INFO - Running setup.py (path:/tmp/pip-16L1Eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:16:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:16:03 INFO - Running setup.py (path:/tmp/pip-ETW6E2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:16:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:16:03 INFO - Running setup.py (path:/tmp/pip-OdTpK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:16:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:16:03 INFO - Running setup.py (path:/tmp/pip-qXMrZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:16:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:16:03 INFO - Running setup.py (path:/tmp/pip-ibQugc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:16:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:16:03 INFO - Running setup.py (path:/tmp/pip-BfUlHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:16:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:16:03 INFO - Running setup.py (path:/tmp/pip-GB83BC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:16:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:16:03 INFO - Running setup.py (path:/tmp/pip-1sNoYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:16:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:16:03 INFO - Running setup.py (path:/tmp/pip-nEzmEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:16:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:16:03 INFO - Running setup.py (path:/tmp/pip-6Fy21B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:16:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:16:03 INFO - Running setup.py (path:/tmp/pip-uSkNvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:16:04 INFO - Running setup.py (path:/tmp/pip-JMzh6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:16:04 INFO - Running setup.py (path:/tmp/pip-M9z6Vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:16:04 INFO - Running setup.py (path:/tmp/pip-P3QaWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:04 INFO - Running setup.py (path:/tmp/pip-Rva3L8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:16:04 INFO - Running setup.py (path:/tmp/pip-leI6sP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:16:04 INFO - Running setup.py (path:/tmp/pip-24Xa0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:16:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:16: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 03:16: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 03:16: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 03:16: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 03:16:05 INFO - Downloading blessings-1.6.tar.gz 03:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:16:05 INFO - Installing collected packages: blessings 03:16:05 INFO - Running setup.py install for blessings 03:16:05 INFO - Successfully installed blessings 03:16:05 INFO - Cleaning up... 03:16:05 INFO - Return code: 0 03:16:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:16:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:16:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:16:06 INFO - Reading from file tmpfile_stdout 03:16:06 INFO - Current package versions: 03:16:06 INFO - argparse == 1.2.1 03:16:06 INFO - blessings == 1.6 03:16:06 INFO - blobuploader == 1.2.4 03:16:06 INFO - docopt == 0.6.1 03:16:06 INFO - functools32 == 3.2.3-2 03:16:06 INFO - jsonschema == 2.5.1 03:16:06 INFO - manifestparser == 1.1 03:16:06 INFO - mozInstall == 1.13 03:16:06 INFO - mozcrash == 1.0 03:16:06 INFO - mozdebug == 0.1 03:16:06 INFO - mozdevice == 0.51 03:16:06 INFO - mozfile == 1.2 03:16:06 INFO - mozhttpd == 0.7 03:16:06 INFO - mozinfo == 0.10 03:16:06 INFO - mozleak == 0.1 03:16:06 INFO - mozlog == 3.5 03:16:06 INFO - moznetwork == 0.27 03:16:06 INFO - mozprocess == 0.25 03:16:06 INFO - mozprofile == 0.28 03:16:06 INFO - mozrunner == 6.13 03:16:06 INFO - mozscreenshot == 0.1 03:16:06 INFO - mozsystemmonitor == 0.3 03:16:06 INFO - moztest == 0.8 03:16:06 INFO - mozversion == 1.4 03:16:06 INFO - psutil == 3.1.1 03:16:06 INFO - requests == 1.2.3 03:16:06 INFO - wsgiref == 0.1.2 03:16:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:16:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:06 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 0x29111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c81090>, '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 0x2c81d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7bf90>, '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': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:16:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:16:06 INFO - Using env: {'DISPLAY': ':0', 03:16:06 INFO - 'HOME': '/home/cltbld', 03:16:06 INFO - 'LANG': 'en_US.UTF-8', 03:16:06 INFO - 'LANGUAGE': 'en_US:en', 03:16:06 INFO - 'LOGNAME': 'cltbld', 03:16:06 INFO - 'MAIL': '/var/mail/cltbld', 03:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:06 INFO - 'MOZ_NO_REMOTE': '1', 03:16:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:06 INFO - 'NO_EM_RESTART': '1', 03:16:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:06 INFO - 'PWD': '/builds/slave/test', 03:16:06 INFO - 'SHELL': '/bin/bash', 03:16:06 INFO - 'SHLVL': '1', 03:16:06 INFO - 'TERM': 'linux', 03:16:06 INFO - 'TMOUT': '86400', 03:16:06 INFO - 'USER': 'cltbld', 03:16:06 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 03:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:06 INFO - '_': '/tools/buildbot/bin/python'} 03:16:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:16:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:16: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 03:16: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 03:16: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 03:16: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 03:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:16:07 INFO - Installing collected packages: simplejson 03:16:07 INFO - Running setup.py install for simplejson 03:16:07 INFO - building 'simplejson._speedups' extension 03:16:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:16:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 03:16:08 INFO - Successfully installed simplejson 03:16:08 INFO - Cleaning up... 03:16:08 INFO - Return code: 0 03:16:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29111f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c81090>, '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 0x2c81d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7bf90>, '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': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:16:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:16:08 INFO - Using env: {'DISPLAY': ':0', 03:16:08 INFO - 'HOME': '/home/cltbld', 03:16:08 INFO - 'LANG': 'en_US.UTF-8', 03:16:08 INFO - 'LANGUAGE': 'en_US:en', 03:16:08 INFO - 'LOGNAME': 'cltbld', 03:16:08 INFO - 'MAIL': '/var/mail/cltbld', 03:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:08 INFO - 'MOZ_NO_REMOTE': '1', 03:16:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:08 INFO - 'NO_EM_RESTART': '1', 03:16:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:08 INFO - 'PWD': '/builds/slave/test', 03:16:08 INFO - 'SHELL': '/bin/bash', 03:16:08 INFO - 'SHLVL': '1', 03:16:08 INFO - 'TERM': 'linux', 03:16:08 INFO - 'TMOUT': '86400', 03:16:08 INFO - 'USER': 'cltbld', 03:16:08 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 03:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:08 INFO - '_': '/tools/buildbot/bin/python'} 03:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:16:08 INFO - Cleaning up... 03:16:08 INFO - Return code: 0 03:16:08 INFO - Running post-action listener: _resource_record_post_action 03:16:08 INFO - Running post-action listener: _start_resource_monitoring 03:16:08 INFO - Starting resource monitoring. 03:16:08 INFO - [mozharness: 2017-09-11 10:16:08.720007Z] Finished create-virtualenv step (success) 03:16:08 INFO - [mozharness: 2017-09-11 10:16:08.720523Z] Running install step. 03:16:08 INFO - Running pre-action listener: _resource_record_pre_action 03:16:08 INFO - Running main action method: install 03:16:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:16:08 INFO - Reading from file tmpfile_stdout 03:16:08 INFO - Detecting whether we're running mozinstall >=1.0... 03:16:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:16:09 INFO - Reading from file tmpfile_stdout 03:16:09 INFO - Output received: 03:16:09 INFO - Usage: mozinstall [options] installer 03:16:09 INFO - Options: 03:16:09 INFO - -h, --help show this help message and exit 03:16:09 INFO - -d DEST, --destination=DEST 03:16:09 INFO - Directory to install application into. [default: 03:16:09 INFO - "/builds/slave/test"] 03:16:09 INFO - --app=APP Application being installed. [default: firefox] 03:16:09 INFO - mkdir: /builds/slave/test/build/application 03:16:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:16:25 INFO - Reading from file tmpfile_stdout 03:16:25 INFO - Output received: 03:16:25 INFO - /builds/slave/test/build/application/firefox/firefox 03:16:25 INFO - Running post-action listener: _resource_record_post_action 03:16:25 INFO - [mozharness: 2017-09-11 10:16:25.749875Z] Finished install step (success) 03:16:25 INFO - [mozharness: 2017-09-11 10:16:25.750019Z] Running setup-mitmproxy step. 03:16:25 INFO - Running pre-action listener: _resource_record_pre_action 03:16:25 INFO - Running main action method: setup_mitmproxy 03:16:25 INFO - Skipping: mitmproxy is not required 03:16:25 INFO - Running post-action listener: _resource_record_post_action 03:16:25 INFO - [mozharness: 2017-09-11 10:16:25.750962Z] Finished setup-mitmproxy step (success) 03:16:25 INFO - [mozharness: 2017-09-11 10:16:25.751182Z] Running run-tests step. 03:16:25 INFO - Running pre-action listener: _resource_record_pre_action 03:16:25 INFO - Running pre-action listener: _set_gcov_prefix 03:16:25 INFO - Running main action method: run_tests 03:16:25 WARNING - Try message not found. 03:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:16:25 INFO - Python 2.7.3 03:16:25 INFO - Return code: 0 03:16:25 INFO - grabbing minidump binary from tooltool 03:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:25 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 0x2c81090>, '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 0x2c81d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7bf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:16:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:16:25 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:16:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:16:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:16:25 INFO - Return code: 0 03:16:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:16:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:16:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:16:25 INFO - ENV: RUST_BACKTRACE is now full 03:16:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:16:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-089 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:16:25 INFO - Using env: {'DISPLAY': ':0', 03:16:25 INFO - 'HOME': '/home/cltbld', 03:16:25 INFO - 'LANG': 'en_US.UTF-8', 03:16:25 INFO - 'LANGUAGE': 'en_US:en', 03:16:25 INFO - 'LOGNAME': 'cltbld', 03:16:25 INFO - 'MAIL': '/var/mail/cltbld', 03:16:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:16:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:25 INFO - 'MOZ_NO_REMOTE': '1', 03:16:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:25 INFO - 'NO_EM_RESTART': '1', 03:16:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:25 INFO - 'PWD': '/builds/slave/test', 03:16:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:16:25 INFO - 'RUST_BACKTRACE': 'full', 03:16:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:16:25 INFO - 'SHELL': '/bin/bash', 03:16:25 INFO - 'SHLVL': '1', 03:16:25 INFO - 'TERM': 'linux', 03:16:25 INFO - 'TMOUT': '86400', 03:16:25 INFO - 'USER': 'cltbld', 03:16:25 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 03:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:25 INFO - '_': '/tools/buildbot/bin/python'} 03:16:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:16:26 INFO - mozversion application_buildid: 20170911091903 03:16:26 INFO - mozversion application_changeset: f9a5e9ed62103c84e4cde915f4d08f1ce71be83e 03:16:26 INFO - mozversion application_display_name: Nightly 03:16:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:16:26 INFO - mozversion application_name: Firefox 03:16:26 INFO - mozversion application_remotingname: firefox 03:16:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:16:26 INFO - mozversion application_vendor: Mozilla 03:16:26 INFO - mozversion application_version: 57.0a1 03:16:26 INFO - mozversion platform_buildid: 20170911091903 03:16:26 INFO - mozversion platform_changeset: f9a5e9ed62103c84e4cde915f4d08f1ce71be83e 03:16:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:16:26 INFO - mozversion platform_version: 57.0a1 03:16:26 INFO - using testdate: 1505124986 03:16:26 INFO - actual date: 1505124986 03:16:26 INFO - starting webserver on 'localhost:59272' 03:16:26 INFO - SUITE-START | Running 2 tests 03:16:26 INFO - TEST-START | dromaeo_css 03:16:26 INFO - Initialising browser for dromaeo_css test... 03:16:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59272/getInfo.html -profile /tmp/tmp4qTQ8g/profile 03:16:26 INFO - TEST-INFO | started process 18455 (/builds/slave/test/build/application/firefox/firefox http://localhost:59272/getInfo.html) 03:16:32 INFO - TEST-INFO | 18455: exit 0 03:16:32 INFO - Browser initialized. 03:16:32 INFO - Running cycle 1/1 for dromaeo_css test... 03:16:32 INFO - Using env: {'DISPLAY': ':0', 03:16:32 INFO - 'HOME': '/home/cltbld', 03:16:32 INFO - 'JSGC_DISABLE_POISONING': '1', 03:16:32 INFO - 'LANG': 'en_US.UTF-8', 03:16:32 INFO - 'LANGUAGE': 'en_US:en', 03:16:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:16:32 INFO - 'LOGNAME': 'cltbld', 03:16:32 INFO - 'MAIL': '/var/mail/cltbld', 03:16:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:16:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:16:32 INFO - 'MOZ_CRASHREPORTER': '1', 03:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:16:32 INFO - 'MOZ_NO_REMOTE': '1', 03:16:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:16:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:32 INFO - 'NO_EM_RESTART': '1', 03:16:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:32 INFO - 'PWD': '/builds/slave/test', 03:16:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:16:32 INFO - 'RUST_BACKTRACE': 'full', 03:16:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:16:32 INFO - 'SHELL': '/bin/bash', 03:16:32 INFO - 'SHLVL': '1', 03:16:32 INFO - 'TERM': 'linux', 03:16:32 INFO - 'TMOUT': '86400', 03:16:32 INFO - 'USER': 'cltbld', 03:16:32 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 03:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:32 INFO - '_': '/tools/buildbot/bin/python'} 03:16:32 INFO - TEST-INFO | started process 18652 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4qTQ8g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:16:33 INFO - PID 18652 | 03:16:33 INFO - PID 18652 | (/builds/slave/test/build/application/firefox/firefox:18703): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:16:33 INFO - PID 18652 | 03:16:43 INFO - PID 18652 | RSS: Main: 167632896 03:16:43 INFO - PID 18652 | 03:19:41 INFO - PID 18652 | Cycle 1(1): loaded http://localhost:59272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59272/tests/dromaeo/cssquery-ext.html) 03:19:42 INFO - PID 18652 | RSS: Main: 173715456 03:19:42 INFO - PID 18652 | 03:22:44 INFO - PID 18652 | Cycle 1(1): loaded http://localhost:59272/tests/dromaeo/cssquery-ext.html (next: http://localhost:59272/tests/dromaeo/cssquery-jquery.html) 03:22:44 INFO - PID 18652 | RSS: Main: 169738240 03:22:44 INFO - PID 18652 | 03:25:46 INFO - PID 18652 | Cycle 1(1): loaded http://localhost:59272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59272/tests/dromaeo/cssquery-mootools.html) 03:25:46 INFO - PID 18652 | RSS: Main: 172204032 03:25:46 INFO - PID 18652 | 03:28:48 INFO - PID 18652 | Cycle 1(1): loaded http://localhost:59272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59272/tests/dromaeo/cssquery-prototype.html) 03:28:49 INFO - PID 18652 | RSS: Main: 170725376 03:28:49 INFO - PID 18652 | 03:31:52 INFO - PID 18652 | Cycle 1(1): loaded http://localhost:59272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59272/tests/dromaeo/cssquery-yui.html) 03:31:53 INFO - PID 18652 | RSS: Main: 172122112 03:31:53 INFO - PID 18652 | 03:34:54 INFO - PID 18652 | Cycle 1(1): loaded http://localhost:59272/tests/dromaeo/cssquery-yui.html (next: http://localhost:59272/tests/dromaeo/cssquery-dojo.html) 03:34:55 INFO - PID 18652 | RSS: Main: 174055424 03:34:55 INFO - PID 18652 | 03:34:55 INFO - PID 18652 | __start_tp_report 03:34:55 INFO - PID 18652 | _x_x_mozilla_page_load 03:34:55 INFO - PID 18652 | _x_x_mozilla_page_load_details 03:34:55 INFO - PID 18652 | |i|pagename|runs| 03:34:55 INFO - PID 18652 | |0;dojo.html;3127.32762456072;3135.075152829915;3177.95233071504;3178.07835727639;3186.203449137716;6243.65659888706;6330.936690633092;6385.744570217192;6426.100345951565;6469.385408386208;6307.873842523147;6315.873682526347;6324.399182077706;6360.395762402576;6394.680265986705;5876.618019828718;5911.73397197125;5933.961556727577;5970.313413957407;5982.910256346158;1320.030912116297;5821.53427725781;5898.705064746767;5939.198208241875;5986;5773.191753154561;5776.5089967352615;5853;5899.46904778572;5910.556708246877;5853.4684722936845;5871.588988770788;5923.17075609415;5946.672932988684;5950.702464876739;5822.184894114827;5837.970810145965;5889.676067816268;5901.970490147564;5915.556333275022;6275.341089185616;6370.872582548323;6424.871502569922;6444.935550644527;6475.773347932847;6567.67161641916;6726.42825359845;6750.729970801162;6827.556208846409;6916.688749006306;6872.656367181621;6942.444604431684;6943.097397338364;6945.444364450832;6952.304769523058;6184.134221209034;6267.4672652824565;6328.588641738273;6432.356764323577;6435.131257280254;6964.93035069653;7078.752243671447;7114.608696521637;7117.4306055515435;7118;6695.598264104169;6709.36261055199;6765.42493888025;6798.252192258848;6807.047013418125;6622.933770662232;6729.596224226562;6732.831679207961;6802.693878775467;6882.346177113166;6811.557248778813;6811.863762724718;6814.965925170342;6828.52200345981;6829.965850170816;6878.3465570770695;6880.415164710956;6887.965560172167;6890.31096890307;6907.34380233877;5309.41596424393;5325.786968521294;5377.623566350396;5381.623286369917;5384.9730751346;4622.86131416058;4628.629709623222;4688.742119183477;4688.929666055012;4692.085043416501;5724.685142317213;5815.709214539256;5835.037218858841;5898.2332296802;5920.2895652521975;6842.863142737117;6854.897176542356;6891.896621550681;6913.930860691329;6923.065386172802;5833.82498525045;5844.327902291206;5929.762809487572;5935.6735379553675;5939.227900372924;5731.885362292731;5859.355470898197;5864.325602555676;5931.881362372815;5938.376470470625;6510.283868774431;6530.216374035146;6614.00789881512;6615.636140012345;6621.602703837785;6854.2117656470145;6892.138482689664;6905.930940690529;6906.689198985957;6919.308069193041;6565.474762018932;6578.76974305897;6583.407493325623;6653.367930046637;6668.299828517936;6598.703058362481;6703.128593282842;6706.798796036127;6729;6833.863322733617;6859.382655561023;6879;6880.415164711056;6893.448524118159;6907.792766217021;6596;6599.6040237583975;6659.2008958925235;6660.500462465431;6679.365460281266;6591.242007169237;6616;6649.534532582673;6654.800355989328;6669.399754022162;6291.811245662638;6401.64790936503;6428.100065990859;6476.676166191577;6583.736650533925;6685.164354455693;6690.464762818866;6696.899546506904;6699.363560461748;6729.629870357177;6296.401841825019;6395.880720873922;6441.098246245436;6524.086627872007;6531.412172904462 03:34:55 INFO - PID 18652 | |1;ext.html;820.1388542030866;825.8224481736426;829.9668013279468;866.1425189062828;871.507598207013;38750.41874371887;41645.79177104114;42096;42289.57710422895;42413.78793106034;42110.36834447486;42606.36090458646;42680;42700.57299427005;42759.3586096208;7865.960670196655;7948.801279967989;7970.4819186752675;7978.042634883822;8007.159248278934;7852.568108754016;7952.244536769027;7958.164392738766;7958.204179582053;7963.641636126388;7979.601019949008;8010.038795344567;8011.319037881758;8015;8019.919800802005;7920.287174154296;7920.287174154325;7947.08608510023;7948.7217947371855;7955.562444065574;7800.687972481094;7864.449488535804;7866.370690344758;7866.68533258669;7881.527108373516;37536.24927501462;37827.621723782686;37850.81074594637;37868.810655946545;37902.43146352807;3541.4156664150382;3576.8211589420685;3576.91057723559;3578.58846232684;3579.4093974494176;30556.69443305583;30744;30790.23024424385;30978.690213097805;30982;5054.519820617035;5185;5229.111051121318;5241.764120614582;5243.501867322599;183606;183958;184489;184934;184988;47595.286070708964;47949.28076078861;48061.279080813816;48162;48205.27692084621;50359.49640503548;50385.24422133671;50455.747721261156;50564.74717626388;50593;15445.304961276544;15668.451604194026;15702.21488925572;15714.842851571566;15725.213739312989;12792.2964236966;12838.229706217657;12876.80684789729;12891.935540322238;12895.548655797002;13190.60428187156;13367.799483007762;13443.126196797373;13495.460181592824;13690.52083177084;5281.762320695578;5470.56235501159;5476.808311709071;5478.863028424242;5479.123340265538;983.4099540275778;999.4053538144792;1003.9247056470793;1013.1337706261188;1015.1422048369117;22381.664275035888;22425.10299587998;22458.438539036328;22575.661365079537;22727;1093.639099097296;1099.4722533184117;1107.5791199344199;1112.1158678850172;1114.6321713834416;11939.522419103312;11960.162788604714;12047.518099276105;12059.21615095016;12134.332611706264;213901;213911;214334;232034;235589;56008.43991560032;56041.159382609294;56125.71937140288;56179;56315.71842140763;1400.4748219417718;1436.9281535923371;1441.509886638527;1442.8917831162705;1446.8191476065492;25489.617655734808;26125.477490450463;26212;26425.471490570464;26619.06833260904;36477.27045459129;36521.45217821788;36588.81705591455;36591.63408365882;36592.817035915716;194684;194771;194808;194888;195003;33024.50463243005;33140.50289245615;33186.66813331836;33509.83245083759;33520;39715.80142099271;41062.38406423966;41123.1775364497;41224.38163427611;41311;31159.84420077885;31259;31437.842810785798;31822.204444889063;32088;31039.224019399695;31072.22319442032;31123.37753244968;31256.843715782186;31386.21534461657;34518.30963380769;34573.65426345704;34787;34876.43056062433;34982.65017349794;30453;30473.54289685612;30547.847260763552;30598.388032238785;30603.846980764953;22558.436039099153;22824.771752282264;22840.885795570917;22841.31476055721;23259 03:34:55 INFO - PID 18652 | |2;jquery.html;545.9945400545998;559.3874707195622;563.9999999999998;565.6747370262099;578.1963071330849;22079;22683.20608778693;22914.427139321484;22930.885345573268;22963.540729185406;22832.771672283274;23182;23182.3045308641;23216.303510894657;23473.5305293894;6444.967775161118;6730.394264516192;6744.662766861662;6745.797626071201;6746.359095885908;6638.767643132492;6747.954067119605;6749.325067493261;6756.49326300527;6757.222919364287;6209.658468784215;6445.162128923257;6464.385883341075;6466.25638051623;6473.6763161841955;6578.342165783407;6609.272979972199;6619.338066193391;6629.966850165766;6631.336866313378;5950.523958083386;6129.049997250433;6167.136600875881;6186.195794546725;6189.040698691665;29759.404811903856;30383;30392;30428.695713043027;30466.238344041354;2014.2345908554803;2033.8169564739096;2035.837133029369;2036.2364113457454;2038.9694154587694;19445.611087778165;19498.70751938722;19796.13975274822;19906.104225309897;20101.095450704754;2448.3389484838995;2459.1638842793536;2494.2517244826663;2516.9748302516923;2521.6721826162666;116967.41516292449;123446.38276808648;125676.99999999908;134393;134609.999999999;14409.289735391681;14435.927820361041;14733.55799326022;14779;14948.850511494964;22829.543409132057;22864.314070577577;22888.771112288665;22911.656325155134;22949.541009179724;10070.949645251727;10092.495375231209;10237.385756854612;10270.691879243635;10296.485175741183;9416.293777966679;9420.576074076684;9463.479508626953;9484.905150948402;9591.472469014272;8978.551072446351;9081.954590227007;9131.634734610541;9165.90834091664;9406.576704048197;2501.912433064834;2557.296743395581;2644.0613582178435;2651.9602205966926;2654.2170059832397;939.4410325856105;978.222313260945;981.3719219699398;982.3369225772718;985.9901400985898;18947.81052189488;18983.430497085108;19305.420837374902;19431.319903803582;19716.802831971785;997.8553109799038;1019.8725159355081;1020.5254556631054;1025.5026312238558;1029.8764148302103;10616.203784716177;10633.680989570325;10751.83872241917;10804.13566914645;11001.504932278067;437555;444618;447153;450309;456839;111402.44298778617;111981.44009279902;112047.43976280067;112279;112939;1139.6182278936649;1200.5137919142762;1205.379229696707;1210.1226610707167;1215.9088068394728;17184.140792960054;17344.826551734826;17391.652166956843;17552.82447175512;17706.291748329924;49789.751051244515;52098;52522.47477525176;53473.46526534685;54099.459005409444;64714.35285647083;66913.66543167448;67030.66484667546;67114.85598183285;67242.3275767236;14005;14079.648008799863;14293.785593215902;14295;14298.714025719635;21344.466388340417;21467.892660536596;21708.67436988478;21738.239161629266;22351.99416026315;17221.741673875153;17259.05075220875;18156.001419921504;18505.81494185095;18522.166502507687;14465.4937077206;15343.61640958985;15408.691826163189;16274;16492.752608710638;17571;18406.500169691033;18708.438746837615;18874.150663220462;18949.43151705451;14043.1574105554;14057.297135143406;14057.859421405656;14126.364313605707;14187.929060354632;17888.28446862111;17975.820241797413;18091;18130.274789008294;18150.5462363442 03:34:55 INFO - PID 18652 | |3;mootools.html;1345.4887142885705;1407.3948202273018;1410.9365078571464;1412.3079690951438;1413.9646508837282;14917.104973701586;15554.844451555495;15578.143202123907;15586.454474093409;15593;15101.546953591382;15302.617434564141;15313.61715957099;15330.386784528606;15333.92333038336;2771.8614069296555;2807.5507918733006;2837.205582436921;2842.374677570931;2844.871980760871;2766.114843250161;2795.9301017474622;2806.705295943927;2809.7190280971945;2814.0713564523658;2723.564229723245;2837.574363845419;2837.67366752824;2838.531642279032;2840.034388307975;2765.2119146043283;2768.5016696994535;2806.031918987946;2813.338865366637;2813.6060951466707;2771.4041481081663;2782.4296019315993;2789.7628701560393;2793.1480898326004;2795.2872017635577;15016.47442339513;15061;15087.24563771807;15184.164870932205;15230.390784368612;7741.729039483647;8052.9194708052755;8053.476524025919;8057.073436554754;8066.11272759996;5141.948580514184;5227.581793456515;5313.8937221255355;5389.380221274525;5424.647397919122;1941.93203237886;1968.0750047477663;1976.9308074217477;1989.990050049755;1993.1429485321305;26451.73548264512;26726.866365668047;26743.732562674515;26755.46489070208;26793.33016674599;6600.768973085919;6670.232923213795;6707.564008339442;6969.407600354027;7044.436445084381;17252.309907603805;17253.137343132792;17308.480745577654;17310.82689173092;17330.74003889943;7722.884156737653;7929.1277959424415;7951.165127661512;7952.761417157494;7952.801179970431;7021.859562808715;7133.857322853514;7161.212266650664;7163.2478589747325;7193.136823581203;4889.266610008527;4983.227599722049;5017.448080711119;5030.220315851049;5060.797568097235;1217.573849152807;1286.247545186058;1441.5242969819935;1454.8763355114722;1463.751162302411;772.7527191298843;907.3013779389833;913.6893456224915;914.0448231597996;918.8162367526388;8013.559254241072;8308.459950103224;8325.916740832514;8437.40938134337;8438;988.728099772554;1006.3056491021172;1006.5621454667244;1014.2494554030112;1015.1371334365878;4516.1419330327135;4522.66080043998;4894.8042078317185;5314.893702125936;5448.101063324507;20610;21680;21936.670949935764;21937.561248774935;21944;20645.58708825845;21183.78816211849;21418.892905535373;21554.13783448676;21572.24497142624;11273.661790146309;11351.772964540663;11360.375179365214;11391.373474458984;11393.3164010158;8214.753557393049;8327.292180164754;8338.458000229966;8349.58252087381;8384.748457546284;18129.184186711893;18255.90872045631;18258.817411825712;18450.630987379907;18468.907655462175;18781.248750049846;18834;18914.810851891303;18916;18985.525361866064;10904.563817447213;10909.399983000694;10927;11030.448477575928;11054;13668.794968075686;13900.16599004063;13928.51250206238;13960.092593981764;13961.162330260217;11275.66173014811;11335.433228338716;11403.543858245577;11567.942160289145;11689.766204676029;10175.185985121321;10297.000074810863;10349.534270957674;10446;10668.253222274367;8980.640774369213;9154.130357616015;9210.98679145307;9221.677241296522;9266.305027123128;6818.556793808387;6827.112475378169;6899.241083480712;6909.619970901648;6968.3728464438445;6801.9319806801295;6804.081449004321;6874.759383421556;6908.48186386013;6922.238553759184 03:34:55 INFO - PID 18652 | |4;prototype.html;502.45704376564163;515.0214592274675;519.4649511003666;520.7760662914948;526.8814516733736;6305.873882522347;6331.841703957403;6385.553011289212;6445.613263204199;6469.9971504416835;6195.907061394071;6245.750169993206;6250.562460627746;6303.4011768882;6318.304986451498;5199.662021968579;5238.397584277819;5262.236975638531;5263.526282634562;5270.894582108374;4849.806007759703;5114.284000239951;5124.871878203037;5275.155975043994;5293.602979776513;4954.232094025414;4970.353853998993;4992.301077849104;5000.1249781288125;5013.222950442687;4954.133026720309;4985.227289770091;5017.623678224148;5044.596432285427;5075.56857667095;4479.4176757021705;4515.661325400575;4521.050579378334;4532.79602417892;4540.137373898949;6228.28374736902;6275;6291.213598300213;6301.464375528086;6303;5654.660720356792;5691.231683722686;5724.5992780505385;5762.654240745569;5777.682227477487;6540.4767618590395;6551.901721474182;6554.475641948633;6568.8357791055605;6587.61660051385;4896.975515122437;4898.167311557044;4902.877428064291;4910.508949105097;4910.631702622282;6619.669016549154;6679.532432729711;6722.260551339349;6800.353966373186;6805.80898342788;4952.4304704959395;4980.726060066731;4994.200927851526;5027.974860125749;5039.974800125976;5082.881766011472;5085.508101030468;5087.821926232564;5087.872803179876;5116.976604679079;5774.538036957033;5780.479756821906;5782.132680097935;5796.94203057964;5802.33273173582;6557;6577.6711164441585;6655.833604159935;6678.165229346332;6692.86614267712;1721.2942693495606;1746.9563260918324;1759.5777013516663;1761.6036391811997;1762.418401927361;1177.581958404754;1199.4782269712703;1213.4660749270292;1233.7046101593291;1256.0830593666492;5600.187972743929;5674;5699.145128230799;5762.1849117527845;5810.157527158538;6759.831004224835;6795.898061528982;6839.897401538981;6855.143107111611;6889.827754306082;5754.762726013843;5768.596198266081;5778.971105144447;5802.127946666427;5836.562257830679;3568.678818906259;3619.674229319321;3620.3483372992587;3635.0367152704553;3650.653187947141;6485.935140648628;6533.509986751013;6550.737970481223;6567.868642627121;6591.472682185414;6888.104546408935;6945.652717364112;7025.894611580728;7026.43788496919;7053.576785392893;6541.34586541342;6574.868502630016;6583.44040756541;6634.170728658918;6636.701348439332;6636.203655561363;6666.733330666719;6774.966125169342;6810.139741308762;6866.498085976288;6672.599644021374;6702.352570734435;6775.322467753185;6792.388685018372;6799.626020568916;6401.903971440339;6520.315366886598;6539.803805885831;6540.803775886731;6624.436922861611;4955.677880937727;4970.975145124251;4983.259207435824;4990.176620857518;5049.166887463527;6454.257760357431;6623.602583844985;6668.699908504226;6856.794296171123;6871.415929646036;6527.184101987252;6586.407223349922;6589.901151482636;6592.868142637216;6625.900611490928;6502.902456463063;6504.38208370204;6577.539572229898;6613;6620.63586502747;6116.633002019893;6361.268454127835;6427.710753016032;6436.195475565554;6441.001644745072;6497.772577959749;6505.902411463737;6539.253513524426;6593.80218593443;6654.434373078343;6295.622262664255;6357.884636528161;6425.164728585254;6434.227892652912;6444.484441244783 03:34:55 INFO - PID 18652 | |5;yui.html;888.9111088891111;938.8591711243308;940.4874343482801;946.6308139825464;949.6391371278916;18677.81322186778;18860.434186974395;19311.420657380284;19324.90337548312;19331.613367732636;18711.3451029214;18715.157817898194;18729;18830.34093806717;18876.716849247274;978.33473238198;999.5801763259449;1000.0049950299426;1001.3991605036992;1001.4642166440956;949.1125797379473;984.1093810101869;995.3032876986102;999.9950000249988;1001.2590682894642;1032.4424810602266;1039.8232300508932;1043.056034288966;1043.0925095167215;1044.0238377117419;980.4411485453295;1015.1929216273007;1033.0341131042462;1035.8135535603587;1038.5897570459701;749.568997826252;750.8685979953541;751.0761763031502;751.0836779129507;752.8833030880222;18435.63128737431;18439.90780046091;18445.35441259563;18978.52553686155;19008;21287.78712212889;21735.782642173694;21790;21940.89029554842;21956.23153189631;16786.160691965473;17084.31662733489;17480;17494.300227990865;17512.21195046226;3747.7189210809024;3771.4719939208535;3773.2830762155104;3773.5094437723196;3775.6790672792845;140324;140703;141217.2939135308;143140.2842985789;146116;16110.275037623334;16430.589235269214;16522.09128497944;16631;16643.750343744854;25859.224223272955;26545.46909061808;28875.711242887723;28907.56638650422;28965.56551651727;8199.139090395542;8279.793005174919;8280.337572994145;8301.501909885306;8303.377246706401;3920.176762879827;3954.228925359585;3977.801109944549;3977.940330895039;3982.26328129297;5284.630075894651;5377.112776391929;5381.4349493303425;5395.541378982752;5395.730213489388;943.8112377524523;961.8076384723083;974.2060220919927;982.091565302107;989.7773001074701;696.1854630082815;710.9608971506514;722.7831650504828;736.138717700292;742.6249743879306;11795.941020294844;12066.155369124253;12098.455569499394;12126.69683257908;12810.295433751233;649.8270621528093;662.5428454366472;674.8043067510367;674.9426298764562;683.9418649414855;5282.128448805904;5358.46415358461;5368;5394.244805727201;5432;123589;125582;126875.3656231713;128092;128861;87196.56401717951;106324.46837765761;107989.46005269923;110521;112067.43966280279;93710;95799;97068.51465742626;98494;98742.5062874681;12606.180598261451;12939.417726202155;12978.221306721627;13002.544910928073;13101.082924195214;42586.361204581335;42762.358564622176;42795.860206990146;43605.345919811865;44018.119637607706;55459.722701386236;55709.16436253378;56840;57234;58102.70948645399;17613;17649;17749.467515974542;17872.195751190968;17998.460046198634;19797.01014949218;20117.094730737444;20129.496762581053;20261.69607455919;20268;18248.817511824713;18417;18447.16987735582;18502.44492665222;18587.90706046461;17385;17455.738163927297;17587.648247034733;17703.468895933143;17712.91143544274;17895.284188632308;17983.550411239823;18126.818731812513;18148.637027259116;18149.183286751864;13423.127496712683;13684.93157534206;13737.793933091212;13888.305584720925;13967.580972570839;12484.313362765135;12606.936965315115;12612;12921.418536166084;12972.286524241257 03:34:55 INFO - PID 18652 | __end_tp_report 03:34:55 INFO - PID 18652 | __start_cc_report 03:34:55 INFO - PID 18652 | _x_x_mozilla_cycle_collect,373 03:34:55 INFO - PID 18652 | __end_cc_report 03:34:55 INFO - PID 18652 | __startTimestamp1505126095044__endTimestamp 03:34:55 INFO - PID 18652 | 03:34:55 INFO - PID 18652 | ------- Summary: start ------- 03:34:55 INFO - PID 18652 | Number of tests: 6 03:34:55 INFO - PID 18652 | 03:34:55 INFO - PID 18652 | [#0] dojo.html Cycles:165 Average:6247.79 Median:6510.28 stddev:852.35 (13.1%) stddev-sans-first:819.06 03:34:55 INFO - PID 18652 | Values: 3127.3 3135.1 3178.0 3178.1 3186.2 6243.7 6330.9 6385.7 6426.1 6469.4 6307.9 6315.9 6324.4 6360.4 6394.7 5876.6 5911.7 5934.0 5970.3 5982.9 1320.0 5821.5 5898.7 5939.2 5986.0 5773.2 5776.5 5853.0 5899.5 5910.6 5853.5 5871.6 5923.2 5946.7 5950.7 5822.2 5838.0 5889.7 5902.0 5915.6 6275.3 6370.9 6424.9 6444.9 6475.8 6567.7 6726.4 6750.7 6827.6 6916.7 6872.7 6942.4 6943.1 6945.4 6952.3 6184.1 6267.5 6328.6 6432.4 6435.1 6964.9 7078.8 7114.6 7117.4 7118.0 6695.6 6709.4 6765.4 6798.3 6807.0 6622.9 6729.6 6732.8 6802.7 6882.3 6811.6 6811.9 6815.0 6828.5 6830.0 6878.3 6880.4 6888.0 6890.3 6907.3 5309.4 5325.8 5377.6 5381.6 5385.0 4622.9 4628.6 4688.7 4688.9 4692.1 5724.7 5815.7 5835.0 5898.2 5920.3 6842.9 6854.9 6891.9 6913.9 6923.1 5833.8 5844.3 5929.8 5935.7 5939.2 5731.9 5859.4 5864.3 5931.9 5938.4 6510.3 6530.2 6614.0 6615.6 6621.6 6854.2 6892.1 6905.9 6906.7 6919.3 6565.5 6578.8 6583.4 6653.4 6668.3 6598.7 6703.1 6706.8 6729.0 6833.9 6859.4 6879.0 6880.4 6893.4 6907.8 6596.0 6599.6 6659.2 6660.5 6679.4 6591.2 6616.0 6649.5 6654.8 6669.4 6291.8 6401.6 6428.1 6476.7 6583.7 6685.2 6690.5 6696.9 6699.4 6729.6 6296.4 6395.9 6441.1 6524.1 6531.4 03:34:55 INFO - PID 18652 | 03:34:55 INFO - PID 18652 | [#1] ext.html Cycles:180 Average:36970.35 Median:34831.72 stddev:52076.67 (149.5%) stddev-sans-first:52152.01 03:34:55 INFO - PID 18652 | Values: 820.1 825.8 830.0 866.1 871.5 38750.4 41645.8 42096.0 42289.6 42413.8 42110.4 42606.4 42680.0 42700.6 42759.4 7866.0 7948.8 7970.5 7978.0 8007.2 7852.6 7952.2 7958.2 7958.2 7963.6 7979.6 8010.0 8011.3 8015.0 8019.9 7920.3 7920.3 7947.1 7948.7 7955.6 7800.7 7864.4 7866.4 7866.7 7881.5 37536.2 37827.6 37850.8 37868.8 37902.4 3541.4 3576.8 3576.9 3578.6 3579.4 30556.7 30744.0 30790.2 30978.7 30982.0 5054.5 5185.0 5229.1 5241.8 5243.5 183606.0 183958.0 184489.0 184934.0 184988.0 47595.3 47949.3 48061.3 48162.0 48205.3 50359.5 50385.2 50455.7 50564.7 50593.0 15445.3 15668.5 15702.2 15714.8 15725.2 12792.3 12838.2 12876.8 12891.9 12895.5 13190.6 13367.8 13443.1 13495.5 13690.5 5281.8 5470.6 5476.8 5478.9 5479.1 983.4 999.4 1003.9 1013.1 1015.1 22381.7 22425.1 22458.4 22575.7 22727.0 1093.6 1099.5 1107.6 1112.1 1114.6 11939.5 11960.2 12047.5 12059.2 12134.3 213901.0 213911.0 214334.0 232034.0 235589.0 56008.4 56041.2 56125.7 56179.0 56315.7 1400.5 1436.9 1441.5 1442.9 1446.8 25489.6 26125.5 26212.0 26425.5 26619.1 36477.3 36521.5 36588.8 36591.6 36592.8 194684.0 194771.0 194808.0 194888.0 195003.0 33024.5 33140.5 33186.7 33509.8 33520.0 39715.8 41062.4 41123.2 41224.4 41311.0 31159.8 31259.0 31437.8 31822.2 32088.0 31039.2 31072.2 31123.4 31256.8 31386.2 34518.3 34573.7 34787.0 34876.4 34982.7 30453.0 30473.5 30547.8 30598.4 30603.8 22558.4 22824.8 22840.9 22841.3 23259.0 03:34:55 INFO - PID 18652 | 03:34:55 INFO - PID 18652 | [#2] jquery.html Cycles:180 Average:32742.78 Median:22756.37 stddev:75480.39 (331.7%) stddev-sans-first:75653.42 03:34:55 INFO - PID 18652 | Values: 546.0 559.4 564.0 565.7 578.2 22079.0 22683.2 22914.4 22930.9 22963.5 22832.8 23182.0 23182.3 23216.3 23473.5 6445.0 6730.4 6744.7 6745.8 6746.4 6638.8 6748.0 6749.3 6756.5 6757.2 6209.7 6445.2 6464.4 6466.3 6473.7 6578.3 6609.3 6619.3 6630.0 6631.3 5950.5 6129.0 6167.1 6186.2 6189.0 29759.4 30383.0 30392.0 30428.7 30466.2 2014.2 2033.8 2035.8 2036.2 2039.0 19445.6 19498.7 19796.1 19906.1 20101.1 2448.3 2459.2 2494.3 2517.0 2521.7 116967.4 123446.4 125677.0 134393.0 134610.0 14409.3 14435.9 14733.6 14779.0 14948.9 22829.5 22864.3 22888.8 22911.7 22949.5 10070.9 10092.5 10237.4 10270.7 10296.5 9416.3 9420.6 9463.5 9484.9 9591.5 8978.6 9082.0 9131.6 9165.9 9406.6 2501.9 2557.3 2644.1 2652.0 2654.2 939.4 978.2 981.4 982.3 986.0 18947.8 18983.4 19305.4 19431.3 19716.8 997.9 1019.9 1020.5 1025.5 1029.9 10616.2 10633.7 10751.8 10804.1 11001.5 437555.0 444618.0 447153.0 450309.0 456839.0 111402.4 111981.4 112047.4 112279.0 112939.0 1139.6 1200.5 1205.4 1210.1 1215.9 17184.1 17344.8 17391.7 17552.8 17706.3 49789.8 52098.0 52522.5 53473.5 54099.5 64714.4 66913.7 67030.7 67114.9 67242.3 14005.0 14079.6 14293.8 14295.0 14298.7 21344.5 21467.9 21708.7 21738.2 22352.0 17221.7 17259.1 18156.0 18505.8 18522.2 14465.5 15343.6 15408.7 16274.0 16492.8 17571.0 18406.5 18708.4 18874.2 18949.4 14043.2 14057.3 14057.9 14126.4 14187.9 17888.3 17975.8 18091.0 18130.3 18150.5 03:34:55 INFO - PID 18652 | 03:34:55 INFO - PID 18652 | [#3] mootools.html Cycles:180 Average:9256.11 Median:2765.66 stddev:6660.55 (240.8%) stddev-sans-first:6652.72 03:34:55 INFO - PID 18652 | Values: 1345.5 1407.4 1410.9 1412.3 1414.0 14917.1 15554.8 15578.1 15586.5 15593.0 15101.5 15302.6 15313.6 15330.4 15333.9 2771.9 2807.6 2837.2 2842.4 2844.9 2766.1 2795.9 2806.7 2809.7 2814.1 2723.6 2837.6 2837.7 2838.5 2840.0 2765.2 2768.5 2806.0 2813.3 2813.6 2771.4 2782.4 2789.8 2793.1 2795.3 15016.5 15061.0 15087.2 15184.2 15230.4 7741.7 8052.9 8053.5 8057.1 8066.1 5141.9 5227.6 5313.9 5389.4 5424.6 1941.9 1968.1 1976.9 1990.0 1993.1 26451.7 26726.9 26743.7 26755.5 26793.3 6600.8 6670.2 6707.6 6969.4 7044.4 17252.3 17253.1 17308.5 17310.8 17330.7 7722.9 7929.1 7951.2 7952.8 7952.8 7021.9 7133.9 7161.2 7163.2 7193.1 4889.3 4983.2 5017.4 5030.2 5060.8 1217.6 1286.2 1441.5 1454.9 1463.8 772.8 907.3 913.7 914.0 918.8 8013.6 8308.5 8325.9 8437.4 8438.0 988.7 1006.3 1006.6 1014.2 1015.1 4516.1 4522.7 4894.8 5314.9 5448.1 20610.0 21680.0 21936.7 21937.6 21944.0 20645.6 21183.8 21418.9 21554.1 21572.2 11273.7 11351.8 11360.4 11391.4 11393.3 8214.8 8327.3 8338.5 8349.6 8384.7 18129.2 18255.9 18258.8 18450.6 18468.9 18781.2 18834.0 18914.8 18916.0 18985.5 10904.6 10909.4 10927.0 11030.4 11054.0 13668.8 13900.2 13928.5 13960.1 13961.2 11275.7 11335.4 11403.5 11567.9 11689.8 10175.2 10297.0 10349.5 10446.0 10668.3 8980.6 9154.1 9211.0 9221.7 9266.3 6818.6 6827.1 6899.2 6909.6 6968.4 6801.9 6804.1 6874.8 6908.5 6922.2 03:34:55 INFO - PID 18652 | 03:34:55 INFO - PID 18652 | [#4] prototype.html Cycles:180 Average:5554.75 Median:6283.11 stddev:1554.17 (24.7%) stddev-sans-first:1511.56 03:34:55 INFO - PID 18652 | Values: 502.5 515.0 519.5 520.8 526.9 6305.9 6331.8 6385.6 6445.6 6470.0 6195.9 6245.8 6250.6 6303.4 6318.3 5199.7 5238.4 5262.2 5263.5 5270.9 4849.8 5114.3 5124.9 5275.2 5293.6 4954.2 4970.4 4992.3 5000.1 5013.2 4954.1 4985.2 5017.6 5044.6 5075.6 4479.4 4515.7 4521.1 4532.8 4540.1 6228.3 6275.0 6291.2 6301.5 6303.0 5654.7 5691.2 5724.6 5762.7 5777.7 6540.5 6551.9 6554.5 6568.8 6587.6 4897.0 4898.2 4902.9 4910.5 4910.6 6619.7 6679.5 6722.3 6800.4 6805.8 4952.4 4980.7 4994.2 5028.0 5040.0 5082.9 5085.5 5087.8 5087.9 5117.0 5774.5 5780.5 5782.1 5796.9 5802.3 6557.0 6577.7 6655.8 6678.2 6692.9 1721.3 1747.0 1759.6 1761.6 1762.4 1177.6 1199.5 1213.5 1233.7 1256.1 5600.2 5674.0 5699.1 5762.2 5810.2 6759.8 6795.9 6839.9 6855.1 6889.8 5754.8 5768.6 5779.0 5802.1 5836.6 3568.7 3619.7 3620.3 3635.0 3650.7 6485.9 6533.5 6550.7 6567.9 6591.5 6888.1 6945.7 7025.9 7026.4 7053.6 6541.3 6574.9 6583.4 6634.2 6636.7 6636.2 6666.7 6775.0 6810.1 6866.5 6672.6 6702.4 6775.3 6792.4 6799.6 6401.9 6520.3 6539.8 6540.8 6624.4 4955.7 4971.0 4983.3 4990.2 5049.2 6454.3 6623.6 6668.7 6856.8 6871.4 6527.2 6586.4 6589.9 6592.9 6625.9 6502.9 6504.4 6577.5 6613.0 6620.6 6116.6 6361.3 6427.7 6436.2 6441.0 6497.8 6505.9 6539.3 6593.8 6654.4 6295.6 6357.9 6425.2 6434.2 6444.5 03:34:55 INFO - PID 18652 | 03:34:55 INFO - PID 18652 | [#5] yui.html Cycles:180 Average:24680.68 Median:19328.26 stddev:35562.44 (184.0%) stddev-sans-first:35617.34 03:34:55 INFO - PID 18652 | Values: 888.9 938.9 940.5 946.6 949.6 18677.8 18860.4 19311.4 19324.9 19331.6 18711.3 18715.2 18729.0 18830.3 18876.7 978.3 999.6 1000.0 1001.4 1001.5 949.1 984.1 995.3 1000.0 1001.3 1032.4 1039.8 1043.1 1043.1 1044.0 980.4 1015.2 1033.0 1035.8 1038.6 749.6 750.9 751.1 751.1 752.9 18435.6 18439.9 18445.4 18978.5 19008.0 21287.8 21735.8 21790.0 21940.9 21956.2 16786.2 17084.3 17480.0 17494.3 17512.2 3747.7 3771.5 3773.3 3773.5 3775.7 140324.0 140703.0 141217.3 143140.3 146116.0 16110.3 16430.6 16522.1 16631.0 16643.8 25859.2 26545.5 28875.7 28907.6 28965.6 8199.1 8279.8 8280.3 8301.5 8303.4 3920.2 3954.2 3977.8 3977.9 3982.3 5284.6 5377.1 5381.4 5395.5 5395.7 943.8 961.8 974.2 982.1 989.8 696.2 711.0 722.8 736.1 742.6 11795.9 12066.2 12098.5 12126.7 12810.3 649.8 662.5 674.8 674.9 683.9 5282.1 5358.5 5368.0 5394.2 5432.0 123589.0 125582.0 126875.4 128092.0 128861.0 87196.6 106324.5 107989.5 110521.0 112067.4 93710.0 95799.0 97068.5 98494.0 98742.5 12606.2 12939.4 12978.2 13002.5 13101.1 42586.4 42762.4 42795.9 43605.3 44018.1 55459.7 55709.2 56840.0 57234.0 58102.7 17613.0 17649.0 17749.5 17872.2 17998.5 19797.0 20117.1 20129.5 20261.7 20268.0 18248.8 18417.0 18447.2 18502.4 18587.9 17385.0 17455.7 17587.6 17703.5 17712.9 17895.3 17983.6 18126.8 18148.6 18149.2 13423.1 13684.9 13737.8 13888.3 13967.6 12484.3 12606.9 12612.0 12921.4 12972.3 03:34:55 INFO - PID 18652 | -------- Summary: end -------- 03:34:55 INFO - PID 18652 | 03:34:55 INFO - TEST-INFO | 18652: exit 0 03:34:55 INFO - TEST-OK | dromaeo_css | took 1109678ms 03:34:55 INFO - TEST-START | kraken 03:34:55 INFO - Initialising browser for kraken test... 03:34:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59272/getInfo.html -profile /tmp/tmpe6vlh7/profile 03:34:55 INFO - TEST-INFO | started process 19033 (/builds/slave/test/build/application/firefox/firefox http://localhost:59272/getInfo.html) 03:35:01 INFO - TEST-INFO | 19033: exit 0 03:35:02 INFO - Browser initialized. 03:35:02 INFO - Running cycle 1/1 for kraken test... 03:35:02 INFO - Using env: {'DISPLAY': ':0', 03:35:02 INFO - 'HOME': '/home/cltbld', 03:35:02 INFO - 'JSGC_DISABLE_POISONING': '1', 03:35:02 INFO - 'LANG': 'en_US.UTF-8', 03:35:02 INFO - 'LANGUAGE': 'en_US:en', 03:35:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:35:02 INFO - 'LOGNAME': 'cltbld', 03:35:02 INFO - 'MAIL': '/var/mail/cltbld', 03:35:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:35:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:35:02 INFO - 'MOZ_CRASHREPORTER': '1', 03:35:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:35:02 INFO - 'MOZ_NO_REMOTE': '1', 03:35:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:35:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:35:02 INFO - 'NO_EM_RESTART': '1', 03:35:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:35:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:02 INFO - 'PWD': '/builds/slave/test', 03:35:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:35:02 INFO - 'RUST_BACKTRACE': 'full', 03:35:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:35:02 INFO - 'SHELL': '/bin/bash', 03:35:02 INFO - 'SHLVL': '1', 03:35:02 INFO - 'TERM': 'linux', 03:35:02 INFO - 'TMOUT': '86400', 03:35:02 INFO - 'USER': 'cltbld', 03:35:02 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738', 03:35:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:02 INFO - '_': '/tools/buildbot/bin/python'} 03:35:02 INFO - TEST-INFO | started process 19231 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpe6vlh7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:35:03 INFO - PID 19231 | 03:35:03 INFO - PID 19231 | (/builds/slave/test/build/application/firefox/firefox:19281): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:35:03 INFO - PID 19231 | 03:35:13 INFO - PID 19231 | RSS: Main: 167202816 03:35:13 INFO - PID 19231 | 03:35:16 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59272/tests/kraken/driver.html?testName=audio-beat-detection) 03:35:17 INFO - PID 19231 | RSS: Main: 172101632 03:35:17 INFO - PID 19231 | 03:35:19 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59272/tests/kraken/driver.html?testName=audio-dft) 03:35:20 INFO - PID 19231 | RSS: Main: 172244992 03:35:20 INFO - PID 19231 | 03:35:23 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59272/tests/kraken/driver.html?testName=audio-fft) 03:35:23 INFO - PID 19231 | RSS: Main: 168292352 03:35:23 INFO - PID 19231 | 03:35:26 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59272/tests/kraken/driver.html?testName=audio-oscillator) 03:35:26 INFO - PID 19231 | RSS: Main: 168869888 03:35:26 INFO - PID 19231 | 03:35:28 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:35:29 INFO - PID 19231 | RSS: Main: 169254912 03:35:29 INFO - PID 19231 | 03:35:32 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59272/tests/kraken/driver.html?testName=imaging-darkroom) 03:35:33 INFO - PID 19231 | RSS: Main: 171630592 03:35:33 INFO - PID 19231 | 03:35:36 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59272/tests/kraken/driver.html?testName=imaging-desaturate) 03:35:37 INFO - PID 19231 | RSS: Main: 172015616 03:35:37 INFO - PID 19231 | 03:35:40 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59272/tests/kraken/driver.html?testName=json-parse-financial) 03:35:41 INFO - PID 19231 | RSS: Main: 172023808 03:35:41 INFO - PID 19231 | 03:35:42 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:35:43 INFO - PID 19231 | RSS: Main: 172605440 03:35:43 INFO - PID 19231 | 03:35:45 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59272/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:35:45 INFO - PID 19231 | RSS: Main: 172105728 03:35:45 INFO - PID 19231 | 03:35:47 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:35:48 INFO - PID 19231 | RSS: Main: 172146688 03:35:48 INFO - PID 19231 | 03:35:51 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:35:51 INFO - PID 19231 | RSS: Main: 172527616 03:35:51 INFO - PID 19231 | 03:35:54 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:35:55 INFO - PID 19231 | RSS: Main: 172937216 03:35:55 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | Cycle 1(1): loaded http://localhost:59272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59272/tests/kraken/driver.html?testName=ai-astar) 03:35:57 INFO - PID 19231 | RSS: Main: 174366720 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | __start_tp_report 03:35:57 INFO - PID 19231 | _x_x_mozilla_page_load 03:35:57 INFO - PID 19231 | _x_x_mozilla_page_load_details 03:35:57 INFO - PID 19231 | |i|pagename|runs| 03:35:57 INFO - PID 19231 | |0;ai-astar;108;107;107;106;106;103;109;105;107;107 03:35:57 INFO - PID 19231 | |1;audio-beat-detection;118;118;120;145;113;117;118;117;116;117 03:35:57 INFO - PID 19231 | |2;audio-dft;164;163;163;163;163;163;166;164;168;164 03:35:57 INFO - PID 19231 | |3;audio-fft;75;73;73;73;73;82;74;75;76;74 03:35:57 INFO - PID 19231 | |4;audio-oscillator;80;83;80;79;80;80;79;79;80;79 03:35:57 INFO - PID 19231 | |5;imaging-gaussian-blur;113;114;113;114;114;114;116;114;115;116 03:35:57 INFO - PID 19231 | |6;imaging-darkroom;160;148;148;148;148;148;148;149;149;148 03:35:57 INFO - PID 19231 | |7;imaging-desaturate;94;94;93;99;99;99;99;94;93;93 03:35:57 INFO - PID 19231 | |8;json-parse-financial;53;52;52;53;53;53;51;51;51;52 03:35:57 INFO - PID 19231 | |9;json-stringify-tinderbox;39;38;39;38;38;38;38;38;39;38 03:35:57 INFO - PID 19231 | |10;stanford-crypto-aes;74;79;74;76;77;75;75;74;76;75 03:35:57 INFO - PID 19231 | |11;stanford-crypto-ccm;116;126;119;118;115;131;120;126;121;120 03:35:57 INFO - PID 19231 | |12;stanford-crypto-pbkdf2;169;168;175;166;172;169;175;176;171;170 03:35:57 INFO - PID 19231 | |13;stanford-crypto-sha256-iterative;67;63;63;64;68;65;65;64;63;64 03:35:57 INFO - PID 19231 | __end_tp_report 03:35:57 INFO - PID 19231 | __start_cc_report 03:35:57 INFO - PID 19231 | _x_x_mozilla_cycle_collect,933 03:35:57 INFO - PID 19231 | __end_cc_report 03:35:57 INFO - PID 19231 | __startTimestamp1505126157342__endTimestamp 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | ------- Summary: start ------- 03:35:57 INFO - PID 19231 | Number of tests: 14 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#0] ai-astar Cycles:10 Average:106.50 Median:107.00 stddev:1.65 (1.5%) stddev-sans-first:1.66 03:35:57 INFO - PID 19231 | Values: 108.0 107.0 107.0 106.0 106.0 103.0 109.0 105.0 107.0 107.0 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#1] audio-beat-detection Cycles:10 Average:119.90 Median:118.00 stddev:9.00 (7.6%) stddev-sans-first:9.52 03:35:57 INFO - PID 19231 | Values: 118.0 118.0 120.0 145.0 113.0 117.0 118.0 117.0 116.0 117.0 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:1.66 (1.0%) stddev-sans-first:1.76 03:35:57 INFO - PID 19231 | Values: 164.0 163.0 163.0 163.0 163.0 163.0 166.0 164.0 168.0 164.0 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#3] audio-fft Cycles:10 Average:74.80 Median:74.50 stddev:2.74 (3.7%) stddev-sans-first:2.91 03:35:57 INFO - PID 19231 | Values: 75.0 73.0 73.0 73.0 73.0 82.0 74.0 75.0 76.0 74.0 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:80.00 stddev:1.20 (1.5%) stddev-sans-first:1.27 03:35:57 INFO - PID 19231 | Values: 80.0 83.0 80.0 79.0 80.0 80.0 79.0 79.0 80.0 79.0 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:1.06 (0.9%) stddev-sans-first:1.01 03:35:57 INFO - PID 19231 | Values: 113.0 114.0 113.0 114.0 114.0 114.0 116.0 114.0 115.0 116.0 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#6] imaging-darkroom Cycles:10 Average:149.40 Median:148.00 stddev:3.75 (2.5%) stddev-sans-first:0.44 03:35:57 INFO - PID 19231 | Values: 160.0 148.0 148.0 148.0 148.0 148.0 148.0 149.0 149.0 148.0 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.50 stddev:2.87 (3.0%) stddev-sans-first:2.98 03:35:57 INFO - PID 19231 | Values: 94.0 94.0 93.0 99.0 99.0 99.0 99.0 94.0 93.0 93.0 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.50 stddev:0.88 (1.7%) stddev-sans-first:0.87 03:35:57 INFO - PID 19231 | Values: 53.0 52.0 52.0 53.0 53.0 53.0 51.0 51.0 51.0 52.0 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.00 stddev:0.48 (1.3%) stddev-sans-first:0.44 03:35:57 INFO - PID 19231 | Values: 39.0 38.0 39.0 38.0 38.0 38.0 38.0 38.0 39.0 38.0 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#10] stanford-crypto-aes Cycles:10 Average:75.50 Median:75.50 stddev:1.58 (2.1%) stddev-sans-first:1.58 03:35:57 INFO - PID 19231 | Values: 74.0 79.0 74.0 76.0 77.0 75.0 75.0 74.0 76.0 75.0 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#11] stanford-crypto-ccm Cycles:10 Average:121.20 Median:120.50 stddev:5.01 (4.2%) stddev-sans-first:4.94 03:35:57 INFO - PID 19231 | Values: 116.0 126.0 119.0 118.0 115.0 131.0 120.0 126.0 121.0 120.0 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.10 Median:171.50 stddev:3.35 (2.0%) stddev-sans-first:3.46 03:35:57 INFO - PID 19231 | Values: 169.0 168.0 175.0 166.0 172.0 169.0 175.0 176.0 171.0 170.0 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:64.50 stddev:1.71 (2.7%) stddev-sans-first:1.58 03:35:57 INFO - PID 19231 | Values: 67.0 63.0 63.0 64.0 68.0 65.0 65.0 64.0 63.0 64.0 03:35:57 INFO - PID 19231 | -------- Summary: end -------- 03:35:57 INFO - PID 19231 | 03:35:57 INFO - PID 19231 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:35:58 INFO - TEST-INFO | 19231: exit 0 03:35:58 INFO - TEST-OK | kraken | took 62310ms 03:35:58 INFO - SUITE-END | took 1171s 03:35:58 INFO - Completed test suite (00:19:32) 03:35:58 INFO - javascript benchmark 03:35:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8514.834348287242, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3127.32762456072, 3135.075152829915, 3177.95233071504, 3178.07835727639, 3186.203449137716, 6243.65659888706, 6330.936690633092, 6385.744570217192, 6426.100345951565, 6469.385408386208, 6307.873842523147, 6315.873682526347, 6324.399182077706, 6360.395762402576, 6394.680265986705, 5876.618019828718, 5911.73397197125, 5933.961556727577, 5970.313413957407, 5982.910256346158, 1320.030912116297, 5821.53427725781, 5898.705064746767, 5939.198208241875, 5986.0, 5773.191753154561, 5776.5089967352615, 5853.0, 5899.46904778572, 5910.556708246877, 5853.4684722936845, 5871.588988770788, 5923.17075609415, 5946.672932988684, 5950.702464876739, 5822.184894114827, 5837.970810145965, 5889.676067816268, 5901.970490147564, 5915.556333275022, 6275.341089185616, 6370.872582548323, 6424.871502569922, 6444.935550644527, 6475.773347932847, 6567.67161641916, 6726.42825359845, 6750.729970801162, 6827.556208846409, 6916.688749006306, 6872.656367181621, 6942.444604431684, 6943.097397338364, 6945.444364450832, 6952.304769523058, 6184.134221209034, 6267.4672652824565, 6328.588641738273, 6432.356764323577, 6435.131257280254, 6964.93035069653, 7078.752243671447, 7114.608696521637, 7117.4306055515435, 7118.0, 6695.598264104169, 6709.36261055199, 6765.42493888025, 6798.252192258848, 6807.047013418125, 6622.933770662232, 6729.596224226562, 6732.831679207961, 6802.693878775467, 6882.346177113166, 6811.557248778813, 6811.863762724718, 6814.965925170342, 6828.52200345981, 6829.965850170816, 6878.3465570770695, 6880.415164710956, 6887.965560172167, 6890.31096890307, 6907.34380233877, 5309.41596424393, 5325.786968521294, 5377.623566350396, 5381.623286369917, 5384.9730751346, 4622.86131416058, 4628.629709623222, 4688.742119183477, 4688.929666055012, 4692.085043416501, 5724.685142317213, 5815.709214539256, 5835.037218858841, 5898.2332296802, 5920.2895652521975, 6842.863142737117, 6854.897176542356, 6891.896621550681, 6913.930860691329, 6923.065386172802, 5833.82498525045, 5844.327902291206, 5929.762809487572, 5935.6735379553675, 5939.227900372924, 5731.885362292731, 5859.355470898197, 5864.325602555676, 5931.881362372815, 5938.376470470625, 6510.283868774431, 6530.216374035146, 6614.00789881512, 6615.636140012345, 6621.602703837785, 6854.2117656470145, 6892.138482689664, 6905.930940690529, 6906.689198985957, 6919.308069193041, 6565.474762018932, 6578.76974305897, 6583.407493325623, 6653.367930046637, 6668.299828517936, 6598.703058362481, 6703.128593282842, 6706.798796036127, 6729.0, 6833.863322733617, 6859.382655561023, 6879.0, 6880.415164711056, 6893.448524118159, 6907.792766217021, 6596.0, 6599.6040237583975, 6659.2008958925235, 6660.500462465431, 6679.365460281266, 6591.242007169237, 6616.0, 6649.534532582673, 6654.800355989328, 6669.399754022162, 6291.811245662638, 6401.64790936503, 6428.100065990859, 6476.676166191577, 6583.736650533925, 6685.164354455693, 6690.464762818866, 6696.899546506904, 6699.363560461748, 6729.629870357177, 6296.401841825019, 6395.880720873922, 6441.098246245436, 6524.086627872007, 6531.412172904462], "value": 6184.942842812241, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.1388542030866, 825.8224481736426, 829.9668013279468, 866.1425189062828, 871.507598207013, 38750.41874371887, 41645.79177104114, 42096.0, 42289.57710422895, 42413.78793106034, 42110.36834447486, 42606.36090458646, 42680.0, 42700.57299427005, 42759.3586096208, 7865.960670196655, 7948.801279967989, 7970.4819186752675, 7978.042634883822, 8007.159248278934, 7852.568108754016, 7952.244536769027, 7958.164392738766, 7958.204179582053, 7963.641636126388, 7979.601019949008, 8010.038795344567, 8011.319037881758, 8015.0, 8019.919800802005, 7920.287174154296, 7920.287174154325, 7947.08608510023, 7948.7217947371855, 7955.562444065574, 7800.687972481094, 7864.449488535804, 7866.370690344758, 7866.68533258669, 7881.527108373516, 37536.24927501462, 37827.621723782686, 37850.81074594637, 37868.810655946545, 37902.43146352807, 3541.4156664150382, 3576.8211589420685, 3576.91057723559, 3578.58846232684, 3579.4093974494176, 30556.69443305583, 30744.0, 30790.23024424385, 30978.690213097805, 30982.0, 5054.519820617035, 5185.0, 5229.111051121318, 5241.764120614582, 5243.501867322599, 183606.0, 183958.0, 184489.0, 184934.0, 184988.0, 47595.286070708964, 47949.28076078861, 48061.279080813816, 48162.0, 48205.27692084621, 50359.49640503548, 50385.24422133671, 50455.747721261156, 50564.74717626388, 50593.0, 15445.304961276544, 15668.451604194026, 15702.21488925572, 15714.842851571566, 15725.213739312989, 12792.2964236966, 12838.229706217657, 12876.80684789729, 12891.935540322238, 12895.548655797002, 13190.60428187156, 13367.799483007762, 13443.126196797373, 13495.460181592824, 13690.52083177084, 5281.762320695578, 5470.56235501159, 5476.808311709071, 5478.863028424242, 5479.123340265538, 983.4099540275778, 999.4053538144792, 1003.9247056470793, 1013.1337706261188, 1015.1422048369117, 22381.664275035888, 22425.10299587998, 22458.438539036328, 22575.661365079537, 22727.0, 1093.639099097296, 1099.4722533184117, 1107.5791199344199, 1112.1158678850172, 1114.6321713834416, 11939.522419103312, 11960.162788604714, 12047.518099276105, 12059.21615095016, 12134.332611706264, 213901.0, 213911.0, 214334.0, 232034.0, 235589.0, 56008.43991560032, 56041.159382609294, 56125.71937140288, 56179.0, 56315.71842140763, 1400.4748219417718, 1436.9281535923371, 1441.509886638527, 1442.8917831162705, 1446.8191476065492, 25489.617655734808, 26125.477490450463, 26212.0, 26425.471490570464, 26619.06833260904, 36477.27045459129, 36521.45217821788, 36588.81705591455, 36591.63408365882, 36592.817035915716, 194684.0, 194771.0, 194808.0, 194888.0, 195003.0, 33024.50463243005, 33140.50289245615, 33186.66813331836, 33509.83245083759, 33520.0, 39715.80142099271, 41062.38406423966, 41123.1775364497, 41224.38163427611, 41311.0, 31159.84420077885, 31259.0, 31437.842810785798, 31822.204444889063, 32088.0, 31039.224019399695, 31072.22319442032, 31123.37753244968, 31256.843715782186, 31386.21534461657, 34518.30963380769, 34573.65426345704, 34787.0, 34876.43056062433, 34982.65017349794, 30453.0, 30473.54289685612, 30547.847260763552, 30598.388032238785, 30603.846980764953, 22558.436039099153, 22824.771752282264, 22840.885795570917, 22841.31476055721, 23259.0], "value": 17042.824542618102, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.9945400545998, 559.3874707195622, 563.9999999999998, 565.6747370262099, 578.1963071330849, 22079.0, 22683.20608778693, 22914.427139321484, 22930.885345573268, 22963.540729185406, 22832.771672283274, 23182.0, 23182.3045308641, 23216.303510894657, 23473.5305293894, 6444.967775161118, 6730.394264516192, 6744.662766861662, 6745.797626071201, 6746.359095885908, 6638.767643132492, 6747.954067119605, 6749.325067493261, 6756.49326300527, 6757.222919364287, 6209.658468784215, 6445.162128923257, 6464.385883341075, 6466.25638051623, 6473.6763161841955, 6578.342165783407, 6609.272979972199, 6619.338066193391, 6629.966850165766, 6631.336866313378, 5950.523958083386, 6129.049997250433, 6167.136600875881, 6186.195794546725, 6189.040698691665, 29759.404811903856, 30383.0, 30392.0, 30428.695713043027, 30466.238344041354, 2014.2345908554803, 2033.8169564739096, 2035.837133029369, 2036.2364113457454, 2038.9694154587694, 19445.611087778165, 19498.70751938722, 19796.13975274822, 19906.104225309897, 20101.095450704754, 2448.3389484838995, 2459.1638842793536, 2494.2517244826663, 2516.9748302516923, 2521.6721826162666, 116967.41516292449, 123446.38276808648, 125676.99999999908, 134393.0, 134609.999999999, 14409.289735391681, 14435.927820361041, 14733.55799326022, 14779.0, 14948.850511494964, 22829.543409132057, 22864.314070577577, 22888.771112288665, 22911.656325155134, 22949.541009179724, 10070.949645251727, 10092.495375231209, 10237.385756854612, 10270.691879243635, 10296.485175741183, 9416.293777966679, 9420.576074076684, 9463.479508626953, 9484.905150948402, 9591.472469014272, 8978.551072446351, 9081.954590227007, 9131.634734610541, 9165.90834091664, 9406.576704048197, 2501.912433064834, 2557.296743395581, 2644.0613582178435, 2651.9602205966926, 2654.2170059832397, 939.4410325856105, 978.222313260945, 981.3719219699398, 982.3369225772718, 985.9901400985898, 18947.81052189488, 18983.430497085108, 19305.420837374902, 19431.319903803582, 19716.802831971785, 997.8553109799038, 1019.8725159355081, 1020.5254556631054, 1025.5026312238558, 1029.8764148302103, 10616.203784716177, 10633.680989570325, 10751.83872241917, 10804.13566914645, 11001.504932278067, 437555.0, 444618.0, 447153.0, 450309.0, 456839.0, 111402.44298778617, 111981.44009279902, 112047.43976280067, 112279.0, 112939.0, 1139.6182278936649, 1200.5137919142762, 1205.379229696707, 1210.1226610707167, 1215.9088068394728, 17184.140792960054, 17344.826551734826, 17391.652166956843, 17552.82447175512, 17706.291748329924, 49789.751051244515, 52098.0, 52522.47477525176, 53473.46526534685, 54099.459005409444, 64714.35285647083, 66913.66543167448, 67030.66484667546, 67114.85598183285, 67242.3275767236, 14005.0, 14079.648008799863, 14293.785593215902, 14295.0, 14298.714025719635, 21344.466388340417, 21467.892660536596, 21708.67436988478, 21738.239161629266, 22351.99416026315, 17221.741673875153, 17259.05075220875, 18156.001419921504, 18505.81494185095, 18522.166502507687, 14465.4937077206, 15343.61640958985, 15408.691826163189, 16274.0, 16492.752608710638, 17571.0, 18406.500169691033, 18708.438746837615, 18874.150663220462, 18949.43151705451, 14043.1574105554, 14057.297135143406, 14057.859421405656, 14126.364313605707, 14187.929060354632, 17888.28446862111, 17975.820241797413, 18091.0, 18130.274789008294, 18150.5462363442], "value": 11731.317698266761, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.4887142885705, 1407.3948202273018, 1410.9365078571464, 1412.3079690951438, 1413.9646508837282, 14917.104973701586, 15554.844451555495, 15578.143202123907, 15586.454474093409, 15593.0, 15101.546953591382, 15302.617434564141, 15313.61715957099, 15330.386784528606, 15333.92333038336, 2771.8614069296555, 2807.5507918733006, 2837.205582436921, 2842.374677570931, 2844.871980760871, 2766.114843250161, 2795.9301017474622, 2806.705295943927, 2809.7190280971945, 2814.0713564523658, 2723.564229723245, 2837.574363845419, 2837.67366752824, 2838.531642279032, 2840.034388307975, 2765.2119146043283, 2768.5016696994535, 2806.031918987946, 2813.338865366637, 2813.6060951466707, 2771.4041481081663, 2782.4296019315993, 2789.7628701560393, 2793.1480898326004, 2795.2872017635577, 15016.47442339513, 15061.0, 15087.24563771807, 15184.164870932205, 15230.390784368612, 7741.729039483647, 8052.9194708052755, 8053.476524025919, 8057.073436554754, 8066.11272759996, 5141.948580514184, 5227.581793456515, 5313.8937221255355, 5389.380221274525, 5424.647397919122, 1941.93203237886, 1968.0750047477663, 1976.9308074217477, 1989.990050049755, 1993.1429485321305, 26451.73548264512, 26726.866365668047, 26743.732562674515, 26755.46489070208, 26793.33016674599, 6600.768973085919, 6670.232923213795, 6707.564008339442, 6969.407600354027, 7044.436445084381, 17252.309907603805, 17253.137343132792, 17308.480745577654, 17310.82689173092, 17330.74003889943, 7722.884156737653, 7929.1277959424415, 7951.165127661512, 7952.761417157494, 7952.801179970431, 7021.859562808715, 7133.857322853514, 7161.212266650664, 7163.2478589747325, 7193.136823581203, 4889.266610008527, 4983.227599722049, 5017.448080711119, 5030.220315851049, 5060.797568097235, 1217.573849152807, 1286.247545186058, 1441.5242969819935, 1454.8763355114722, 1463.751162302411, 772.7527191298843, 907.3013779389833, 913.6893456224915, 914.0448231597996, 918.8162367526388, 8013.559254241072, 8308.459950103224, 8325.916740832514, 8437.40938134337, 8438.0, 988.728099772554, 1006.3056491021172, 1006.5621454667244, 1014.2494554030112, 1015.1371334365878, 4516.1419330327135, 4522.66080043998, 4894.8042078317185, 5314.893702125936, 5448.101063324507, 20610.0, 21680.0, 21936.670949935764, 21937.561248774935, 21944.0, 20645.58708825845, 21183.78816211849, 21418.892905535373, 21554.13783448676, 21572.24497142624, 11273.661790146309, 11351.772964540663, 11360.375179365214, 11391.373474458984, 11393.3164010158, 8214.753557393049, 8327.292180164754, 8338.458000229966, 8349.58252087381, 8384.748457546284, 18129.184186711893, 18255.90872045631, 18258.817411825712, 18450.630987379907, 18468.907655462175, 18781.248750049846, 18834.0, 18914.810851891303, 18916.0, 18985.525361866064, 10904.563817447213, 10909.399983000694, 10927.0, 11030.448477575928, 11054.0, 13668.794968075686, 13900.16599004063, 13928.51250206238, 13960.092593981764, 13961.162330260217, 11275.66173014811, 11335.433228338716, 11403.543858245577, 11567.942160289145, 11689.766204676029, 10175.185985121321, 10297.000074810863, 10349.534270957674, 10446.0, 10668.253222274367, 8980.640774369213, 9154.130357616015, 9210.98679145307, 9221.677241296522, 9266.305027123128, 6818.556793808387, 6827.112475378169, 6899.241083480712, 6909.619970901648, 6968.3728464438445, 6801.9319806801295, 6804.081449004321, 6874.759383421556, 6908.48186386013, 6922.238553759184], "value": 6631.457128017679, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.45704376564163, 515.0214592274675, 519.4649511003666, 520.7760662914948, 526.8814516733736, 6305.873882522347, 6331.841703957403, 6385.553011289212, 6445.613263204199, 6469.9971504416835, 6195.907061394071, 6245.750169993206, 6250.562460627746, 6303.4011768882, 6318.304986451498, 5199.662021968579, 5238.397584277819, 5262.236975638531, 5263.526282634562, 5270.894582108374, 4849.806007759703, 5114.284000239951, 5124.871878203037, 5275.155975043994, 5293.602979776513, 4954.232094025414, 4970.353853998993, 4992.301077849104, 5000.1249781288125, 5013.222950442687, 4954.133026720309, 4985.227289770091, 5017.623678224148, 5044.596432285427, 5075.56857667095, 4479.4176757021705, 4515.661325400575, 4521.050579378334, 4532.79602417892, 4540.137373898949, 6228.28374736902, 6275.0, 6291.213598300213, 6301.464375528086, 6303.0, 5654.660720356792, 5691.231683722686, 5724.5992780505385, 5762.654240745569, 5777.682227477487, 6540.4767618590395, 6551.901721474182, 6554.475641948633, 6568.8357791055605, 6587.61660051385, 4896.975515122437, 4898.167311557044, 4902.877428064291, 4910.508949105097, 4910.631702622282, 6619.669016549154, 6679.532432729711, 6722.260551339349, 6800.353966373186, 6805.80898342788, 4952.4304704959395, 4980.726060066731, 4994.200927851526, 5027.974860125749, 5039.974800125976, 5082.881766011472, 5085.508101030468, 5087.821926232564, 5087.872803179876, 5116.976604679079, 5774.538036957033, 5780.479756821906, 5782.132680097935, 5796.94203057964, 5802.33273173582, 6557.0, 6577.6711164441585, 6655.833604159935, 6678.165229346332, 6692.86614267712, 1721.2942693495606, 1746.9563260918324, 1759.5777013516663, 1761.6036391811997, 1762.418401927361, 1177.581958404754, 1199.4782269712703, 1213.4660749270292, 1233.7046101593291, 1256.0830593666492, 5600.187972743929, 5674.0, 5699.145128230799, 5762.1849117527845, 5810.157527158538, 6759.831004224835, 6795.898061528982, 6839.897401538981, 6855.143107111611, 6889.827754306082, 5754.762726013843, 5768.596198266081, 5778.971105144447, 5802.127946666427, 5836.562257830679, 3568.678818906259, 3619.674229319321, 3620.3483372992587, 3635.0367152704553, 3650.653187947141, 6485.935140648628, 6533.509986751013, 6550.737970481223, 6567.868642627121, 6591.472682185414, 6888.104546408935, 6945.652717364112, 7025.894611580728, 7026.43788496919, 7053.576785392893, 6541.34586541342, 6574.868502630016, 6583.44040756541, 6634.170728658918, 6636.701348439332, 6636.203655561363, 6666.733330666719, 6774.966125169342, 6810.139741308762, 6866.498085976288, 6672.599644021374, 6702.352570734435, 6775.322467753185, 6792.388685018372, 6799.626020568916, 6401.903971440339, 6520.315366886598, 6539.803805885831, 6540.803775886731, 6624.436922861611, 4955.677880937727, 4970.975145124251, 4983.259207435824, 4990.176620857518, 5049.166887463527, 6454.257760357431, 6623.602583844985, 6668.699908504226, 6856.794296171123, 6871.415929646036, 6527.184101987252, 6586.407223349922, 6589.901151482636, 6592.868142637216, 6625.900611490928, 6502.902456463063, 6504.38208370204, 6577.539572229898, 6613.0, 6620.63586502747, 6116.633002019893, 6361.268454127835, 6427.710753016032, 6436.195475565554, 6441.001644745072, 6497.772577959749, 6505.902411463737, 6539.253513524426, 6593.80218593443, 6654.434373078343, 6295.622262664255, 6357.884636528161, 6425.164728585254, 6434.227892652912, 6444.484441244783], "value": 5095.26897595649, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [888.9111088891111, 938.8591711243308, 940.4874343482801, 946.6308139825464, 949.6391371278916, 18677.81322186778, 18860.434186974395, 19311.420657380284, 19324.90337548312, 19331.613367732636, 18711.3451029214, 18715.157817898194, 18729.0, 18830.34093806717, 18876.716849247274, 978.33473238198, 999.5801763259449, 1000.0049950299426, 1001.3991605036992, 1001.4642166440956, 949.1125797379473, 984.1093810101869, 995.3032876986102, 999.9950000249988, 1001.2590682894642, 1032.4424810602266, 1039.8232300508932, 1043.056034288966, 1043.0925095167215, 1044.0238377117419, 980.4411485453295, 1015.1929216273007, 1033.0341131042462, 1035.8135535603587, 1038.5897570459701, 749.568997826252, 750.8685979953541, 751.0761763031502, 751.0836779129507, 752.8833030880222, 18435.63128737431, 18439.90780046091, 18445.35441259563, 18978.52553686155, 19008.0, 21287.78712212889, 21735.782642173694, 21790.0, 21940.89029554842, 21956.23153189631, 16786.160691965473, 17084.31662733489, 17480.0, 17494.300227990865, 17512.21195046226, 3747.7189210809024, 3771.4719939208535, 3773.2830762155104, 3773.5094437723196, 3775.6790672792845, 140324.0, 140703.0, 141217.2939135308, 143140.2842985789, 146116.0, 16110.275037623334, 16430.589235269214, 16522.09128497944, 16631.0, 16643.750343744854, 25859.224223272955, 26545.46909061808, 28875.711242887723, 28907.56638650422, 28965.56551651727, 8199.139090395542, 8279.793005174919, 8280.337572994145, 8301.501909885306, 8303.377246706401, 3920.176762879827, 3954.228925359585, 3977.801109944549, 3977.940330895039, 3982.26328129297, 5284.630075894651, 5377.112776391929, 5381.4349493303425, 5395.541378982752, 5395.730213489388, 943.8112377524523, 961.8076384723083, 974.2060220919927, 982.091565302107, 989.7773001074701, 696.1854630082815, 710.9608971506514, 722.7831650504828, 736.138717700292, 742.6249743879306, 11795.941020294844, 12066.155369124253, 12098.455569499394, 12126.69683257908, 12810.295433751233, 649.8270621528093, 662.5428454366472, 674.8043067510367, 674.9426298764562, 683.9418649414855, 5282.128448805904, 5358.46415358461, 5368.0, 5394.244805727201, 5432.0, 123589.0, 125582.0, 126875.3656231713, 128092.0, 128861.0, 87196.56401717951, 106324.46837765761, 107989.46005269923, 110521.0, 112067.43966280279, 93710.0, 95799.0, 97068.51465742626, 98494.0, 98742.5062874681, 12606.180598261451, 12939.417726202155, 12978.221306721627, 13002.544910928073, 13101.082924195214, 42586.361204581335, 42762.358564622176, 42795.860206990146, 43605.345919811865, 44018.119637607706, 55459.722701386236, 55709.16436253378, 56840.0, 57234.0, 58102.70948645399, 17613.0, 17649.0, 17749.467515974542, 17872.195751190968, 17998.460046198634, 19797.01014949218, 20117.094730737444, 20129.496762581053, 20261.69607455919, 20268.0, 18248.817511824713, 18417.0, 18447.16987735582, 18502.44492665222, 18587.90706046461, 17385.0, 17455.738163927297, 17587.648247034733, 17703.468895933143, 17712.91143544274, 17895.284188632308, 17983.550411239823, 18126.818731812513, 18148.637027259116, 18149.183286751864, 13423.127496712683, 13684.93157534206, 13737.793933091212, 13888.305584720925, 13967.580972570839, 12484.313362765135, 12606.936965315115, 12612.0, 12921.418536166084, 12972.286524241257], "value": 9120.781476415435, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1427.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 107.0, 107.0, 106.0, 106.0, 103.0, 109.0, 105.0, 107.0, 107.0], "value": 106.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 120.0, 145.0, 113.0, 117.0, 118.0, 117.0, 116.0, 117.0], "value": 119.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 163.0, 163.0, 163.0, 163.0, 166.0, 164.0, 168.0, 164.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 73.0, 73.0, 73.0, 82.0, 74.0, 75.0, 76.0, 74.0], "value": 74.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 80.0, 79.0, 80.0, 80.0, 79.0, 79.0, 80.0, 79.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 114.0, 114.0, 114.0, 116.0, 114.0, 115.0, 116.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 148.0, 148.0, 148.0, 148.0, 148.0, 148.0, 149.0, 149.0, 148.0], "value": 149.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 99.0, 99.0, 99.0, 99.0, 94.0, 93.0, 93.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 52.0, 53.0, 53.0, 53.0, 51.0, 51.0, 51.0, 52.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 38.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 79.0, 74.0, 76.0, 77.0, 75.0, 75.0, 74.0, 76.0, 75.0], "value": 75.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 126.0, 119.0, 118.0, 115.0, 131.0, 120.0, 126.0, 121.0, 120.0], "value": 121.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 175.0, 166.0, 172.0, 169.0, 175.0, 176.0, 171.0, 170.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 63.0, 64.0, 68.0, 65.0, 65.0, 64.0, 63.0, 64.0], "value": 64.6, "unit": "score"}]}]} 03:35:58 INFO - Return code: 0 03:35:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:35:58 INFO - # TBPL SUCCESS # 03:35:58 INFO - Running post-action listener: _package_coverage_data 03:35:58 INFO - Running post-action listener: _resource_record_post_action 03:35:58 INFO - [mozharness: 2017-09-11 10:35:58.622044Z] Finished run-tests step (success) 03:35:58 INFO - Running post-run listener: _resource_record_post_run 03:35:58 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 40960; Write bytes: 299200512; Read time: 48; Write time: 169884 03:35:58 INFO - TinderboxPrint: CPU usage
13.1% 03:35:58 INFO - TinderboxPrint: I/O read bytes / time
40,960 / 48 03:35:58 INFO - TinderboxPrint: I/O write bytes / time
299,200,512 / 169,884 03:35:58 INFO - TinderboxPrint: CPU idle
8,238.9 (86.9%) 03:35:58 INFO - TinderboxPrint: CPU user
1,191.3 (12.6%) 03:35:58 INFO - TinderboxPrint: Swap in / out
0 / 0 03:35:58 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 12767232; Read time: 0; Write time: 1752 03:35:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:35:58 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 36864; Write bytes: 285818880; Read time: 40; Write time: 168132 03:35:59 INFO - Running post-run listener: _upload_blobber_files 03:35:59 INFO - Blob upload gear active. 03:35:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:35:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:35:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:35:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:35:59 INFO - (blobuploader) - INFO - Open directory for files ... 03:35:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:35:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:35:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:36:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:00 INFO - (blobuploader) - INFO - Done attempting. 03:36:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:36:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:36:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:01 INFO - (blobuploader) - INFO - Done attempting. 03:36:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:36:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:36:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:02 INFO - (blobuploader) - INFO - Done attempting. 03:36:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:36:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:36:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:09 INFO - (blobuploader) - INFO - Done attempting. 03:36:09 INFO - (blobuploader) - INFO - Iteration through files over. 03:36:09 INFO - Return code: 0 03:36:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:36:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:36:09 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f11e2e0c1db00143b5bea196ead9ea2193b57954617a0c778d12ce29a8faaa724891e3b2929fa5c6f3e022052c66d919733c5e3bc4923fd94314f5c4c4e8e4b1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e2532841311b176f2352dcdeeee443c76f0750dbb06d53cae3faef062d29a7953def204ad2a5916eee6e9c084c2af4089748727da558bdedb0a78e2d114d70f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f4c0c25e894a2008e2f1d2a90d39318ead22ea7895555a937621fa56a2f901a27d5a34c70aeb8307b156cad667e5c2d9e8ea3d31e9dd0ba5119f4aab2930f62", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0a92610585ad68a8075f42239065ed334646d8fa6362a4b431232c9d389e16f4ba99aeb10279d18b7ffe86532a94abb5fd4bbe46bbceed6a29d0ec0ab8c7fd26"} 03:36:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:36:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:36:09 INFO - Contents: 03:36:09 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f11e2e0c1db00143b5bea196ead9ea2193b57954617a0c778d12ce29a8faaa724891e3b2929fa5c6f3e022052c66d919733c5e3bc4923fd94314f5c4c4e8e4b1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e2532841311b176f2352dcdeeee443c76f0750dbb06d53cae3faef062d29a7953def204ad2a5916eee6e9c084c2af4089748727da558bdedb0a78e2d114d70f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f4c0c25e894a2008e2f1d2a90d39318ead22ea7895555a937621fa56a2f901a27d5a34c70aeb8307b156cad667e5c2d9e8ea3d31e9dd0ba5119f4aab2930f62", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0a92610585ad68a8075f42239065ed334646d8fa6362a4b431232c9d389e16f4ba99aeb10279d18b7ffe86532a94abb5fd4bbe46bbceed6a29d0ec0ab8c7fd26"} 03:36:09 INFO - Running post-run listener: copy_logs_to_upload_dir 03:36:09 INFO - Copying logs to upload dir... 03:36:09 INFO - mkdir: /builds/slave/test/build/upload/logs 03:36:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.470131 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-11 03:36:09.699937) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-11 03:36:09.704167) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738 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-Non-PGO/sha512/f11e2e0c1db00143b5bea196ead9ea2193b57954617a0c778d12ce29a8faaa724891e3b2929fa5c6f3e022052c66d919733c5e3bc4923fd94314f5c4c4e8e4b1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e2532841311b176f2352dcdeeee443c76f0750dbb06d53cae3faef062d29a7953def204ad2a5916eee6e9c084c2af4089748727da558bdedb0a78e2d114d70f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f4c0c25e894a2008e2f1d2a90d39318ead22ea7895555a937621fa56a2f901a27d5a34c70aeb8307b156cad667e5c2d9e8ea3d31e9dd0ba5119f4aab2930f62", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0a92610585ad68a8075f42239065ed334646d8fa6362a4b431232c9d389e16f4ba99aeb10279d18b7ffe86532a94abb5fd4bbe46bbceed6a29d0ec0ab8c7fd26"} build_url:https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011323 build_url: 'https://queue.taskcluster.net/v1/task/TV-2xJbFQNaqNCVPY33lrg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f11e2e0c1db00143b5bea196ead9ea2193b57954617a0c778d12ce29a8faaa724891e3b2929fa5c6f3e022052c66d919733c5e3bc4923fd94314f5c4c4e8e4b1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e2532841311b176f2352dcdeeee443c76f0750dbb06d53cae3faef062d29a7953def204ad2a5916eee6e9c084c2af4089748727da558bdedb0a78e2d114d70f", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f4c0c25e894a2008e2f1d2a90d39318ead22ea7895555a937621fa56a2f901a27d5a34c70aeb8307b156cad667e5c2d9e8ea3d31e9dd0ba5119f4aab2930f62", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0a92610585ad68a8075f42239065ed334646d8fa6362a4b431232c9d389e16f4ba99aeb10279d18b7ffe86532a94abb5fd4bbe46bbceed6a29d0ec0ab8c7fd26"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-11 03:36:09.739876) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:36:09.740523) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1505124926.420439-970449738 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004568 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-11 03:36:09.823027) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-11 03:36:09.823712) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-11 03:36:09.824310) ========= ========= Total master_lag: 0.20 =========