builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-069 starttime: 1506222717.23 results: success (0) revision: 42aa84eec339c29bf3d21178ae6e5c138d50e82b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 20:11:57.232238) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 20:11:57.234994) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 20:11:57.235300) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 20:11:57.460892) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:11:57.461553) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-23 20:11:57-- 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% 222M=0s 2017-09-23 20:11:57 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132133 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:11:57.641270) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:11:57.641583) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019422 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:11:57.739988) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-23 20:11:57.740313) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 42aa84eec339c29bf3d21178ae6e5c138d50e82b --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 42aa84eec339c29bf3d21178ae6e5c138d50e82b --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=05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-23 20:11:57,957 truncating revision to first 12 chars 2017-09-23 20:11:57,957 Setting DEBUG logging. 2017-09-23 20:11:57,957 attempt 1/10 2017-09-23 20:11:57,957 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/42aa84eec339?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-23 20:11:59,056 unpacking tar archive at: mozilla-central-42aa84eec339/testing/mozharness/ program finished with exit code 0 elapsedTime=1.404951 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-23 20:11:59.173207) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 20:11:59.173511) ========= script_repo_revision: 42aa84eec339c29bf3d21178ae6e5c138d50e82b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 20:11:59.173883) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 20:11:59.174219) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 20:11:59.187884) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-23 20:11:59.188160) ========= /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=05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:11:59 INFO - MultiFileLogger online at 20170923 20:11:59 in /builds/slave/test 20:11:59 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 20:11:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:11:59 INFO - {'append_to_log': False, 20:11:59 INFO - 'base_work_dir': '/builds/slave/test', 20:11:59 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 20:11:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:11:59 INFO - 'branch': 'Firefox-Non-PGO', 20:11:59 INFO - 'buildbot_json_path': 'buildprops.json', 20:11:59 INFO - 'code_coverage': False, 20:11:59 INFO - 'config_files': ('talos/linux_config.py',), 20:11:59 INFO - 'default_actions': ('clobber', 20:11:59 INFO - 'read-buildbot-config', 20:11:59 INFO - 'download-and-extract', 20:11:59 INFO - 'populate-webroot', 20:11:59 INFO - 'create-virtualenv', 20:11:59 INFO - 'install', 20:11:59 INFO - 'setup-mitmproxy', 20:11:59 INFO - 'run-tests'), 20:11:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:11:59 INFO - 'disable_ccov_upload': False, 20:11:59 INFO - 'disable_stylo': False, 20:11:59 INFO - 'download_minidump_stackwalk': True, 20:11:59 INFO - 'download_symbols': 'ondemand', 20:11:59 INFO - 'enable_stylo': False, 20:11:59 INFO - 'enable_webrender': False, 20:11:59 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 20:11:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:11:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:11:59 INFO - 'gecko_profile': False, 20:11:59 INFO - 'gecko_profile_interval': 0, 20:11:59 INFO - 'installer_path': 'installer.exe', 20:11:59 INFO - 'log_level': 'info', 20:11:59 INFO - 'log_name': 'talos', 20:11:59 INFO - 'log_to_console': True, 20:11:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:11:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:11:59 INFO - 'opt_config_files': (), 20:11:59 INFO - 'pip_index': False, 20:11:59 INFO - 'suite': 'dromaeojs-e10s', 20:11:59 INFO - 'system_bits': '32', 20:11:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:11:59 INFO - 'title': 'talos-linux64-ix-069', 20:11:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:11:59 INFO - 'use_talos_json': True, 20:11:59 INFO - 'verify': 'False', 20:11:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:11:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:11:59 INFO - 'work_dir': 'build'} 20:11:59 INFO - [mozharness: 2017-09-24 03:11:59.542636Z] Running clobber step. 20:11:59 INFO - Running pre-action listener: _resource_record_pre_action 20:11:59 INFO - Running main action method: clobber 20:11:59 INFO - rmtree: /builds/slave/test/build 20:11:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:12:00 INFO - Running post-action listener: _resource_record_post_action 20:12:00 INFO - [mozharness: 2017-09-24 03:12:00.541318Z] Finished clobber step (success) 20:12:00 INFO - [mozharness: 2017-09-24 03:12:00.541417Z] Running read-buildbot-config step. 20:12:00 INFO - Running pre-action listener: _resource_record_pre_action 20:12:00 INFO - Running main action method: read_buildbot_config 20:12:00 INFO - Using buildbot properties: 20:12:00 INFO - { 20:12:00 INFO - "project": "", 20:12:00 INFO - "product": "firefox", 20:12:00 INFO - "who": "philringnalda@gmail.com", 20:12:00 INFO - "installer_path": "public/build/target.tar.bz2", 20:12:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 20:12:00 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 20:12:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 20:12:00 INFO - "stage_platform": "linux64", 20:12:00 INFO - "basedir": "/builds/slave/test", 20:12:00 INFO - "buildnumber": 860, 20:12:00 INFO - "platform": "ubuntu64_hw", 20:12:00 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:12:00 INFO - "slavebuilddir": "test", 20:12:00 INFO - "taskId": "H1DEk7lwSZKAz25sOGgabQ", 20:12:00 INFO - "branch": "mozilla-central", 20:12:00 INFO - "script_repo_revision": "production", 20:12:00 INFO - "revision": "42aa84eec339c29bf3d21178ae6e5c138d50e82b", 20:12:00 INFO - "slavename": "talos-linux64-ix-069", 20:12:00 INFO - "repo_path": "mozilla-central" 20:12:00 INFO - } 20:12:00 INFO - Finding installer, test and symbols from parent task. 20:12:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H1DEk7lwSZKAz25sOGgabQ'}, attempt #1 20:12:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H1DEk7lwSZKAz25sOGgabQ'}, attempt #1 20:12:01 INFO - Task dependencies: JuOc-pfvTaqJ1Q-nyQhMGg 20:12:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg'}, attempt #1 20:12:01 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.tar.bz2 20:12:01 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.test_packages.json 20:12:01 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.crashreporter-symbols.zip 20:12:01 INFO - Running post-action listener: _resource_record_post_action 20:12:01 INFO - [mozharness: 2017-09-24 03:12:01.997576Z] Finished read-buildbot-config step (success) 20:12:01 INFO - [mozharness: 2017-09-24 03:12:01.997792Z] Running download-and-extract step. 20:12:01 INFO - Running pre-action listener: _resource_record_pre_action 20:12:01 INFO - Running main action method: download_and_extract 20:12:01 INFO - mkdir: /builds/slave/test/build/tests 20:12:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:12:01 INFO - trying https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.test_packages.json 20:12:01 INFO - Downloading https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:12:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:12:02 INFO - Downloaded 989 bytes. 20:12:02 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:12:02 INFO - Using the following test package requirements: 20:12:02 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 20:12:02 INFO - u'common': [u'target.common.tests.zip'], 20:12:02 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:12:02 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:12:02 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:12:02 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:12:02 INFO - u'mozbase': [u'target.common.tests.zip'], 20:12:02 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:12:02 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:12:02 INFO - u'web-platform': [u'target.common.tests.zip', 20:12:02 INFO - u'target.web-platform.tests.tar.gz'], 20:12:02 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:12:02 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 20:12:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.common.tests.zip 20:12:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:12:02 INFO - Fetch https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.common.tests.zip into memory 20:12:03 INFO - Content-Length response header: 54943858 20:12:03 INFO - Bytes received: 54943858 20:12:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.talos.tests.zip 20:12:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:12:14 INFO - Fetch https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.talos.tests.zip into memory 20:12:15 INFO - Content-Length response header: 14513823 20:12:15 INFO - Bytes received: 14513823 20:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:12:15 INFO - trying https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.tar.bz2 20:12:15 INFO - Downloading https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:12:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:12:19 INFO - Downloaded 61639506 bytes. 20:12:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.tar.bz2 20:12:19 INFO - mkdir: /builds/slave/test/properties 20:12:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:12:19 INFO - Writing to file /builds/slave/test/properties/build_url 20:12:19 INFO - Contents: 20:12:19 INFO - build_url:https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.tar.bz2 20:12:19 INFO - Running post-action listener: _resource_record_post_action 20:12:19 INFO - Running post-action listener: find_tests_for_verification 20:12:19 INFO - Running post-action listener: set_extra_try_arguments 20:12:19 INFO - [mozharness: 2017-09-24 03:12:19.148752Z] Finished download-and-extract step (success) 20:12:19 INFO - [mozharness: 2017-09-24 03:12:19.148848Z] Running populate-webroot step. 20:12:19 INFO - Running pre-action listener: _resource_record_pre_action 20:12:19 INFO - Running main action method: populate_webroot 20:12:19 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:12:19 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:12:19 INFO - 'tests': ['tresize', 20:12:19 INFO - 'tcanvasmark']}, 20:12:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:12:19 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:12:19 INFO - 'tests': ['dromaeo_css', 20:12:19 INFO - 'kraken']}, 20:12:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:12:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:12:19 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:12:19 INFO - 'talos_options': ['--disable-stylo'], 20:12:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:12:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:12:19 INFO - 'tests': ['damp', 'tps']}, 20:12:19 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:12:19 INFO - 'talos_options': ['--disable-stylo'], 20:12:19 INFO - 'tests': ['damp', 'tps']}, 20:12:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:12:19 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:12:19 INFO - 'tests': ['dromaeo_dom']}, 20:12:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 20:12:19 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:12:19 INFO - 'tests': ['basic_compositor_video', 20:12:19 INFO - 'glvideo']}, 20:12:19 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 20:12:19 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:12:19 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:12:19 INFO - 'talos_options': ['--disable-stylo'], 20:12:19 INFO - 'tests': ['ts_paint_webext', 20:12:19 INFO - 'tp5o_webext']}, 20:12:19 INFO - 'other-e10s': {'tests': ['a11yr', 20:12:19 INFO - 'ts_paint', 20:12:19 INFO - 'tpaint', 20:12:19 INFO - 'sessionrestore', 20:12:19 INFO - 'sessionrestore_many_windows', 20:12:19 INFO - 'sessionrestore_no_auto_restore', 20:12:19 INFO - 'tabpaint']}, 20:12:19 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:12:19 INFO - 'tests': ['a11yr', 20:12:19 INFO - 'ts_paint', 20:12:19 INFO - 'tpaint', 20:12:19 INFO - 'sessionrestore', 20:12:19 INFO - 'sessionrestore_many_windows', 20:12:19 INFO - 'sessionrestore_no_auto_restore', 20:12:19 INFO - 'tabpaint']}, 20:12:19 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 20:12:19 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 20:12:19 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:12:19 INFO - 'tests': ['perf_reftest_singletons']}, 20:12:19 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:12:19 INFO - 'tests': ['perf_reftest']}, 20:12:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:12:19 INFO - 'tsvgr_opacity', 20:12:19 INFO - 'tart', 20:12:19 INFO - 'tscrollx', 20:12:19 INFO - 'tsvg_static']}, 20:12:19 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:12:19 INFO - 'tests': ['tsvgx', 20:12:19 INFO - 'tsvgr_opacity', 20:12:19 INFO - 'tart', 20:12:19 INFO - 'tscrollx', 20:12:19 INFO - 'tsvg_static']}, 20:12:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:12:19 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:12:19 INFO - 'talos_options': ['--disable-stylo'], 20:12:19 INFO - 'tests': ['tp5o']}, 20:12:19 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:12:19 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 20:12:19 INFO - 'talos_options': ['--mitmproxy', 20:12:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:12:19 INFO - '--firstNonBlankPaint'], 20:12:19 INFO - 'tests': ['tp6_google', 20:12:19 INFO - 'tp6_youtube', 20:12:19 INFO - 'tp6_amazon', 20:12:19 INFO - 'tp6_facebook']}, 20:12:19 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:12:19 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 20:12:19 INFO - 'talos_options': ['--disable-stylo', 20:12:19 INFO - '--mitmproxy', 20:12:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:12:19 INFO - '--firstNonBlankPaint'], 20:12:19 INFO - 'tests': ['tp6_google', 20:12:19 INFO - 'tp6_youtube', 20:12:19 INFO - 'tp6_amazon', 20:12:19 INFO - 'tp6_facebook']}, 20:12:19 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:12:19 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 20:12:19 INFO - 'talos_options': ['--stylo-threads=1', 20:12:19 INFO - '--mitmproxy', 20:12:19 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:12:19 INFO - '--firstNonBlankPaint'], 20:12:19 INFO - 'tests': ['tp6_google', 20:12:19 INFO - 'tp6_youtube', 20:12:19 INFO - 'tp6_amazon', 20:12:19 INFO - 'tp6_facebook']}, 20:12:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:12:19 INFO - 'talos_options': ['--xperf_path', 20:12:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:12:19 INFO - 'tests': ['tp5n']}, 20:12:19 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:12:19 INFO - 'talos_options': ['--disable-stylo', 20:12:19 INFO - '--xperf_path', 20:12:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:12:19 INFO - 'tests': ['tp5n']}}} 20:12:19 INFO - Running post-action listener: _resource_record_post_action 20:12:19 INFO - [mozharness: 2017-09-24 03:12:19.164118Z] Finished populate-webroot step (success) 20:12:19 INFO - [mozharness: 2017-09-24 03:12:19.164207Z] Running create-virtualenv step. 20:12:19 INFO - Running pre-action listener: _resource_record_pre_action 20:12:19 INFO - Running main action method: create_virtualenv 20:12:19 INFO - Creating virtualenv /builds/slave/test/build/venv 20:12:19 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:12:19 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 20:12:19 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 20:12:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:12:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:12:19 INFO - Installing distribute.............................................................................................................................................................................................done. 20:12:20 INFO - Installing pip...............done. 20:12:20 INFO - Return code: 0 20:12:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:12:20 INFO - Reading from file tmpfile_stdout 20:12:20 INFO - Output received: 20:12:20 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 20:12:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:12:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8fd60>, '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 0xf90a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf90ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', '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 20:12:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:12:20 INFO - Using env: {'DISPLAY': ':0', 20:12:20 INFO - 'HOME': '/home/cltbld', 20:12:20 INFO - 'LANG': 'en_US.UTF-8', 20:12:20 INFO - 'LANGUAGE': 'en_US:en', 20:12:20 INFO - 'LOGNAME': 'cltbld', 20:12:20 INFO - 'MAIL': '/var/mail/cltbld', 20:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:12:20 INFO - 'MOZ_NO_REMOTE': '1', 20:12:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:12:20 INFO - 'NO_EM_RESTART': '1', 20:12:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:12:20 INFO - 'PWD': '/builds/slave/test', 20:12:20 INFO - 'SHELL': '/bin/bash', 20:12:20 INFO - 'SHLVL': '1', 20:12:20 INFO - 'TERM': 'linux', 20:12:20 INFO - 'TMOUT': '86400', 20:12:20 INFO - 'USER': 'cltbld', 20:12:20 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', 20:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:12:20 INFO - '_': '/tools/buildbot/bin/python'} 20:12:21 INFO - Ignoring indexes: http://pypi.python.org/simple/ 20:12:21 INFO - Downloading/unpacking pip>=1.5 20:12:21 INFO - Running setup.py egg_info for package pip 20:12:21 INFO - warning: no files found matching 'pip/cacert.pem' 20:12:21 INFO - warning: no files found matching '*.html' under directory 'docs' 20:12:21 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:12:21 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:12:21 INFO - Installing collected packages: pip 20:12:21 INFO - Found existing installation: pip 1.1 20:12:21 INFO - Uninstalling pip: 20:12:21 INFO - Successfully uninstalled pip 20:12:21 INFO - Running setup.py install for pip 20:12:21 INFO - warning: no files found matching 'pip/cacert.pem' 20:12:21 INFO - warning: no files found matching '*.html' under directory 'docs' 20:12:21 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:12:21 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:12:21 INFO - Installing pip script to /builds/slave/test/build/venv/bin 20:12:21 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 20:12:21 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 20:12:21 INFO - Successfully installed pip 20:12:21 INFO - Cleaning up... 20:12:21 INFO - Return code: 0 20:12:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:12:21 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')]} 20:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:12:21 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')]} 20:12:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:12:21 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 0xc251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8fd60>, '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 0xf90a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf90ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', '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 20:12:21 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 20:12:21 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 20:12:21 INFO - Using env: {'DISPLAY': ':0', 20:12:21 INFO - 'HOME': '/home/cltbld', 20:12:21 INFO - 'LANG': 'en_US.UTF-8', 20:12:21 INFO - 'LANGUAGE': 'en_US:en', 20:12:21 INFO - 'LOGNAME': 'cltbld', 20:12:21 INFO - 'MAIL': '/var/mail/cltbld', 20:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:12:21 INFO - 'MOZ_NO_REMOTE': '1', 20:12:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:12:21 INFO - 'NO_EM_RESTART': '1', 20:12:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:12:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:12:21 INFO - 'PWD': '/builds/slave/test', 20:12:21 INFO - 'SHELL': '/bin/bash', 20:12:21 INFO - 'SHLVL': '1', 20:12:21 INFO - 'TERM': 'linux', 20:12:21 INFO - 'TMOUT': '86400', 20:12:21 INFO - 'USER': 'cltbld', 20:12:21 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', 20:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:12:21 INFO - '_': '/tools/buildbot/bin/python'} 20:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:12:21 INFO - Downloading/unpacking psutil>=3.1.1 20:12:21 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 20:12:21 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 20:12:21 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 20:12:21 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 20:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:12:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:12:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:12:22 INFO - Installing collected packages: psutil 20:12:22 INFO - Running setup.py install for psutil 20:12:22 INFO - building 'psutil._psutil_linux' extension 20:12:22 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 20:12:23 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 20:12:23 INFO - building 'psutil._psutil_posix' extension 20:12:23 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 20:12:23 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 20:12:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:12:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:12:23 INFO - Successfully installed psutil 20:12:23 INFO - Cleaning up... 20:12:23 INFO - Return code: 0 20:12:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:12:23 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')]} 20:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:12:23 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')]} 20:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:12:23 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 0xc251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8fd60>, '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 0xf90a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf90ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', '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 20:12:23 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 20:12:23 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 20:12:23 INFO - Using env: {'DISPLAY': ':0', 20:12:23 INFO - 'HOME': '/home/cltbld', 20:12:23 INFO - 'LANG': 'en_US.UTF-8', 20:12:23 INFO - 'LANGUAGE': 'en_US:en', 20:12:23 INFO - 'LOGNAME': 'cltbld', 20:12:23 INFO - 'MAIL': '/var/mail/cltbld', 20:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:12:23 INFO - 'MOZ_NO_REMOTE': '1', 20:12:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:12:23 INFO - 'NO_EM_RESTART': '1', 20:12:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:12:23 INFO - 'PWD': '/builds/slave/test', 20:12:23 INFO - 'SHELL': '/bin/bash', 20:12:23 INFO - 'SHLVL': '1', 20:12:23 INFO - 'TERM': 'linux', 20:12:23 INFO - 'TMOUT': '86400', 20:12:23 INFO - 'USER': 'cltbld', 20:12:23 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', 20:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:12:23 INFO - '_': '/tools/buildbot/bin/python'} 20:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:12:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:12:23 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 20:12:23 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 20:12:23 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 20:12:23 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 20:12:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:12:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:12:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:12:24 INFO - Installing collected packages: mozsystemmonitor 20:12:24 INFO - Running setup.py install for mozsystemmonitor 20:12:24 INFO - Successfully installed mozsystemmonitor 20:12:24 INFO - Cleaning up... 20:12:25 INFO - Return code: 0 20:12:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:12: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')]} 20:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:12: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')]} 20:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:12:25 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 0xc251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8fd60>, '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 0xf90a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf90ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', '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 20:12:25 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 20:12:25 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 20:12:25 INFO - Using env: {'DISPLAY': ':0', 20:12:25 INFO - 'HOME': '/home/cltbld', 20:12:25 INFO - 'LANG': 'en_US.UTF-8', 20:12:25 INFO - 'LANGUAGE': 'en_US:en', 20:12:25 INFO - 'LOGNAME': 'cltbld', 20:12:25 INFO - 'MAIL': '/var/mail/cltbld', 20:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:12:25 INFO - 'MOZ_NO_REMOTE': '1', 20:12:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:12:25 INFO - 'NO_EM_RESTART': '1', 20:12:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:12:25 INFO - 'PWD': '/builds/slave/test', 20:12:25 INFO - 'SHELL': '/bin/bash', 20:12:25 INFO - 'SHLVL': '1', 20:12:25 INFO - 'TERM': 'linux', 20:12:25 INFO - 'TMOUT': '86400', 20:12:25 INFO - 'USER': 'cltbld', 20:12:25 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', 20:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:12:25 INFO - '_': '/tools/buildbot/bin/python'} 20:12:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:12:26 INFO - Downloading/unpacking jsonschema==2.5.1 20:12:26 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 20:12:26 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 20:12:26 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 20:12:26 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 20:12:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:12:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:12:34 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 20:12:34 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 20:12:34 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 20:12:34 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 20:12:34 INFO - Downloading functools32-3.2.3-2.tar.gz 20:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:12:34 INFO - warning: no files found matching '*.txt' 20:12:34 INFO - no previously-included directories found matching 'build' 20:12:34 INFO - no previously-included directories found matching 'dist' 20:12:34 INFO - no previously-included directories found matching '.git*' 20:12:34 INFO - Installing collected packages: jsonschema, functools32 20:12:34 INFO - Running setup.py install for functools32 20:12:34 INFO - warning: no files found matching '*.txt' 20:12:34 INFO - no previously-included directories found matching 'build' 20:12:34 INFO - no previously-included directories found matching 'dist' 20:12:34 INFO - no previously-included directories found matching '.git*' 20:12:34 INFO - Successfully installed jsonschema functools32 20:12:34 INFO - Cleaning up... 20:12:34 INFO - Return code: 0 20:12:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:12:34 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 0xc251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8fd60>, '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 0xf90a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf90ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', '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 20:12:34 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 20:12:34 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 20:12:34 INFO - Using env: {'DISPLAY': ':0', 20:12:34 INFO - 'HOME': '/home/cltbld', 20:12:34 INFO - 'LANG': 'en_US.UTF-8', 20:12:34 INFO - 'LANGUAGE': 'en_US:en', 20:12:34 INFO - 'LOGNAME': 'cltbld', 20:12:34 INFO - 'MAIL': '/var/mail/cltbld', 20:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:12:34 INFO - 'MOZ_NO_REMOTE': '1', 20:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:12:34 INFO - 'NO_EM_RESTART': '1', 20:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:12:34 INFO - 'PWD': '/builds/slave/test', 20:12:34 INFO - 'SHELL': '/bin/bash', 20:12:34 INFO - 'SHLVL': '1', 20:12:34 INFO - 'TERM': 'linux', 20:12:34 INFO - 'TMOUT': '86400', 20:12:34 INFO - 'USER': 'cltbld', 20:12:34 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', 20:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:12:34 INFO - '_': '/tools/buildbot/bin/python'} 20:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:12:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:12:34 INFO - Cleaning up... 20:12:34 INFO - Return code: 0 20:12:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:12:34 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 0xc251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8fd60>, '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 0xf90a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf90ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', '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 20:12:34 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 20:12:34 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 20:12:34 INFO - Using env: {'DISPLAY': ':0', 20:12:34 INFO - 'HOME': '/home/cltbld', 20:12:34 INFO - 'LANG': 'en_US.UTF-8', 20:12:34 INFO - 'LANGUAGE': 'en_US:en', 20:12:34 INFO - 'LOGNAME': 'cltbld', 20:12:34 INFO - 'MAIL': '/var/mail/cltbld', 20:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:12:34 INFO - 'MOZ_NO_REMOTE': '1', 20:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:12:34 INFO - 'NO_EM_RESTART': '1', 20:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:12:34 INFO - 'PWD': '/builds/slave/test', 20:12:34 INFO - 'SHELL': '/bin/bash', 20:12:34 INFO - 'SHLVL': '1', 20:12:34 INFO - 'TERM': 'linux', 20:12:34 INFO - 'TMOUT': '86400', 20:12:34 INFO - 'USER': 'cltbld', 20:12:34 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', 20:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:12:34 INFO - '_': '/tools/buildbot/bin/python'} 20:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:12:35 INFO - Downloading/unpacking blobuploader==1.2.4 20:12:35 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 20:12:35 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 20:12:35 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 20:12:35 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 20:12:36 INFO - Downloading blobuploader-1.2.4.tar.gz 20:12:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:12:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:12:36 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 20:12:36 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 20:12:36 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 20:12:36 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 20:12:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:12:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:12:36 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 20:12:36 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 20:12:36 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 20:12:36 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 20:12:36 INFO - Downloading docopt-0.6.1.tar.gz 20:12:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:12:36 INFO - Installing collected packages: blobuploader, requests, docopt 20:12:36 INFO - Running setup.py install for blobuploader 20:12:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:12:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:12:37 INFO - Running setup.py install for requests 20:12:37 INFO - Running setup.py install for docopt 20:12:37 INFO - Successfully installed blobuploader requests docopt 20:12:37 INFO - Cleaning up... 20:12:37 INFO - Return code: 0 20:12:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:12:37 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')]} 20:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:12:37 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')]} 20:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:12:37 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 0xc251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8fd60>, '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 0xf90a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf90ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', '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 20:12:37 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 20:12:37 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 20:12:37 INFO - Using env: {'DISPLAY': ':0', 20:12:37 INFO - 'HOME': '/home/cltbld', 20:12:37 INFO - 'LANG': 'en_US.UTF-8', 20:12:37 INFO - 'LANGUAGE': 'en_US:en', 20:12:37 INFO - 'LOGNAME': 'cltbld', 20:12:37 INFO - 'MAIL': '/var/mail/cltbld', 20:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:12:37 INFO - 'MOZ_NO_REMOTE': '1', 20:12:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:12:37 INFO - 'NO_EM_RESTART': '1', 20:12:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:12:37 INFO - 'PWD': '/builds/slave/test', 20:12:37 INFO - 'SHELL': '/bin/bash', 20:12:37 INFO - 'SHLVL': '1', 20:12:37 INFO - 'TERM': 'linux', 20:12:37 INFO - 'TMOUT': '86400', 20:12:37 INFO - 'USER': 'cltbld', 20:12:37 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', 20:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:12:37 INFO - '_': '/tools/buildbot/bin/python'} 20:12:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:12:37 INFO - Running setup.py (path:/tmp/pip-aekvay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:12:37 INFO - Running setup.py (path:/tmp/pip-mQSdTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:12:37 INFO - Running setup.py (path:/tmp/pip-BNn6lW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:12:37 INFO - Running setup.py (path:/tmp/pip-jJZbZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:12:38 INFO - Running setup.py (path:/tmp/pip-mHM5JL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:12:38 INFO - Running setup.py (path:/tmp/pip-1sBfW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:12:38 INFO - Running setup.py (path:/tmp/pip-jtkB98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:12:38 INFO - Running setup.py (path:/tmp/pip-XrkdEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:12:38 INFO - Running setup.py (path:/tmp/pip-HUEsUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:12:38 INFO - Running setup.py (path:/tmp/pip-er15Bl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:12:38 INFO - Running setup.py (path:/tmp/pip-CiJWW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:12:38 INFO - Running setup.py (path:/tmp/pip-7GA46M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:12:38 INFO - Running setup.py (path:/tmp/pip-Z80QWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:12:38 INFO - Running setup.py (path:/tmp/pip-Yxybo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:12:38 INFO - Running setup.py (path:/tmp/pip-YZ52Kw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:12:38 INFO - Running setup.py (path:/tmp/pip-UuRNJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:12:38 INFO - Running setup.py (path:/tmp/pip-uonaJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:12:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:12:38 INFO - Running setup.py install for manifestparser 20:12:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:12:39 INFO - Running setup.py install for mozcrash 20:12:39 INFO - Running setup.py install for mozdebug 20:12:39 INFO - Running setup.py install for mozdevice 20:12:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:12:39 INFO - Running setup.py install for mozfile 20:12:39 INFO - Running setup.py install for mozhttpd 20:12:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:12:39 INFO - Running setup.py install for mozinfo 20:12:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:12:39 INFO - Running setup.py install for mozInstall 20:12:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:12:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:12:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:12:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:12:39 INFO - Running setup.py install for mozleak 20:12:39 INFO - Running setup.py install for mozlog 20:12:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:12:39 INFO - Running setup.py install for moznetwork 20:12:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:12:40 INFO - Running setup.py install for mozprocess 20:12:40 INFO - Running setup.py install for mozprofile 20:12:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:12:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:12:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:12:40 INFO - Running setup.py install for mozrunner 20:12:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:12:40 INFO - Running setup.py install for mozscreenshot 20:12:40 INFO - Running setup.py install for moztest 20:12:40 INFO - Running setup.py install for mozversion 20:12:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:12:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:12:40 INFO - Cleaning up... 20:12:40 INFO - Return code: 0 20:12:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:12:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:12:40 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 0xc251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8fd60>, '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 0xf90a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf90ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', '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 20:12:40 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 20:12:40 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 20:12:40 INFO - Using env: {'DISPLAY': ':0', 20:12:40 INFO - 'HOME': '/home/cltbld', 20:12:40 INFO - 'LANG': 'en_US.UTF-8', 20:12:40 INFO - 'LANGUAGE': 'en_US:en', 20:12:40 INFO - 'LOGNAME': 'cltbld', 20:12:40 INFO - 'MAIL': '/var/mail/cltbld', 20:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:12:40 INFO - 'MOZ_NO_REMOTE': '1', 20:12:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:12:40 INFO - 'NO_EM_RESTART': '1', 20:12:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:12:40 INFO - 'PWD': '/builds/slave/test', 20:12:40 INFO - 'SHELL': '/bin/bash', 20:12:40 INFO - 'SHLVL': '1', 20:12:40 INFO - 'TERM': 'linux', 20:12:40 INFO - 'TMOUT': '86400', 20:12:40 INFO - 'USER': 'cltbld', 20:12:40 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', 20:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:12:40 INFO - '_': '/tools/buildbot/bin/python'} 20:12:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:12:40 INFO - Running setup.py (path:/tmp/pip-7MM03j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:12:40 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)) 20:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:12:40 INFO - Running setup.py (path:/tmp/pip-UZVvfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:12:41 INFO - Running setup.py (path:/tmp/pip-zVbDk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:12:41 INFO - Running setup.py (path:/tmp/pip-eUDTrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:12:41 INFO - Running setup.py (path:/tmp/pip-kXkS4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:12:41 INFO - Running setup.py (path:/tmp/pip-3AODiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:12:41 INFO - Running setup.py (path:/tmp/pip-qdhiCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:12:41 INFO - Running setup.py (path:/tmp/pip-gefLca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:12:41 INFO - Running setup.py (path:/tmp/pip-4lx3SH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:12:41 INFO - Running setup.py (path:/tmp/pip-lmYG2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:12:41 INFO - Running setup.py (path:/tmp/pip-VpKRdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:12:41 INFO - Running setup.py (path:/tmp/pip-vI730Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:12:41 INFO - Running setup.py (path:/tmp/pip-oehTMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:12:41 INFO - Running setup.py (path:/tmp/pip-t3QXfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:12:41 INFO - Running setup.py (path:/tmp/pip-4qBsHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:12:41 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)) 20:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:12:41 INFO - Running setup.py (path:/tmp/pip-r_gGdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:12:42 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)) 20:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:12:42 INFO - Running setup.py (path:/tmp/pip-iGeC7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:12:42 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)) 20:12:42 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)) 20:12:42 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)) 20:12:42 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)) 20:12:42 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)) 20:12:42 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)) 20:12:42 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)) 20:12:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:12:42 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 20:12:42 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 20:12:42 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 20:12:42 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 20:12:43 INFO - Downloading blessings-1.6.tar.gz 20:12:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:12:43 INFO - Installing collected packages: blessings 20:12:43 INFO - Running setup.py install for blessings 20:12:43 INFO - Successfully installed blessings 20:12:43 INFO - Cleaning up... 20:12:43 INFO - Return code: 0 20:12:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:12:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:12:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:12:43 INFO - Reading from file tmpfile_stdout 20:12:43 INFO - Current package versions: 20:12:43 INFO - argparse == 1.2.1 20:12:43 INFO - blessings == 1.6 20:12:43 INFO - blobuploader == 1.2.4 20:12:43 INFO - docopt == 0.6.1 20:12:43 INFO - functools32 == 3.2.3-2 20:12:43 INFO - jsonschema == 2.5.1 20:12:43 INFO - manifestparser == 1.1 20:12:43 INFO - mozInstall == 1.13 20:12:43 INFO - mozcrash == 1.0 20:12:43 INFO - mozdebug == 0.1 20:12:43 INFO - mozdevice == 0.51 20:12:43 INFO - mozfile == 1.2 20:12:43 INFO - mozhttpd == 0.7 20:12:43 INFO - mozinfo == 0.10 20:12:43 INFO - mozleak == 0.1 20:12:43 INFO - mozlog == 3.5 20:12:43 INFO - moznetwork == 0.27 20:12:43 INFO - mozprocess == 0.25 20:12:43 INFO - mozprofile == 0.28 20:12:43 INFO - mozrunner == 6.13 20:12:43 INFO - mozscreenshot == 0.1 20:12:43 INFO - mozsystemmonitor == 0.3 20:12:43 INFO - moztest == 0.8 20:12:43 INFO - mozversion == 1.4 20:12:43 INFO - psutil == 3.1.1 20:12:43 INFO - requests == 1.2.3 20:12:43 INFO - wsgiref == 0.1.2 20:12:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:12:43 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')]} 20:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:12:43 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')]} 20:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:12:43 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 0xc251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8fd60>, '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 0xf90a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf90ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', '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 20:12:43 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 20:12:43 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 20:12:43 INFO - Using env: {'DISPLAY': ':0', 20:12:43 INFO - 'HOME': '/home/cltbld', 20:12:43 INFO - 'LANG': 'en_US.UTF-8', 20:12:43 INFO - 'LANGUAGE': 'en_US:en', 20:12:43 INFO - 'LOGNAME': 'cltbld', 20:12:43 INFO - 'MAIL': '/var/mail/cltbld', 20:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:12:43 INFO - 'MOZ_NO_REMOTE': '1', 20:12:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:12:43 INFO - 'NO_EM_RESTART': '1', 20:12:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:12:43 INFO - 'PWD': '/builds/slave/test', 20:12:43 INFO - 'SHELL': '/bin/bash', 20:12:43 INFO - 'SHLVL': '1', 20:12:43 INFO - 'TERM': 'linux', 20:12:43 INFO - 'TMOUT': '86400', 20:12:43 INFO - 'USER': 'cltbld', 20:12:43 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', 20:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:12:43 INFO - '_': '/tools/buildbot/bin/python'} 20:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:12:43 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)) 20:12:43 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)) 20:12:43 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)) 20:12:43 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)) 20:12:43 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)) 20:12:43 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)) 20:12:43 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)) 20:12:43 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)) 20:12:43 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)) 20:12:43 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:12:43 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 20:12:43 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 20:12:43 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 20:12:43 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 20:12:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 20:12:45 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)) 20:12:45 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)) 20:12:45 INFO - Installing collected packages: simplejson 20:12:45 INFO - Running setup.py install for simplejson 20:12:45 INFO - building 'simplejson._speedups' extension 20:12:45 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 20:12:46 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 20:12:46 INFO - Successfully installed simplejson 20:12:46 INFO - Cleaning up... 20:12:46 INFO - Return code: 0 20:12:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:12: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')]} 20:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:12: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')]} 20:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:12:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc251f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdedb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf929c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf8fd60>, '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 0xf90a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf90ef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', '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 20:12:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:12:46 INFO - Using env: {'DISPLAY': ':0', 20:12:46 INFO - 'HOME': '/home/cltbld', 20:12:46 INFO - 'LANG': 'en_US.UTF-8', 20:12:46 INFO - 'LANGUAGE': 'en_US:en', 20:12:46 INFO - 'LOGNAME': 'cltbld', 20:12:46 INFO - 'MAIL': '/var/mail/cltbld', 20:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:12:46 INFO - 'MOZ_NO_REMOTE': '1', 20:12:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:12:46 INFO - 'NO_EM_RESTART': '1', 20:12:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:12:46 INFO - 'PWD': '/builds/slave/test', 20:12:46 INFO - 'SHELL': '/bin/bash', 20:12:46 INFO - 'SHLVL': '1', 20:12:46 INFO - 'TERM': 'linux', 20:12:46 INFO - 'TMOUT': '86400', 20:12:46 INFO - 'USER': 'cltbld', 20:12:46 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', 20:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:12:46 INFO - '_': '/tools/buildbot/bin/python'} 20:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:12:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:12:46 INFO - Cleaning up... 20:12:46 INFO - Return code: 0 20:12:46 INFO - Running post-action listener: _resource_record_post_action 20:12:46 INFO - Running post-action listener: _start_resource_monitoring 20:12:46 INFO - Starting resource monitoring. 20:12:46 INFO - [mozharness: 2017-09-24 03:12:46.402974Z] Finished create-virtualenv step (success) 20:12:46 INFO - [mozharness: 2017-09-24 03:12:46.403504Z] Running install step. 20:12:46 INFO - Running pre-action listener: _resource_record_pre_action 20:12:46 INFO - Running main action method: install 20:12:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:12:46 INFO - Reading from file tmpfile_stdout 20:12:46 INFO - Detecting whether we're running mozinstall >=1.0... 20:12:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:12:46 INFO - Reading from file tmpfile_stdout 20:12:46 INFO - Output received: 20:12:46 INFO - Usage: mozinstall [options] installer 20:12:46 INFO - Options: 20:12:46 INFO - -h, --help show this help message and exit 20:12:46 INFO - -d DEST, --destination=DEST 20:12:46 INFO - Directory to install application into. [default: 20:12:46 INFO - "/builds/slave/test"] 20:12:46 INFO - --app=APP Application being installed. [default: firefox] 20:12:46 INFO - mkdir: /builds/slave/test/build/application 20:12:46 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'] 20:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:13:04 INFO - Reading from file tmpfile_stdout 20:13:04 INFO - Output received: 20:13:04 INFO - /builds/slave/test/build/application/firefox/firefox 20:13:04 INFO - Running post-action listener: _resource_record_post_action 20:13:04 INFO - [mozharness: 2017-09-24 03:13:04.599936Z] Finished install step (success) 20:13:04 INFO - [mozharness: 2017-09-24 03:13:04.600189Z] Running setup-mitmproxy step. 20:13:04 INFO - Running pre-action listener: _resource_record_pre_action 20:13:04 INFO - Running main action method: setup_mitmproxy 20:13:04 INFO - Skipping: mitmproxy is not required 20:13:04 INFO - Running post-action listener: _resource_record_post_action 20:13:04 INFO - [mozharness: 2017-09-24 03:13:04.601389Z] Finished setup-mitmproxy step (success) 20:13:04 INFO - [mozharness: 2017-09-24 03:13:04.601607Z] Running run-tests step. 20:13:04 INFO - Running pre-action listener: _resource_record_pre_action 20:13:04 INFO - Running pre-action listener: _set_gcov_prefix 20:13:04 INFO - Running main action method: run_tests 20:13:04 WARNING - Try message not found. 20:13:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:13:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:13:04 INFO - Python 2.7.3 20:13:04 INFO - Return code: 0 20:13:04 INFO - grabbing minidump binary from tooltool 20:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:13:04 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 0xf8fd60>, '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 0xf90a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf90ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:13:04 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 20:13:04 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 20:13:04 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 20:13:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:13:04 INFO - Return code: 0 20:13:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:13:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:13:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:13:04 INFO - ENV: RUST_BACKTRACE is now full 20:13:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:13:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:13:04 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/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 20:13:04 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/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 20:13:04 INFO - Using env: {'DISPLAY': ':0', 20:13:04 INFO - 'HOME': '/home/cltbld', 20:13:04 INFO - 'LANG': 'en_US.UTF-8', 20:13:04 INFO - 'LANGUAGE': 'en_US:en', 20:13:04 INFO - 'LOGNAME': 'cltbld', 20:13:04 INFO - 'MAIL': '/var/mail/cltbld', 20:13:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:13:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:13:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:13:04 INFO - 'MOZ_NO_REMOTE': '1', 20:13:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:13:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:13:04 INFO - 'NO_EM_RESTART': '1', 20:13:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:13:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:13:04 INFO - 'PWD': '/builds/slave/test', 20:13:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:13:04 INFO - 'RUST_BACKTRACE': 'full', 20:13:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:13:04 INFO - 'SHELL': '/bin/bash', 20:13:04 INFO - 'SHLVL': '1', 20:13:04 INFO - 'TERM': 'linux', 20:13:04 INFO - 'TMOUT': '86400', 20:13:04 INFO - 'USER': 'cltbld', 20:13:04 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', 20:13:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:13:04 INFO - '_': '/tools/buildbot/bin/python'} 20:13:04 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/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 20:13:04 INFO - mozversion application_buildid: 20170924021622 20:13:04 INFO - mozversion application_changeset: 42aa84eec339c29bf3d21178ae6e5c138d50e82b 20:13:04 INFO - mozversion application_display_name: Nightly 20:13:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:13:04 INFO - mozversion application_name: Firefox 20:13:04 INFO - mozversion application_remotingname: firefox 20:13:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:13:04 INFO - mozversion application_vendor: Mozilla 20:13:04 INFO - mozversion application_version: 58.0a1 20:13:04 INFO - mozversion platform_buildid: 20170924021622 20:13:04 INFO - mozversion platform_changeset: 42aa84eec339c29bf3d21178ae6e5c138d50e82b 20:13:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:13:04 INFO - mozversion platform_version: 58.0a1 20:13:04 INFO - using testdate: 1506222784 20:13:04 INFO - actual date: 1506222784 20:13:04 INFO - starting webserver on 'localhost:54273' 20:13:04 INFO - SUITE-START | Running 2 tests 20:13:04 INFO - TEST-START | dromaeo_css 20:13:04 INFO - Initialising browser for dromaeo_css test... 20:13:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54273/getInfo.html -profile /tmp/tmpZNvBXi/profile 20:13:04 INFO - TEST-INFO | started process 4057 (/builds/slave/test/build/application/firefox/firefox http://localhost:54273/getInfo.html) 20:13:10 INFO - TEST-INFO | 4057: exit 0 20:13:10 INFO - Browser initialized. 20:13:10 INFO - Running cycle 1/1 for dromaeo_css test... 20:13:10 INFO - Using env: {'DISPLAY': ':0', 20:13:10 INFO - 'HOME': '/home/cltbld', 20:13:10 INFO - 'JSGC_DISABLE_POISONING': '1', 20:13:10 INFO - 'LANG': 'en_US.UTF-8', 20:13:10 INFO - 'LANGUAGE': 'en_US:en', 20:13:10 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 20:13:10 INFO - 'LOGNAME': 'cltbld', 20:13:10 INFO - 'MAIL': '/var/mail/cltbld', 20:13:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:13:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:13:10 INFO - 'MOZ_CRASHREPORTER': '1', 20:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:13:10 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 20:13:10 INFO - 'MOZ_NO_REMOTE': '1', 20:13:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:13:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:13:10 INFO - 'NO_EM_RESTART': '1', 20:13:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:13:10 INFO - 'PWD': '/builds/slave/test', 20:13:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:13:10 INFO - 'RUST_BACKTRACE': 'full', 20:13:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:13:10 INFO - 'SHELL': '/bin/bash', 20:13:10 INFO - 'SHLVL': '1', 20:13:10 INFO - 'TERM': 'linux', 20:13:10 INFO - 'TMOUT': '86400', 20:13:10 INFO - 'USER': 'cltbld', 20:13:10 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', 20:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:13:10 INFO - '_': '/tools/buildbot/bin/python'} 20:13:10 INFO - TEST-INFO | started process 4294 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZNvBXi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 20:13:11 INFO - PID 4294 | 20:13:11 INFO - PID 4294 | (/builds/slave/test/build/application/firefox/firefox:4343): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 20:13:11 INFO - PID 4294 | 20:13:21 INFO - PID 4294 | RSS: Main: 151379968 20:13:21 INFO - PID 4294 | 20:16:19 INFO - PID 4294 | Cycle 1(1): loaded http://localhost:54273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54273/tests/dromaeo/cssquery-ext.html) 20:16:20 INFO - PID 4294 | RSS: Main: 152809472 20:16:20 INFO - PID 4294 | 20:19:21 INFO - PID 4294 | Cycle 1(1): loaded http://localhost:54273/tests/dromaeo/cssquery-ext.html (next: http://localhost:54273/tests/dromaeo/cssquery-jquery.html) 20:19:22 INFO - PID 4294 | RSS: Main: 152748032 20:19:22 INFO - PID 4294 | 20:22:24 INFO - PID 4294 | Cycle 1(1): loaded http://localhost:54273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54273/tests/dromaeo/cssquery-mootools.html) 20:22:24 INFO - PID 4294 | RSS: Main: 154525696 20:22:24 INFO - PID 4294 | 20:25:26 INFO - PID 4294 | Cycle 1(1): loaded http://localhost:54273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54273/tests/dromaeo/cssquery-prototype.html) 20:25:27 INFO - PID 4294 | RSS: Main: 153219072 20:25:27 INFO - PID 4294 | 20:28:30 INFO - PID 4294 | Cycle 1(1): loaded http://localhost:54273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54273/tests/dromaeo/cssquery-yui.html) 20:28:31 INFO - PID 4294 | RSS: Main: 154750976 20:28:31 INFO - PID 4294 | 20:31:32 INFO - PID 4294 | Cycle 1(1): loaded http://localhost:54273/tests/dromaeo/cssquery-yui.html (next: http://localhost:54273/tests/dromaeo/cssquery-dojo.html) 20:31:33 INFO - PID 4294 | RSS: Main: 156966912 20:31:33 INFO - PID 4294 | 20:31:33 INFO - PID 4294 | __start_tp_report 20:31:33 INFO - PID 4294 | _x_x_mozilla_page_load 20:31:33 INFO - PID 4294 | _x_x_mozilla_page_load_details 20:31:33 INFO - PID 4294 | |i|pagename|runs| 20:31:33 INFO - PID 4294 | |0;dojo.html;3121.079281611925;3130.1861516005833;3145.905622831315;3146.5280207968817;3155.053483954813;6245.094461303108;6366.936330636692;6375.649339286343;6413.583117097385;6494.610323380601;6234.625922444657;6299.055141728743;6300.117983482305;6333.841653958653;6353.8411539711415;5813.30240371156;5851.502622277111;5868.853278668024;5909.379515150912;5929.140274660172;5590.329160500745;5866.73599688015;5892.2929248490245;5984.102384642317;6018.699065046752;5721.828345149652;5825.388334224887;5852.356240813507;5878.617889837147;5902.911456328158;5813.563982701272;5827.650340979555;5881.2354393928945;5892.410758924116;5898.557608179361;5803.448672376117;5815.767369305223;5859.648421094748;5891.499222566087;5903.173555702205;6237.157983672191;6248.187735594389;6313.526485513559;6362;6470.029495575702;6767.830804229884;6767.966160169168;6776.69504872277;6778.966105169443;6781.084553585301;6727.495437842182;6815.079964204819;6839.179298484164;6841.657917104125;6946.340097690711;6220.751169953196;6297.118403423525;6350.428461438447;6350.491960643184;6448.387403196736;7011.579305241702;7045.577265364094;7090.184628767654;7090.503664743522;7091.503594748421;6687.799366019027;6692.564983276072;6708.19501659804;6739.359760822714;6773.1872175338285;6672.666366681647;6769.39075483199;6774.254831968479;6781.830454238585;6853.451723862178;6805.9659701701175;6810.421114205348;6817.795466136024;6830.965845170742;6833.624150671761;6758.966205168943;6864.450843932474;6872.828179295458;6895.793126206222;6896.620685862325;5331.733413329318;5363.061464243741;5415.6479828811;5417.756200970965;5418.810341638024;4592.2193227151465;4625.4911959684405;4670.719756814602;4678.532146785362;4685.835995740201;5642.520385767174;5729.624890026364;5745.396733343023;5748.48263656273;5756.597038207369;6796.25241223465;6809.285025072395;6887.5178737487895;6922.930770692228;6931.549449285781;5849.298084229835;5863.941360586425;5918.733656985446;5938;5942.554308426886;5710.086386178275;5872.442117998783;5932.911006334908;5938.673372964528;5939.881202375928;6529.706163222571;6608.537402381787;6678.899816502756;6679.031540426611;6680.699368528428;6906.723731050803;6944.791656250321;6956.339147780953;6961.095057642474;6968.825779355557;6599.769008084694;6619.702113405005;6622.966885165543;6707.161604799205;6710.932890671031;6609.504287178577;6743.29195434492;6766.4248538874745;6833.69248383814;6862.622555759481;6734.528582999143;6795.694193761392;6810.489213309121;6835.179778426621;6927.7228910845015;6615.702293396905;6628.635425051669;6646;6696.59820410777;6721.327867213239;6620.63586502747;6634.601923884582;6701.262861085374;6704.966475167593;6717.529772916045;6412.743490260337;6475.222973243241;6563.474922006132;6663.900041499478;6672.766453174116;6648.268690444144;6700.329967003261;6705.463562914858;6737.86524269502;6780.118584583973;6227.813165605039;6388.552801303864;6390.041493775971;6422.839429014312;6456.774012909714 20:31:33 INFO - PID 4294 | |1;ext.html;852.1861622150847;859.2996707683242;878.683673877404;892.977675558111;899.815537814748;40686.389704154426;41178.17643647125;41364.96587585308;41391.379129313085;41563.376549351786;41095.38356924641;41468.792656036676;41509.58490415095;41629.375559366556;41636.79181604095;7970.92029079709;8102.635381407821;8125.146859579748;8128.878066829002;8149.103598604149;8044.597770111501;8173.795655108611;8178.100408955011;8178.100408955011;8179.141190175035;8191.836163276731;8212.712555060574;8216.753497395088;8217.137200593941;8223.465474744122;8158.959205203966;8186.222308880647;8193.918060819375;8196.467229630054;8200.507969521848;8017.118117007183;8080.797980050487;8085.231902969209;8087.595620218965;8090.595470226524;38377.808110959544;38688.613113868785;38702.419463708065;38817;39195.412068818994;3558.217192217734;3587.910302242439;3589.300086483137;3589.820508974567;3591.5690117185845;31230.21924451884;31299.217519561964;31312.53031204534;31614;31690.524642130385;5055.595552355802;5198.16829307309;5288.338957630296;5310.283111779899;5325.8934821303355;182709;183705;183885;184354;184746;46851.76574117176;47060;47081.76459117751;47089.764551177024;47211.527884720716;48942.75528622334;48972.755136224805;49008;49018.26472602914;49208.75395623071;15564.688706225812;15719.292631831595;15734.291956861969;15771.44799931997;15951.361945522282;12828.230306181658;12962.80555791664;12984.285864277366;13053.216806991612;13064;12329.691757706129;12514.874851251552;12692.111552191443;12708.3010434427;12816.359182041047;4968.627352948543;5028.719844163614;5096.541311282002;5108.386993560796;5157.767900444489;1005.3967619428284;1012.8632634594383;1013.9898601014042;1014.8731408573929;1027.799579082072;22708.65937010946;22811.429714256945;23023.19418820333;23100.884495577415;23158.073677053068;1103.85098011769;1110.7667389848061;1111.2721167635211;1117.0616681987008;1119.3172164979355;12265.141440099285;12281.815772763415;12421.875781242252;12455.688607784878;12484.063695222894;207389;209256;209720;209728;210056;40331.59668403278;41013;49049.264261036114;49201.75399123053;51626;1458.2854401343477;1464.5972357601745;1465.1209274435466;1466.9853301467062;1469.5297504798575;27624.723752762216;27746.306342341602;27757;28116.71883281141;28266.85866570654;37390.8130459346;37426.8128659355;37440.6255937448;37481.81259093687;37488.2502349946;188677;190109;190279;191149.04425477784;191508;33281;34230.14424639404;34450.55494450535;34920.47619285662;34938;41815.37276940909;42518;42525.57474425216;42567.57432425636;42810.571894280656;32206.35587288288;32221;32354.191145221557;32526.34947301088;33102.50346244856;31698.841505792323;31752.64704858464;31841.840790795897;32206.67793322037;32476.188095297806;36621.26757464783;36756.81621591875;36841.44737828881;36928.63071369252;37006;31583.842080789447;31709.5243571342;31835;31840.68159318377;31869.04392868218;23162.652560211947;23485.412864677837;23534.176303829285;23787.643185351888;23872.52254954926 20:31:33 INFO - PID 4294 | |2;jquery.html;588.9028310328796;591.5740666719961;600.2257088356021;602.3374288282888;605.1527860994607;21858.23496177634;22688.43278918024;22710.205142819985;22753.658695119546;22760.43098922528;23541.646875296883;24146;25397;25638.74361256387;25906.48187036263;6603.93396066038;6800.965995170017;6814.863702725943;6820.079289295931;6838.589684618914;6454.0641606966965;6674.866502669944;6759.459243260552;6777.76277830276;6798.422134118606;6696.263411024807;6715.496337774662;6718.49611279156;6719.966400167992;6724.865502689968;6365.8408539786415;6647.202335719696;6671.166104236971;6681.66591670417;6714.127163468742;6127.816165515042;6161.722722477454;6173.382661733791;6176.197094377702;6176.567640265183;29684.109476715734;30106;30174.54738178907;30190.396192076256;30219.546706799196;2047.600717860003;2076.169532187137;2080.1991233375106;2081.8334533237303;2084.0205103601284;19526.609467810707;19881.403557893285;20196.49508762278;20364.79635203644;20399.49001274965;2334.5330933813307;2386.22447704495;2444.9877750611313;2446.963295550568;2449.30194894456;124159;130322;133133;138467;138795.99999999898;15003.696968485956;15155.242237888062;15477.380904763684;15540.922295388677;15967.36130554788;24063.27810165698;24112.63831042536;24131.276061718178;24140.75859241385;24143.63784543233;9144.54277286133;9152.90847091534;9273.489958052372;9353.438793672402;9373.765655858522;8316.750497484963;8327;8343.123971982975;8424.199701028392;8439.113893041265;9105.81788364229;9167.95416022929;9213.493257870881;9347.672831450867;9539.379940303857;2579.8194126411345;2582.496413199408;2622.960655590168;2684.087410280514;2700.135956493903;942.2320808541017;956.8851737791509;963.2582911158369;982.6511588386163;986.7039888033561;19326.130324135167;19336.226550937805;19378.321758738377;19598.66087441713;19919;1025.2003437318904;1028.2339656955617;1030.9123874312672;1040.4485622620023;1050.1336397472116;10765.730856728644;10795.676129716121;10853.34879907208;11063.72340691489;11071.833922491169;445526;446524;447614;460568;466949;109348.4532577332;109674.45162774295;111979;112418;114222;1042.635077722791;1090.0734375780683;1099.5601759296346;1156.8900954409319;1204.975900482003;20589.89705051465;20652.483687907323;20725;20805.375838724256;20890.582188355846;55703.44296557145;55734.16398754103;55753.16370254531;56136.43863561475;56812.715936420056;67207;69178.96231556631;69395;69611;70006.64996675188;14668.119912805267;14810.777838332218;14828.555143345717;14903.62740931442;14915.254237288309;22663.88668056649;22743.09027638876;22803.77196228083;23069.88465057664;23086.076556937533;18194.090295484908;18212.817871821113;18384.264629415207;18560.886346818694;18598.907005464887;16049;16231.756523651919;16721.163941802617;17062.658746825244;17112.48662540126;18536.814631853507;18777.436676899713;18901.527461813566;19244;19391.2243510258;14531.854681453051;14574;14582.927085364505;14722.337494812973;14763.630909227355;17667.116644167996;19060.714089288947;19097.809021909605;19318.710219347002;19360.70958935589 20:31:33 INFO - PID 4294 | |3;mootools.html;1332.6268644779461;1386.6464051666826;1395.923224222668;1400.6218321053323;1402.3759427054958;15063.17152556609;15544.222788860568;15712.764308535352;15718.607034824136;15718.685626287483;15365.231738413091;15425.845741542582;15560.84439155608;15565.37738490459;15569.844301556981;2783.3737409082946;2894.233028247516;2900.9129726108144;2904.1868276882506;2906.8691908864153;2778.513760091986;2823.5764635304663;2824.449232399683;2834.7590454811366;2838.063439065114;2790.888364465419;2809.564517499791;2812.5921741347593;2815.5072862249126;2815.521361368562;2552.25984464506;2669.119190667075;2707.2284398946304;2859.742623163904;2863.2555535560696;2713.375923537578;2821.604975303459;2840.6733225679104;2841.105051908663;2847;15222.46721364747;15472.07167569938;15479.22603869802;15479.303431345617;15500.92249538745;8088.636011379502;8432.15678432158;8435.915640843636;8452.323814094858;8460.111688272704;5717.9714101429645;5780.421957804228;5894.675792831393;5923.555733319975;5949.6727679977585;1970.9310174143836;1986.294865322804;1993.3521605478277;2033.725447064657;2039.9082041308177;27163.592546111824;27188.456230875272;28714;28723.856380718382;28802.135935921957;7020.157581090302;7038.085048943604;7080.893786593205;7191.748288809867;7279.67241474135;16504.917475412545;17762.822371776118;17860.464186074438;17879.910600446914;18459.446216613524;8247.051589067334;8460.238578527962;8462.407631465738;8475.788105297293;8483.490990540464;7540.660670269851;7674.386049116058;7689.615519224017;7718.035245594301;7791.298783109547;5297.5232229099565;5467.316585426822;5492.697901615449;5503.972480137654;5558.194061861008;1563.7263478891148;1573.5279416175101;1587.2222610920796;1587.8094628644637;1592.673501932101;837.9036410812713;850.5789634131145;871.3900269811031;875.1292463998235;883.8099808541194;7057.435405167575;7061.505694601431;7083.681234344467;7131.500794944304;7193.89209161863;678.3555622158968;719.8668246374438;721.1202333153544;726.5241266970143;727.0620899040179;4985.077760614226;5008.273800298936;5065.834857982684;5096.108181068298;5159.793608255703;21962.780372196394;22331.218407355664;22578.322650320515;22734.09063637469;22810.20164294242;21158;21947.451313716967;22290.442738931655;22472.775272247396;22577.435564111027;11711.238769479956;11740.58907938218;11932.522699092113;11939.940300298444;12061.577844775566;7591.696332146652;7753.379729621504;7791.61041947889;7849.136594974434;7874.409419293691;18687.906560467112;18947.90526047361;18999.14503847358;19429.417117486497;19512.902435487733;19289.228430862608;19371.225150993803;19402.70895936534;19453.221871124995;19623.901880491077;11254.774904502028;11465.885341146482;11467.770644586895;11473.139514536277;11498.367589782307;14337.56987290383;14387.352569134624;14483.275836207937;14493.130412175304;14494.927525362305;10635.042846143884;10831.67504974852;11052.392118433563;11351.318920864775;11382.089432845518;10953.780924381626;11056.668299951014;11191.38447385369;11228.831567526657;11289.661310160709;9569.28230382738;9621;9698.418094914328;9704.466254356079;9704.805903882025;6458.354164583504;6477.708503117276;6500.447461965786;6522.836929076621;6617.536772425884;6745.86508269822;6919.654017299014;6980.232174460907;7010.053642758162;7042.084529011196 20:31:33 INFO - PID 4294 | |4;prototype.html;497.8979309241608;514.740056271583;534.1145755483145;538.7198656698514;546.8304825504094;6221.9999999999945;6233.189685340911;6248;6289.685515724218;6322.747090116395;6084.939150608493;6173.351798061206;6191.721372538235;6209.503239740821;6222.160008398865;5194.117000109972;5218.7651555679895;5236.450172731847;5242.2136679498;5261.342332208474;4834.58905992991;5039.622028347889;5101.84694459165;5221.425643179247;5239.161734122541;4981.9750901245625;5007.824726134586;5022.347094877661;5033.748312584356;5046.066477701618;4973.254011898208;4989.675671081368;5010.549050585465;5037.672551284155;5046.1169295373165;4425.137098265839;4504.797284122223;4519.389882365871;4535.977320113411;4541.954580454186;6044.1263208405535;6055.939440605582;6134.325224225377;6149.862275479006;6155.599886007395;5638.633488823254;5699.202111704365;5714.542836573106;5775.913361299542;5794.884102317972;6486.416222539994;6548.246951600579;6562.934370656328;6587.374199451045;6660.134182556237;4707.835225767116;4948.950510494885;4952.870265785711;4966.627502937295;4983.277424773388;6532.216134063895;6645.601263924132;6728;6769.864602707968;6917.827054323581;4992.527204474689;5003.899922001539;5019.297298378157;5031.622628302892;5086.618503612244;5093.516115968977;5122.308488354099;5132.409772876167;5143.459936706667;5150.399048480494;5673.3475650299915;5705.229793977843;5744.712764361765;5778.855528611818;5788.21859049023;6639.86720265592;6666.100076489709;6677.030276068572;6686.866262674719;6689.732410703517;1757.736339549078;1766;1775.1035726957803;1778.1376032624166;1786.4104845400987;1162.8313894485252;1200.2018657592778;1252.8809763072493;1253.9561115360918;1265.8670839561898;5588.888222235532;5702.210143479497;5735.254416925856;5746.281714785652;5773;6548;6731.562448440934;6742.528023038439;6743.763968261185;6794.898076528856;5630.4369563043365;5691.231683722727;5702.800401985911;5709.7716091356715;5741.684207368635;3469.757117001836;3491.633378495272;3498.600559776059;3520.4895290183294;3538.5930617978747;6422.068800024063;6450.2259728831905;6466.6766661666725;6513.381228783258;6521.11964884744;6469.353064693587;6809.557378770364;6818.590884546943;6910.170779506391;6936.861262774716;6413.743450261937;6420.678966051679;6434.903476447857;6469.708863101077;6506.219253689587;6692.899606505809;6700.162479690039;6714.731410743516;6739.326067393222;6766.86466270662;6506.902396463962;6612.4710023198995;6638.668066596748;6654.600723956578;6697.196336439659;6299.96850015747;6310.5898116622275;6324.810255692337;6391.360863913757;6416.165898433173;5000.124978128885;5011.599072074298;5018;5029.7736601852275;5039.722815245197;6464.741410343534;6527.379898909596;6607.9669601651685;6663.666816659051;6796.490263230178;6421.935780642134;6429.903551446639;6442.484601231984;6493.967530162319;6515.804525864231;6427;6430.1319321892815;6512.837179070561;6533.705983230861;6600.669966501751;6316.936830631635;6358.491320694241;6408.423241908251;6467.611943283418;6490.286068532552;6618.8345291368105;6637.767678131436;6653.733850646114;6656.334366563305;6675;6257.874842503215;6284.560080794484;6307.936920630735;6338.176037115146;6348.174737284124 20:31:33 INFO - PID 4294 | |5;yui.html;907.2243232036609;907.3376435202306;917;928.5171710710431;936.6955739384697;19179;19832.405027849152;19859.602807943833;19977.300794472198;19981.300654477116;18499.167537460813;18499.815001849976;18618.53453663659;18662.906685466587;18684.906575467103;975.3562648651873;988.574912787501;988.9604415823359;991.8313886639289;994.6866736977865;978.4765150644407;989.8267803134422;989.9604015839358;990.0248255468358;990.420603946692;980.7352014955958;1009.6920439266021;1024.3495380433403;1026.2816028779846;1032.5250384822953;1007.6422869881196;1011.110223003752;1011.7521207304221;1012.5797793915516;1013.7009582173276;724.7970568240901;744.133084956031;744.4900243333334;747.439420434674;751.8947347371372;19949.9002504988;19987.300444484372;20058.899705501524;20252.291169808985;20259.594808103902;20463.89768051165;20542.589148217103;22416.88791556048;22543.436414089614;22615.321540353816;17946.282148714035;18149.818501815076;18183.45449636513;18269.45191644253;18358.357457488924;3797.696184305249;3830.157365379612;3831.3103641344546;3832.1952389998132;3833.9424908626393;108835;141988.29005855008;142292.28853855768;143125.28437357745;143958;17628.647427051386;17648.470545883643;17793;17856.46430607084;17947.910260448614;30897.69102308993;31756.364872702416;31789.52315715266;31869.20326991844;31883.202919926724;9267.67563135287;9486.098820612031;9486.667966621135;9497.762555936157;9589.808203835884;4558.749268790182;4643.791029403685;4648.767561621973;4650.95349046512;4651.534846515389;5384.91922621161;5620.594851287178;5658.85852853682;5722.914156287659;5724.998125328051;1108.141190577309;1116.213069785799;1142.092036830705;1143.7083543696472;1147.3976162514746;660.9735610575619;674.5682763031664;682.1200651160048;692.8891377379595;695.5930780493367;11486.621605407405;11898.286102833685;11902.761944761056;12046.819297710541;12421.254724716546;665.3944910131756;684.3259389501337;693.767587858063;694.4964900447136;698.0924797762888;5952.791652292149;5974.940250597525;6000.129981152709;6001.4298641628975;6007.038873780174;117196;119185;121897.39051304686;125168;128320;98634;99023;99490.50254748824;100122.49938750405;102177;92701.5364923171;93083.53458232665;94400;96541.51729241309;96618.51690741502;10997;10997.780044399227;11082.722931926766;11178.720531986766;11323.547058117583;43771.34342984794;44479.77760111179;44574.777126115456;44637.553624464636;44895.55104448914;59436.40563594309;59527.702361487914;59531.404685954316;60258.09612855723;61219;15129.773053403987;15234.619134521725;15470.5358839235;15568.766468503209;15709.685806283582;17902.373416930346;18112.275508979492;18138.365157219436;18207.726884097014;18419;16457;16657.167141642625;16793.664126717154;16935.66128677395;16986.15069246508;16287.755683664518;16368.754468683217;16369.263383147549;16559.503214903572;16601.50195494137;14362.281885905872;14778.18719970412;14831.629209269422;14832.925835370754;14887.255637217879;11397.430128493708;11606.593769218036;11652.126090543412;11896.583619573274;12006.339651318913;12154.817677734663;12275;12333.383330833602;12396.75206495847;12698.555550555686 20:31:33 INFO - PID 4294 | __end_tp_report 20:31:33 INFO - PID 4294 | __start_cc_report 20:31:33 INFO - PID 4294 | _x_x_mozilla_cycle_collect,272 20:31:33 INFO - PID 4294 | __end_cc_report 20:31:33 INFO - PID 4294 | __startTimestamp1506223893017__endTimestamp 20:31:33 INFO - PID 4294 | 20:31:33 INFO - PID 4294 | ------- Summary: start ------- 20:31:33 INFO - PID 4294 | Number of tests: 6 20:31:33 INFO - PID 4294 | 20:31:33 INFO - PID 4294 | [#0] dojo.html Cycles:165 Average:6268.02 Median:6529.71 stddev:767.63 (11.8%) stddev-sans-first:729.21 20:31:33 INFO - PID 4294 | Values: 3121.1 3130.2 3145.9 3146.5 3155.1 6245.1 6366.9 6375.6 6413.6 6494.6 6234.6 6299.1 6300.1 6333.8 6353.8 5813.3 5851.5 5868.9 5909.4 5929.1 5590.3 5866.7 5892.3 5984.1 6018.7 5721.8 5825.4 5852.4 5878.6 5902.9 5813.6 5827.7 5881.2 5892.4 5898.6 5803.4 5815.8 5859.6 5891.5 5903.2 6237.2 6248.2 6313.5 6362.0 6470.0 6767.8 6768.0 6776.7 6779.0 6781.1 6727.5 6815.1 6839.2 6841.7 6946.3 6220.8 6297.1 6350.4 6350.5 6448.4 7011.6 7045.6 7090.2 7090.5 7091.5 6687.8 6692.6 6708.2 6739.4 6773.2 6672.7 6769.4 6774.3 6781.8 6853.5 6806.0 6810.4 6817.8 6831.0 6833.6 6759.0 6864.5 6872.8 6895.8 6896.6 5331.7 5363.1 5415.6 5417.8 5418.8 4592.2 4625.5 4670.7 4678.5 4685.8 5642.5 5729.6 5745.4 5748.5 5756.6 6796.3 6809.3 6887.5 6922.9 6931.5 5849.3 5863.9 5918.7 5938.0 5942.6 5710.1 5872.4 5932.9 5938.7 5939.9 6529.7 6608.5 6678.9 6679.0 6680.7 6906.7 6944.8 6956.3 6961.1 6968.8 6599.8 6619.7 6623.0 6707.2 6710.9 6609.5 6743.3 6766.4 6833.7 6862.6 6734.5 6795.7 6810.5 6835.2 6927.7 6615.7 6628.6 6646.0 6696.6 6721.3 6620.6 6634.6 6701.3 6705.0 6717.5 6412.7 6475.2 6563.5 6663.9 6672.8 6648.3 6700.3 6705.5 6737.9 6780.1 6227.8 6388.6 6390.0 6422.8 6456.8 20:31:33 INFO - PID 4294 | 20:31:33 INFO - PID 4294 | [#1] ext.html Cycles:180 Average:36478.90 Median:3573.06 stddev:50258.69 (1406.6%) stddev-sans-first:50328.48 20:31:33 INFO - PID 4294 | Values: 852.2 859.3 878.7 893.0 899.8 40686.4 41178.2 41365.0 41391.4 41563.4 41095.4 41468.8 41509.6 41629.4 41636.8 7970.9 8102.6 8125.1 8128.9 8149.1 8044.6 8173.8 8178.1 8178.1 8179.1 8191.8 8212.7 8216.8 8217.1 8223.5 8159.0 8186.2 8193.9 8196.5 8200.5 8017.1 8080.8 8085.2 8087.6 8090.6 38377.8 38688.6 38702.4 38817.0 39195.4 3558.2 3587.9 3589.3 3589.8 3591.6 31230.2 31299.2 31312.5 31614.0 31690.5 5055.6 5198.2 5288.3 5310.3 5325.9 182709.0 183705.0 183885.0 184354.0 184746.0 46851.8 47060.0 47081.8 47089.8 47211.5 48942.8 48972.8 49008.0 49018.3 49208.8 15564.7 15719.3 15734.3 15771.4 15951.4 12828.2 12962.8 12984.3 13053.2 13064.0 12329.7 12514.9 12692.1 12708.3 12816.4 4968.6 5028.7 5096.5 5108.4 5157.8 1005.4 1012.9 1014.0 1014.9 1027.8 22708.7 22811.4 23023.2 23100.9 23158.1 1103.9 1110.8 1111.3 1117.1 1119.3 12265.1 12281.8 12421.9 12455.7 12484.1 207389.0 209256.0 209720.0 209728.0 210056.0 40331.6 41013.0 49049.3 49201.8 51626.0 1458.3 1464.6 1465.1 1467.0 1469.5 27624.7 27746.3 27757.0 28116.7 28266.9 37390.8 37426.8 37440.6 37481.8 37488.3 188677.0 190109.0 190279.0 191149.0 191508.0 33281.0 34230.1 34450.6 34920.5 34938.0 41815.4 42518.0 42525.6 42567.6 42810.6 32206.4 32221.0 32354.2 32526.3 33102.5 31698.8 31752.6 31841.8 32206.7 32476.2 36621.3 36756.8 36841.4 36928.6 37006.0 31583.8 31709.5 31835.0 31840.7 31869.0 23162.7 23485.4 23534.2 23787.6 23872.5 20:31:33 INFO - PID 4294 | 20:31:33 INFO - PID 4294 | [#2] jquery.html Cycles:180 Average:33536.50 Median:12938.09 stddev:76628.57 (592.3%) stddev-sans-first:76803.61 20:31:33 INFO - PID 4294 | Values: 588.9 591.6 600.2 602.3 605.2 21858.2 22688.4 22710.2 22753.7 22760.4 23541.6 24146.0 25397.0 25638.7 25906.5 6603.9 6801.0 6814.9 6820.1 6838.6 6454.1 6674.9 6759.5 6777.8 6798.4 6696.3 6715.5 6718.5 6720.0 6724.9 6365.8 6647.2 6671.2 6681.7 6714.1 6127.8 6161.7 6173.4 6176.2 6176.6 29684.1 30106.0 30174.5 30190.4 30219.5 2047.6 2076.2 2080.2 2081.8 2084.0 19526.6 19881.4 20196.5 20364.8 20399.5 2334.5 2386.2 2445.0 2447.0 2449.3 124159.0 130322.0 133133.0 138467.0 138796.0 15003.7 15155.2 15477.4 15540.9 15967.4 24063.3 24112.6 24131.3 24140.8 24143.6 9144.5 9152.9 9273.5 9353.4 9373.8 8316.8 8327.0 8343.1 8424.2 8439.1 9105.8 9168.0 9213.5 9347.7 9539.4 2579.8 2582.5 2623.0 2684.1 2700.1 942.2 956.9 963.3 982.7 986.7 19326.1 19336.2 19378.3 19598.7 19919.0 1025.2 1028.2 1030.9 1040.4 1050.1 10765.7 10795.7 10853.3 11063.7 11071.8 445526.0 446524.0 447614.0 460568.0 466949.0 109348.5 109674.5 111979.0 112418.0 114222.0 1042.6 1090.1 1099.6 1156.9 1205.0 20589.9 20652.5 20725.0 20805.4 20890.6 55703.4 55734.2 55753.2 56136.4 56812.7 67207.0 69179.0 69395.0 69611.0 70006.6 14668.1 14810.8 14828.6 14903.6 14915.3 22663.9 22743.1 22803.8 23069.9 23086.1 18194.1 18212.8 18384.3 18560.9 18598.9 16049.0 16231.8 16721.2 17062.7 17112.5 18536.8 18777.4 18901.5 19244.0 19391.2 14531.9 14574.0 14582.9 14722.3 14763.6 17667.1 19060.7 19097.8 19318.7 19360.7 20:31:33 INFO - PID 4294 | 20:31:33 INFO - PID 4294 | [#3] mootools.html Cycles:180 Average:9497.92 Median:2814.05 stddev:6942.49 (246.7%) stddev-sans-first:6934.86 20:31:33 INFO - PID 4294 | Values: 1332.6 1386.6 1395.9 1400.6 1402.4 15063.2 15544.2 15712.8 15718.6 15718.7 15365.2 15425.8 15560.8 15565.4 15569.8 2783.4 2894.2 2900.9 2904.2 2906.9 2778.5 2823.6 2824.4 2834.8 2838.1 2790.9 2809.6 2812.6 2815.5 2815.5 2552.3 2669.1 2707.2 2859.7 2863.3 2713.4 2821.6 2840.7 2841.1 2847.0 15222.5 15472.1 15479.2 15479.3 15500.9 8088.6 8432.2 8435.9 8452.3 8460.1 5718.0 5780.4 5894.7 5923.6 5949.7 1970.9 1986.3 1993.4 2033.7 2039.9 27163.6 27188.5 28714.0 28723.9 28802.1 7020.2 7038.1 7080.9 7191.7 7279.7 16504.9 17762.8 17860.5 17879.9 18459.4 8247.1 8460.2 8462.4 8475.8 8483.5 7540.7 7674.4 7689.6 7718.0 7791.3 5297.5 5467.3 5492.7 5504.0 5558.2 1563.7 1573.5 1587.2 1587.8 1592.7 837.9 850.6 871.4 875.1 883.8 7057.4 7061.5 7083.7 7131.5 7193.9 678.4 719.9 721.1 726.5 727.1 4985.1 5008.3 5065.8 5096.1 5159.8 21962.8 22331.2 22578.3 22734.1 22810.2 21158.0 21947.5 22290.4 22472.8 22577.4 11711.2 11740.6 11932.5 11939.9 12061.6 7591.7 7753.4 7791.6 7849.1 7874.4 18687.9 18947.9 18999.1 19429.4 19512.9 19289.2 19371.2 19402.7 19453.2 19623.9 11254.8 11465.9 11467.8 11473.1 11498.4 14337.6 14387.4 14483.3 14493.1 14494.9 10635.0 10831.7 11052.4 11351.3 11382.1 10953.8 11056.7 11191.4 11228.8 11289.7 9569.3 9621.0 9698.4 9704.5 9704.8 6458.4 6477.7 6500.4 6522.8 6617.5 6745.9 6919.7 6980.2 7010.1 7042.1 20:31:33 INFO - PID 4294 | 20:31:33 INFO - PID 4294 | [#4] prototype.html Cycles:180 Average:5511.12 Median:6164.48 stddev:1528.73 (24.8%) stddev-sans-first:1485.99 20:31:33 INFO - PID 4294 | Values: 497.9 514.7 534.1 538.7 546.8 6222.0 6233.2 6248.0 6289.7 6322.7 6084.9 6173.4 6191.7 6209.5 6222.2 5194.1 5218.8 5236.5 5242.2 5261.3 4834.6 5039.6 5101.8 5221.4 5239.2 4982.0 5007.8 5022.3 5033.7 5046.1 4973.3 4989.7 5010.5 5037.7 5046.1 4425.1 4504.8 4519.4 4536.0 4542.0 6044.1 6055.9 6134.3 6149.9 6155.6 5638.6 5699.2 5714.5 5775.9 5794.9 6486.4 6548.2 6562.9 6587.4 6660.1 4707.8 4949.0 4952.9 4966.6 4983.3 6532.2 6645.6 6728.0 6769.9 6917.8 4992.5 5003.9 5019.3 5031.6 5086.6 5093.5 5122.3 5132.4 5143.5 5150.4 5673.3 5705.2 5744.7 5778.9 5788.2 6639.9 6666.1 6677.0 6686.9 6689.7 1757.7 1766.0 1775.1 1778.1 1786.4 1162.8 1200.2 1252.9 1254.0 1265.9 5588.9 5702.2 5735.3 5746.3 5773.0 6548.0 6731.6 6742.5 6743.8 6794.9 5630.4 5691.2 5702.8 5709.8 5741.7 3469.8 3491.6 3498.6 3520.5 3538.6 6422.1 6450.2 6466.7 6513.4 6521.1 6469.4 6809.6 6818.6 6910.2 6936.9 6413.7 6420.7 6434.9 6469.7 6506.2 6692.9 6700.2 6714.7 6739.3 6766.9 6506.9 6612.5 6638.7 6654.6 6697.2 6300.0 6310.6 6324.8 6391.4 6416.2 5000.1 5011.6 5018.0 5029.8 5039.7 6464.7 6527.4 6608.0 6663.7 6796.5 6421.9 6429.9 6442.5 6494.0 6515.8 6427.0 6430.1 6512.8 6533.7 6600.7 6316.9 6358.5 6408.4 6467.6 6490.3 6618.8 6637.8 6653.7 6656.3 6675.0 6257.9 6284.6 6307.9 6338.2 6348.2 20:31:33 INFO - PID 4294 | 20:31:33 INFO - PID 4294 | [#5] yui.html Cycles:180 Average:24209.89 Median:19979.30 stddev:34436.89 (172.4%) stddev-sans-first:34489.04 20:31:33 INFO - PID 4294 | Values: 907.2 907.3 917.0 928.5 936.7 19179.0 19832.4 19859.6 19977.3 19981.3 18499.2 18499.8 18618.5 18662.9 18684.9 975.4 988.6 989.0 991.8 994.7 978.5 989.8 990.0 990.0 990.4 980.7 1009.7 1024.3 1026.3 1032.5 1007.6 1011.1 1011.8 1012.6 1013.7 724.8 744.1 744.5 747.4 751.9 19949.9 19987.3 20058.9 20252.3 20259.6 20463.9 20542.6 22416.9 22543.4 22615.3 17946.3 18149.8 18183.5 18269.5 18358.4 3797.7 3830.2 3831.3 3832.2 3833.9 108835.0 141988.3 142292.3 143125.3 143958.0 17628.6 17648.5 17793.0 17856.5 17947.9 30897.7 31756.4 31789.5 31869.2 31883.2 9267.7 9486.1 9486.7 9497.8 9589.8 4558.7 4643.8 4648.8 4651.0 4651.5 5384.9 5620.6 5658.9 5722.9 5725.0 1108.1 1116.2 1142.1 1143.7 1147.4 661.0 674.6 682.1 692.9 695.6 11486.6 11898.3 11902.8 12046.8 12421.3 665.4 684.3 693.8 694.5 698.1 5952.8 5974.9 6000.1 6001.4 6007.0 117196.0 119185.0 121897.4 125168.0 128320.0 98634.0 99023.0 99490.5 100122.5 102177.0 92701.5 93083.5 94400.0 96541.5 96618.5 10997.0 10997.8 11082.7 11178.7 11323.5 43771.3 44479.8 44574.8 44637.6 44895.6 59436.4 59527.7 59531.4 60258.1 61219.0 15129.8 15234.6 15470.5 15568.8 15709.7 17902.4 18112.3 18138.4 18207.7 18419.0 16457.0 16657.2 16793.7 16935.7 16986.2 16287.8 16368.8 16369.3 16559.5 16601.5 14362.3 14778.2 14831.6 14832.9 14887.3 11397.4 11606.6 11652.1 11896.6 12006.3 12154.8 12275.0 12333.4 12396.8 12698.6 20:31:33 INFO - PID 4294 | -------- Summary: end -------- 20:31:33 INFO - PID 4294 | 20:31:33 INFO - TEST-INFO | 4294: exit 0 20:31:33 INFO - TEST-OK | dromaeo_css | took 1108766ms 20:31:33 INFO - TEST-START | kraken 20:31:33 INFO - Initialising browser for kraken test... 20:31:33 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54273/getInfo.html -profile /tmp/tmpaltI2b/profile 20:31:33 INFO - TEST-INFO | started process 4672 (/builds/slave/test/build/application/firefox/firefox http://localhost:54273/getInfo.html) 20:31:38 INFO - TEST-INFO | 4672: exit 0 20:31:38 INFO - Browser initialized. 20:31:38 INFO - Running cycle 1/1 for kraken test... 20:31:38 INFO - Using env: {'DISPLAY': ':0', 20:31:38 INFO - 'HOME': '/home/cltbld', 20:31:38 INFO - 'JSGC_DISABLE_POISONING': '1', 20:31:38 INFO - 'LANG': 'en_US.UTF-8', 20:31:38 INFO - 'LANGUAGE': 'en_US:en', 20:31:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 20:31:38 INFO - 'LOGNAME': 'cltbld', 20:31:38 INFO - 'MAIL': '/var/mail/cltbld', 20:31:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:31:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:31:38 INFO - 'MOZ_CRASHREPORTER': '1', 20:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:31:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 20:31:38 INFO - 'MOZ_NO_REMOTE': '1', 20:31:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:31:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:31:38 INFO - 'NO_EM_RESTART': '1', 20:31:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:31:38 INFO - 'PWD': '/builds/slave/test', 20:31:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:31:38 INFO - 'RUST_BACKTRACE': 'full', 20:31:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:31:38 INFO - 'SHELL': '/bin/bash', 20:31:38 INFO - 'SHLVL': '1', 20:31:38 INFO - 'TERM': 'linux', 20:31:38 INFO - 'TMOUT': '86400', 20:31:38 INFO - 'USER': 'cltbld', 20:31:38 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895', 20:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:31:38 INFO - '_': '/tools/buildbot/bin/python'} 20:31:38 INFO - TEST-INFO | started process 4905 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaltI2b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 20:31:39 INFO - PID 4905 | 20:31:39 INFO - PID 4905 | (/builds/slave/test/build/application/firefox/firefox:4954): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 20:31:39 INFO - PID 4905 | 20:31:50 INFO - PID 4905 | RSS: Main: 153194496 20:31:50 INFO - PID 4905 | 20:31:52 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54273/tests/kraken/driver.html?testName=audio-beat-detection) 20:31:53 INFO - PID 4905 | RSS: Main: 159064064 20:31:53 INFO - PID 4905 | 20:31:56 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54273/tests/kraken/driver.html?testName=audio-dft) 20:31:56 INFO - PID 4905 | RSS: Main: 159412224 20:31:56 INFO - PID 4905 | 20:31:59 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54273/tests/kraken/driver.html?testName=audio-fft) 20:32:00 INFO - PID 4905 | RSS: Main: 156168192 20:32:00 INFO - PID 4905 | 20:32:02 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54273/tests/kraken/driver.html?testName=audio-oscillator) 20:32:03 INFO - PID 4905 | RSS: Main: 156475392 20:32:03 INFO - PID 4905 | 20:32:05 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:32:06 INFO - PID 4905 | RSS: Main: 156430336 20:32:06 INFO - PID 4905 | 20:32:09 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54273/tests/kraken/driver.html?testName=imaging-darkroom) 20:32:09 INFO - PID 4905 | RSS: Main: 158953472 20:32:09 INFO - PID 4905 | 20:32:13 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54273/tests/kraken/driver.html?testName=imaging-desaturate) 20:32:14 INFO - PID 4905 | RSS: Main: 159186944 20:32:14 INFO - PID 4905 | 20:32:17 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54273/tests/kraken/driver.html?testName=json-parse-financial) 20:32:17 INFO - PID 4905 | RSS: Main: 159555584 20:32:17 INFO - PID 4905 | 20:32:19 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:32:20 INFO - PID 4905 | RSS: Main: 159469568 20:32:20 INFO - PID 4905 | 20:32:21 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54273/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:32:22 INFO - PID 4905 | RSS: Main: 159371264 20:32:22 INFO - PID 4905 | 20:32:24 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:32:25 INFO - PID 4905 | RSS: Main: 156459008 20:32:25 INFO - PID 4905 | 20:32:27 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:32:28 INFO - PID 4905 | RSS: Main: 156745728 20:32:28 INFO - PID 4905 | 20:32:31 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:32:31 INFO - PID 4905 | RSS: Main: 156696576 20:32:31 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | Cycle 1(1): loaded http://localhost:54273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54273/tests/kraken/driver.html?testName=ai-astar) 20:32:33 INFO - PID 4905 | RSS: Main: 158347264 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | __start_tp_report 20:32:33 INFO - PID 4905 | _x_x_mozilla_page_load 20:32:33 INFO - PID 4905 | _x_x_mozilla_page_load_details 20:32:33 INFO - PID 4905 | |i|pagename|runs| 20:32:33 INFO - PID 4905 | |0;ai-astar;108;107;109;107;107;107;110;110;108;109 20:32:33 INFO - PID 4905 | |1;audio-beat-detection;118;122;116;117;117;119;116;154;117;119 20:32:33 INFO - PID 4905 | |2;audio-dft;164;163;162;164;164;164;163;168;167;164 20:32:33 INFO - PID 4905 | |3;audio-fft;74;75;77;74;75;74;74;75;77;74 20:32:33 INFO - PID 4905 | |4;audio-oscillator;80;80;79;80;83;83;78;83;81;83 20:32:33 INFO - PID 4905 | |5;imaging-gaussian-blur;112;113;113;114;114;114;115;114;115;115 20:32:33 INFO - PID 4905 | |6;imaging-darkroom;149;149;149;149;149;150;148;150;148;149 20:32:33 INFO - PID 4905 | |7;imaging-desaturate;94;95;94;94;94;96;94;94;100;94 20:32:33 INFO - PID 4905 | |8;json-parse-financial;58;55;54;55;55;55;56;55;53;53 20:32:33 INFO - PID 4905 | |9;json-stringify-tinderbox;37;37;38;38;37;37;38;38;38;37 20:32:33 INFO - PID 4905 | |10;stanford-crypto-aes;80;80;78;75;72;73;73;73;73;77 20:32:33 INFO - PID 4905 | |11;stanford-crypto-ccm;116;114;111;110;110;122;112;111;115;109 20:32:33 INFO - PID 4905 | |12;stanford-crypto-pbkdf2;168;175;170;167;168;173;171;171;172;169 20:32:33 INFO - PID 4905 | |13;stanford-crypto-sha256-iterative;67;66;65;63;73;67;65;70;71;65 20:32:33 INFO - PID 4905 | __end_tp_report 20:32:33 INFO - PID 4905 | __start_cc_report 20:32:33 INFO - PID 4905 | _x_x_mozilla_cycle_collect,728 20:32:33 INFO - PID 4905 | __end_cc_report 20:32:33 INFO - PID 4905 | __startTimestamp1506223953840__endTimestamp 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | ------- Summary: start ------- 20:32:33 INFO - PID 4905 | Number of tests: 14 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#0] ai-astar Cycles:10 Average:108.20 Median:108.50 stddev:1.23 (1.1%) stddev-sans-first:1.30 20:32:33 INFO - PID 4905 | Values: 108.0 107.0 109.0 107.0 107.0 107.0 110.0 110.0 108.0 109.0 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#1] audio-beat-detection Cycles:10 Average:121.50 Median:118.50 stddev:11.56 (9.8%) stddev-sans-first:12.19 20:32:33 INFO - PID 4905 | Values: 118.0 122.0 116.0 117.0 117.0 119.0 116.0 154.0 117.0 119.0 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#2] audio-dft Cycles:10 Average:164.30 Median:164.00 stddev:1.83 (1.1%) stddev-sans-first:1.94 20:32:33 INFO - PID 4905 | Values: 164.0 163.0 162.0 164.0 164.0 164.0 163.0 168.0 167.0 164.0 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#3] audio-fft Cycles:10 Average:74.90 Median:75.00 stddev:1.20 (1.6%) stddev-sans-first:1.22 20:32:33 INFO - PID 4905 | Values: 74.0 75.0 77.0 74.0 75.0 74.0 74.0 75.0 77.0 74.0 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#4] audio-oscillator Cycles:10 Average:81.00 Median:82.00 stddev:1.89 (2.3%) stddev-sans-first:1.96 20:32:33 INFO - PID 4905 | Values: 80.0 80.0 79.0 80.0 83.0 83.0 78.0 83.0 81.0 83.0 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.99 (0.9%) stddev-sans-first:0.78 20:32:33 INFO - PID 4905 | Values: 112.0 113.0 113.0 114.0 114.0 114.0 115.0 114.0 115.0 115.0 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 20:32:33 INFO - PID 4905 | Values: 149.0 149.0 149.0 149.0 149.0 150.0 148.0 150.0 148.0 149.0 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:94.00 stddev:1.91 (2.0%) stddev-sans-first:2.00 20:32:33 INFO - PID 4905 | Values: 94.0 95.0 94.0 94.0 94.0 96.0 94.0 94.0 100.0 94.0 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:1.45 (2.6%) stddev-sans-first:1.01 20:32:33 INFO - PID 4905 | Values: 58.0 55.0 54.0 55.0 55.0 55.0 56.0 55.0 53.0 53.0 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#9] json-stringify-tinderbox Cycles:10 Average:37.50 Median:38.00 stddev:0.53 (1.4%) stddev-sans-first:0.53 20:32:33 INFO - PID 4905 | Values: 37.0 37.0 38.0 38.0 37.0 37.0 38.0 38.0 38.0 37.0 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:76.00 stddev:3.10 (4.1%) stddev-sans-first:2.80 20:32:33 INFO - PID 4905 | Values: 80.0 80.0 78.0 75.0 72.0 73.0 73.0 73.0 73.0 77.0 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#11] stanford-crypto-ccm Cycles:10 Average:113.00 Median:113.00 stddev:3.92 (3.5%) stddev-sans-first:4.00 20:32:33 INFO - PID 4905 | Values: 116.0 114.0 111.0 110.0 110.0 122.0 112.0 111.0 115.0 109.0 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.40 Median:171.00 stddev:2.50 (1.5%) stddev-sans-first:2.50 20:32:33 INFO - PID 4905 | Values: 168.0 175.0 170.0 167.0 168.0 173.0 171.0 171.0 172.0 169.0 20:32:33 INFO - PID 4905 | 20:32:33 INFO - PID 4905 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:3.16 (4.7%) stddev-sans-first:3.35 20:32:33 INFO - PID 4905 | Values: 67.0 66.0 65.0 63.0 73.0 67.0 65.0 70.0 71.0 65.0 20:32:33 INFO - PID 4905 | -------- Summary: end -------- 20:32:33 INFO - PID 4905 | 20:32:34 INFO - PID 4905 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 20:32:34 INFO - TEST-INFO | 4905: exit 0 20:32:34 INFO - TEST-OK | kraken | took 60707ms 20:32:34 INFO - SUITE-END | took 1169s 20:32:34 INFO - Completed test suite (00:19:29) 20:32:34 INFO - javascript benchmark 20:32:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8556.850101637656, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3121.079281611925, 3130.1861516005833, 3145.905622831315, 3146.5280207968817, 3155.053483954813, 6245.094461303108, 6366.936330636692, 6375.649339286343, 6413.583117097385, 6494.610323380601, 6234.625922444657, 6299.055141728743, 6300.117983482305, 6333.841653958653, 6353.8411539711415, 5813.30240371156, 5851.502622277111, 5868.853278668024, 5909.379515150912, 5929.140274660172, 5590.329160500745, 5866.73599688015, 5892.2929248490245, 5984.102384642317, 6018.699065046752, 5721.828345149652, 5825.388334224887, 5852.356240813507, 5878.617889837147, 5902.911456328158, 5813.563982701272, 5827.650340979555, 5881.2354393928945, 5892.410758924116, 5898.557608179361, 5803.448672376117, 5815.767369305223, 5859.648421094748, 5891.499222566087, 5903.173555702205, 6237.157983672191, 6248.187735594389, 6313.526485513559, 6362.0, 6470.029495575702, 6767.830804229884, 6767.966160169168, 6776.69504872277, 6778.966105169443, 6781.084553585301, 6727.495437842182, 6815.079964204819, 6839.179298484164, 6841.657917104125, 6946.340097690711, 6220.751169953196, 6297.118403423525, 6350.428461438447, 6350.491960643184, 6448.387403196736, 7011.579305241702, 7045.577265364094, 7090.184628767654, 7090.503664743522, 7091.503594748421, 6687.799366019027, 6692.564983276072, 6708.19501659804, 6739.359760822714, 6773.1872175338285, 6672.666366681647, 6769.39075483199, 6774.254831968479, 6781.830454238585, 6853.451723862178, 6805.9659701701175, 6810.421114205348, 6817.795466136024, 6830.965845170742, 6833.624150671761, 6758.966205168943, 6864.450843932474, 6872.828179295458, 6895.793126206222, 6896.620685862325, 5331.733413329318, 5363.061464243741, 5415.6479828811, 5417.756200970965, 5418.810341638024, 4592.2193227151465, 4625.4911959684405, 4670.719756814602, 4678.532146785362, 4685.835995740201, 5642.520385767174, 5729.624890026364, 5745.396733343023, 5748.48263656273, 5756.597038207369, 6796.25241223465, 6809.285025072395, 6887.5178737487895, 6922.930770692228, 6931.549449285781, 5849.298084229835, 5863.941360586425, 5918.733656985446, 5938.0, 5942.554308426886, 5710.086386178275, 5872.442117998783, 5932.911006334908, 5938.673372964528, 5939.881202375928, 6529.706163222571, 6608.537402381787, 6678.899816502756, 6679.031540426611, 6680.699368528428, 6906.723731050803, 6944.791656250321, 6956.339147780953, 6961.095057642474, 6968.825779355557, 6599.769008084694, 6619.702113405005, 6622.966885165543, 6707.161604799205, 6710.932890671031, 6609.504287178577, 6743.29195434492, 6766.4248538874745, 6833.69248383814, 6862.622555759481, 6734.528582999143, 6795.694193761392, 6810.489213309121, 6835.179778426621, 6927.7228910845015, 6615.702293396905, 6628.635425051669, 6646.0, 6696.59820410777, 6721.327867213239, 6620.63586502747, 6634.601923884582, 6701.262861085374, 6704.966475167593, 6717.529772916045, 6412.743490260337, 6475.222973243241, 6563.474922006132, 6663.900041499478, 6672.766453174116, 6648.268690444144, 6700.329967003261, 6705.463562914858, 6737.86524269502, 6780.118584583973, 6227.813165605039, 6388.552801303864, 6390.041493775971, 6422.839429014312, 6456.774012909714], "value": 6207.942244461425, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [852.1861622150847, 859.2996707683242, 878.683673877404, 892.977675558111, 899.815537814748, 40686.389704154426, 41178.17643647125, 41364.96587585308, 41391.379129313085, 41563.376549351786, 41095.38356924641, 41468.792656036676, 41509.58490415095, 41629.375559366556, 41636.79181604095, 7970.92029079709, 8102.635381407821, 8125.146859579748, 8128.878066829002, 8149.103598604149, 8044.597770111501, 8173.795655108611, 8178.100408955011, 8178.100408955011, 8179.141190175035, 8191.836163276731, 8212.712555060574, 8216.753497395088, 8217.137200593941, 8223.465474744122, 8158.959205203966, 8186.222308880647, 8193.918060819375, 8196.467229630054, 8200.507969521848, 8017.118117007183, 8080.797980050487, 8085.231902969209, 8087.595620218965, 8090.595470226524, 38377.808110959544, 38688.613113868785, 38702.419463708065, 38817.0, 39195.412068818994, 3558.217192217734, 3587.910302242439, 3589.300086483137, 3589.820508974567, 3591.5690117185845, 31230.21924451884, 31299.217519561964, 31312.53031204534, 31614.0, 31690.524642130385, 5055.595552355802, 5198.16829307309, 5288.338957630296, 5310.283111779899, 5325.8934821303355, 182709.0, 183705.0, 183885.0, 184354.0, 184746.0, 46851.76574117176, 47060.0, 47081.76459117751, 47089.764551177024, 47211.527884720716, 48942.75528622334, 48972.755136224805, 49008.0, 49018.26472602914, 49208.75395623071, 15564.688706225812, 15719.292631831595, 15734.291956861969, 15771.44799931997, 15951.361945522282, 12828.230306181658, 12962.80555791664, 12984.285864277366, 13053.216806991612, 13064.0, 12329.691757706129, 12514.874851251552, 12692.111552191443, 12708.3010434427, 12816.359182041047, 4968.627352948543, 5028.719844163614, 5096.541311282002, 5108.386993560796, 5157.767900444489, 1005.3967619428284, 1012.8632634594383, 1013.9898601014042, 1014.8731408573929, 1027.799579082072, 22708.65937010946, 22811.429714256945, 23023.19418820333, 23100.884495577415, 23158.073677053068, 1103.85098011769, 1110.7667389848061, 1111.2721167635211, 1117.0616681987008, 1119.3172164979355, 12265.141440099285, 12281.815772763415, 12421.875781242252, 12455.688607784878, 12484.063695222894, 207389.0, 209256.0, 209720.0, 209728.0, 210056.0, 40331.59668403278, 41013.0, 49049.264261036114, 49201.75399123053, 51626.0, 1458.2854401343477, 1464.5972357601745, 1465.1209274435466, 1466.9853301467062, 1469.5297504798575, 27624.723752762216, 27746.306342341602, 27757.0, 28116.71883281141, 28266.85866570654, 37390.8130459346, 37426.8128659355, 37440.6255937448, 37481.81259093687, 37488.2502349946, 188677.0, 190109.0, 190279.0, 191149.04425477784, 191508.0, 33281.0, 34230.14424639404, 34450.55494450535, 34920.47619285662, 34938.0, 41815.37276940909, 42518.0, 42525.57474425216, 42567.57432425636, 42810.571894280656, 32206.35587288288, 32221.0, 32354.191145221557, 32526.34947301088, 33102.50346244856, 31698.841505792323, 31752.64704858464, 31841.840790795897, 32206.67793322037, 32476.188095297806, 36621.26757464783, 36756.81621591875, 36841.44737828881, 36928.63071369252, 37006.0, 31583.842080789447, 31709.5243571342, 31835.0, 31840.68159318377, 31869.04392868218, 23162.652560211947, 23485.412864677837, 23534.176303829285, 23787.643185351888, 23872.52254954926], "value": 17126.672710611325, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.9028310328796, 591.5740666719961, 600.2257088356021, 602.3374288282888, 605.1527860994607, 21858.23496177634, 22688.43278918024, 22710.205142819985, 22753.658695119546, 22760.43098922528, 23541.646875296883, 24146.0, 25397.0, 25638.74361256387, 25906.48187036263, 6603.93396066038, 6800.965995170017, 6814.863702725943, 6820.079289295931, 6838.589684618914, 6454.0641606966965, 6674.866502669944, 6759.459243260552, 6777.76277830276, 6798.422134118606, 6696.263411024807, 6715.496337774662, 6718.49611279156, 6719.966400167992, 6724.865502689968, 6365.8408539786415, 6647.202335719696, 6671.166104236971, 6681.66591670417, 6714.127163468742, 6127.816165515042, 6161.722722477454, 6173.382661733791, 6176.197094377702, 6176.567640265183, 29684.109476715734, 30106.0, 30174.54738178907, 30190.396192076256, 30219.546706799196, 2047.600717860003, 2076.169532187137, 2080.1991233375106, 2081.8334533237303, 2084.0205103601284, 19526.609467810707, 19881.403557893285, 20196.49508762278, 20364.79635203644, 20399.49001274965, 2334.5330933813307, 2386.22447704495, 2444.9877750611313, 2446.963295550568, 2449.30194894456, 124159.0, 130322.0, 133133.0, 138467.0, 138795.99999999898, 15003.696968485956, 15155.242237888062, 15477.380904763684, 15540.922295388677, 15967.36130554788, 24063.27810165698, 24112.63831042536, 24131.276061718178, 24140.75859241385, 24143.63784543233, 9144.54277286133, 9152.90847091534, 9273.489958052372, 9353.438793672402, 9373.765655858522, 8316.750497484963, 8327.0, 8343.123971982975, 8424.199701028392, 8439.113893041265, 9105.81788364229, 9167.95416022929, 9213.493257870881, 9347.672831450867, 9539.379940303857, 2579.8194126411345, 2582.496413199408, 2622.960655590168, 2684.087410280514, 2700.135956493903, 942.2320808541017, 956.8851737791509, 963.2582911158369, 982.6511588386163, 986.7039888033561, 19326.130324135167, 19336.226550937805, 19378.321758738377, 19598.66087441713, 19919.0, 1025.2003437318904, 1028.2339656955617, 1030.9123874312672, 1040.4485622620023, 1050.1336397472116, 10765.730856728644, 10795.676129716121, 10853.34879907208, 11063.72340691489, 11071.833922491169, 445526.0, 446524.0, 447614.0, 460568.0, 466949.0, 109348.4532577332, 109674.45162774295, 111979.0, 112418.0, 114222.0, 1042.635077722791, 1090.0734375780683, 1099.5601759296346, 1156.8900954409319, 1204.975900482003, 20589.89705051465, 20652.483687907323, 20725.0, 20805.375838724256, 20890.582188355846, 55703.44296557145, 55734.16398754103, 55753.16370254531, 56136.43863561475, 56812.715936420056, 67207.0, 69178.96231556631, 69395.0, 69611.0, 70006.64996675188, 14668.119912805267, 14810.777838332218, 14828.555143345717, 14903.62740931442, 14915.254237288309, 22663.88668056649, 22743.09027638876, 22803.77196228083, 23069.88465057664, 23086.076556937533, 18194.090295484908, 18212.817871821113, 18384.264629415207, 18560.886346818694, 18598.907005464887, 16049.0, 16231.756523651919, 16721.163941802617, 17062.658746825244, 17112.48662540126, 18536.814631853507, 18777.436676899713, 18901.527461813566, 19244.0, 19391.2243510258, 14531.854681453051, 14574.0, 14582.927085364505, 14722.337494812973, 14763.630909227355, 17667.116644167996, 19060.714089288947, 19097.809021909605, 19318.710219347002, 19360.70958935589], "value": 11957.736890377031, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.6268644779461, 1386.6464051666826, 1395.923224222668, 1400.6218321053323, 1402.3759427054958, 15063.17152556609, 15544.222788860568, 15712.764308535352, 15718.607034824136, 15718.685626287483, 15365.231738413091, 15425.845741542582, 15560.84439155608, 15565.37738490459, 15569.844301556981, 2783.3737409082946, 2894.233028247516, 2900.9129726108144, 2904.1868276882506, 2906.8691908864153, 2778.513760091986, 2823.5764635304663, 2824.449232399683, 2834.7590454811366, 2838.063439065114, 2790.888364465419, 2809.564517499791, 2812.5921741347593, 2815.5072862249126, 2815.521361368562, 2552.25984464506, 2669.119190667075, 2707.2284398946304, 2859.742623163904, 2863.2555535560696, 2713.375923537578, 2821.604975303459, 2840.6733225679104, 2841.105051908663, 2847.0, 15222.46721364747, 15472.07167569938, 15479.22603869802, 15479.303431345617, 15500.92249538745, 8088.636011379502, 8432.15678432158, 8435.915640843636, 8452.323814094858, 8460.111688272704, 5717.9714101429645, 5780.421957804228, 5894.675792831393, 5923.555733319975, 5949.6727679977585, 1970.9310174143836, 1986.294865322804, 1993.3521605478277, 2033.725447064657, 2039.9082041308177, 27163.592546111824, 27188.456230875272, 28714.0, 28723.856380718382, 28802.135935921957, 7020.157581090302, 7038.085048943604, 7080.893786593205, 7191.748288809867, 7279.67241474135, 16504.917475412545, 17762.822371776118, 17860.464186074438, 17879.910600446914, 18459.446216613524, 8247.051589067334, 8460.238578527962, 8462.407631465738, 8475.788105297293, 8483.490990540464, 7540.660670269851, 7674.386049116058, 7689.615519224017, 7718.035245594301, 7791.298783109547, 5297.5232229099565, 5467.316585426822, 5492.697901615449, 5503.972480137654, 5558.194061861008, 1563.7263478891148, 1573.5279416175101, 1587.2222610920796, 1587.8094628644637, 1592.673501932101, 837.9036410812713, 850.5789634131145, 871.3900269811031, 875.1292463998235, 883.8099808541194, 7057.435405167575, 7061.505694601431, 7083.681234344467, 7131.500794944304, 7193.89209161863, 678.3555622158968, 719.8668246374438, 721.1202333153544, 726.5241266970143, 727.0620899040179, 4985.077760614226, 5008.273800298936, 5065.834857982684, 5096.108181068298, 5159.793608255703, 21962.780372196394, 22331.218407355664, 22578.322650320515, 22734.09063637469, 22810.20164294242, 21158.0, 21947.451313716967, 22290.442738931655, 22472.775272247396, 22577.435564111027, 11711.238769479956, 11740.58907938218, 11932.522699092113, 11939.940300298444, 12061.577844775566, 7591.696332146652, 7753.379729621504, 7791.61041947889, 7849.136594974434, 7874.409419293691, 18687.906560467112, 18947.90526047361, 18999.14503847358, 19429.417117486497, 19512.902435487733, 19289.228430862608, 19371.225150993803, 19402.70895936534, 19453.221871124995, 19623.901880491077, 11254.774904502028, 11465.885341146482, 11467.770644586895, 11473.139514536277, 11498.367589782307, 14337.56987290383, 14387.352569134624, 14483.275836207937, 14493.130412175304, 14494.927525362305, 10635.042846143884, 10831.67504974852, 11052.392118433563, 11351.318920864775, 11382.089432845518, 10953.780924381626, 11056.668299951014, 11191.38447385369, 11228.831567526657, 11289.661310160709, 9569.28230382738, 9621.0, 9698.418094914328, 9704.466254356079, 9704.805903882025, 6458.354164583504, 6477.708503117276, 6500.447461965786, 6522.836929076621, 6617.536772425884, 6745.86508269822, 6919.654017299014, 6980.232174460907, 7010.053642758162, 7042.084529011196], "value": 6714.025675639205, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.8979309241608, 514.740056271583, 534.1145755483145, 538.7198656698514, 546.8304825504094, 6221.9999999999945, 6233.189685340911, 6248.0, 6289.685515724218, 6322.747090116395, 6084.939150608493, 6173.351798061206, 6191.721372538235, 6209.503239740821, 6222.160008398865, 5194.117000109972, 5218.7651555679895, 5236.450172731847, 5242.2136679498, 5261.342332208474, 4834.58905992991, 5039.622028347889, 5101.84694459165, 5221.425643179247, 5239.161734122541, 4981.9750901245625, 5007.824726134586, 5022.347094877661, 5033.748312584356, 5046.066477701618, 4973.254011898208, 4989.675671081368, 5010.549050585465, 5037.672551284155, 5046.1169295373165, 4425.137098265839, 4504.797284122223, 4519.389882365871, 4535.977320113411, 4541.954580454186, 6044.1263208405535, 6055.939440605582, 6134.325224225377, 6149.862275479006, 6155.599886007395, 5638.633488823254, 5699.202111704365, 5714.542836573106, 5775.913361299542, 5794.884102317972, 6486.416222539994, 6548.246951600579, 6562.934370656328, 6587.374199451045, 6660.134182556237, 4707.835225767116, 4948.950510494885, 4952.870265785711, 4966.627502937295, 4983.277424773388, 6532.216134063895, 6645.601263924132, 6728.0, 6769.864602707968, 6917.827054323581, 4992.527204474689, 5003.899922001539, 5019.297298378157, 5031.622628302892, 5086.618503612244, 5093.516115968977, 5122.308488354099, 5132.409772876167, 5143.459936706667, 5150.399048480494, 5673.3475650299915, 5705.229793977843, 5744.712764361765, 5778.855528611818, 5788.21859049023, 6639.86720265592, 6666.100076489709, 6677.030276068572, 6686.866262674719, 6689.732410703517, 1757.736339549078, 1766.0, 1775.1035726957803, 1778.1376032624166, 1786.4104845400987, 1162.8313894485252, 1200.2018657592778, 1252.8809763072493, 1253.9561115360918, 1265.8670839561898, 5588.888222235532, 5702.210143479497, 5735.254416925856, 5746.281714785652, 5773.0, 6548.0, 6731.562448440934, 6742.528023038439, 6743.763968261185, 6794.898076528856, 5630.4369563043365, 5691.231683722727, 5702.800401985911, 5709.7716091356715, 5741.684207368635, 3469.757117001836, 3491.633378495272, 3498.600559776059, 3520.4895290183294, 3538.5930617978747, 6422.068800024063, 6450.2259728831905, 6466.6766661666725, 6513.381228783258, 6521.11964884744, 6469.353064693587, 6809.557378770364, 6818.590884546943, 6910.170779506391, 6936.861262774716, 6413.743450261937, 6420.678966051679, 6434.903476447857, 6469.708863101077, 6506.219253689587, 6692.899606505809, 6700.162479690039, 6714.731410743516, 6739.326067393222, 6766.86466270662, 6506.902396463962, 6612.4710023198995, 6638.668066596748, 6654.600723956578, 6697.196336439659, 6299.96850015747, 6310.5898116622275, 6324.810255692337, 6391.360863913757, 6416.165898433173, 5000.124978128885, 5011.599072074298, 5018.0, 5029.7736601852275, 5039.722815245197, 6464.741410343534, 6527.379898909596, 6607.9669601651685, 6663.666816659051, 6796.490263230178, 6421.935780642134, 6429.903551446639, 6442.484601231984, 6493.967530162319, 6515.804525864231, 6427.0, 6430.1319321892815, 6512.837179070561, 6533.705983230861, 6600.669966501751, 6316.936830631635, 6358.491320694241, 6408.423241908251, 6467.611943283418, 6490.286068532552, 6618.8345291368105, 6637.767678131436, 6653.733850646114, 6656.334366563305, 6675.0, 6257.874842503215, 6284.560080794484, 6307.936920630735, 6338.176037115146, 6348.174737284124], "value": 5063.85001961208, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [907.2243232036609, 907.3376435202306, 917.0, 928.5171710710431, 936.6955739384697, 19179.0, 19832.405027849152, 19859.602807943833, 19977.300794472198, 19981.300654477116, 18499.167537460813, 18499.815001849976, 18618.53453663659, 18662.906685466587, 18684.906575467103, 975.3562648651873, 988.574912787501, 988.9604415823359, 991.8313886639289, 994.6866736977865, 978.4765150644407, 989.8267803134422, 989.9604015839358, 990.0248255468358, 990.420603946692, 980.7352014955958, 1009.6920439266021, 1024.3495380433403, 1026.2816028779846, 1032.5250384822953, 1007.6422869881196, 1011.110223003752, 1011.7521207304221, 1012.5797793915516, 1013.7009582173276, 724.7970568240901, 744.133084956031, 744.4900243333334, 747.439420434674, 751.8947347371372, 19949.9002504988, 19987.300444484372, 20058.899705501524, 20252.291169808985, 20259.594808103902, 20463.89768051165, 20542.589148217103, 22416.88791556048, 22543.436414089614, 22615.321540353816, 17946.282148714035, 18149.818501815076, 18183.45449636513, 18269.45191644253, 18358.357457488924, 3797.696184305249, 3830.157365379612, 3831.3103641344546, 3832.1952389998132, 3833.9424908626393, 108835.0, 141988.29005855008, 142292.28853855768, 143125.28437357745, 143958.0, 17628.647427051386, 17648.470545883643, 17793.0, 17856.46430607084, 17947.910260448614, 30897.69102308993, 31756.364872702416, 31789.52315715266, 31869.20326991844, 31883.202919926724, 9267.67563135287, 9486.098820612031, 9486.667966621135, 9497.762555936157, 9589.808203835884, 4558.749268790182, 4643.791029403685, 4648.767561621973, 4650.95349046512, 4651.534846515389, 5384.91922621161, 5620.594851287178, 5658.85852853682, 5722.914156287659, 5724.998125328051, 1108.141190577309, 1116.213069785799, 1142.092036830705, 1143.7083543696472, 1147.3976162514746, 660.9735610575619, 674.5682763031664, 682.1200651160048, 692.8891377379595, 695.5930780493367, 11486.621605407405, 11898.286102833685, 11902.761944761056, 12046.819297710541, 12421.254724716546, 665.3944910131756, 684.3259389501337, 693.767587858063, 694.4964900447136, 698.0924797762888, 5952.791652292149, 5974.940250597525, 6000.129981152709, 6001.4298641628975, 6007.038873780174, 117196.0, 119185.0, 121897.39051304686, 125168.0, 128320.0, 98634.0, 99023.0, 99490.50254748824, 100122.49938750405, 102177.0, 92701.5364923171, 93083.53458232665, 94400.0, 96541.51729241309, 96618.51690741502, 10997.0, 10997.780044399227, 11082.722931926766, 11178.720531986766, 11323.547058117583, 43771.34342984794, 44479.77760111179, 44574.777126115456, 44637.553624464636, 44895.55104448914, 59436.40563594309, 59527.702361487914, 59531.404685954316, 60258.09612855723, 61219.0, 15129.773053403987, 15234.619134521725, 15470.5358839235, 15568.766468503209, 15709.685806283582, 17902.373416930346, 18112.275508979492, 18138.365157219436, 18207.726884097014, 18419.0, 16457.0, 16657.167141642625, 16793.664126717154, 16935.66128677395, 16986.15069246508, 16287.755683664518, 16368.754468683217, 16369.263383147549, 16559.503214903572, 16601.50195494137, 14362.281885905872, 14778.18719970412, 14831.629209269422, 14832.925835370754, 14887.255637217879, 11397.430128493708, 11606.593769218036, 11652.126090543412, 11896.583619573274, 12006.339651318913, 12154.817677734663, 12275.0, 12333.383330833602, 12396.75206495847, 12698.555550555686], "value": 9080.811789133277, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1426.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 107.0, 109.0, 107.0, 107.0, 107.0, 110.0, 110.0, 108.0, 109.0], "value": 108.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 122.0, 116.0, 117.0, 117.0, 119.0, 116.0, 154.0, 117.0, 119.0], "value": 121.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 162.0, 164.0, 164.0, 164.0, 163.0, 168.0, 167.0, 164.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 77.0, 74.0, 75.0, 74.0, 74.0, 75.0, 77.0, 74.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 80.0, 83.0, 83.0, 78.0, 83.0, 81.0, 83.0], "value": 81.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 113.0, 114.0, 114.0, 114.0, 115.0, 114.0, 115.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 149.0, 150.0, 148.0, 150.0, 148.0, 149.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 96.0, 94.0, 94.0, 100.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 54.0, 55.0, 55.0, 55.0, 56.0, 55.0, 53.0, 53.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 38.0, 38.0, 37.0, 37.0, 38.0, 38.0, 38.0, 37.0], "value": 37.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 78.0, 75.0, 72.0, 73.0, 73.0, 73.0, 73.0, 77.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 111.0, 110.0, 110.0, 122.0, 112.0, 111.0, 115.0, 109.0], "value": 113.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 175.0, 170.0, 167.0, 168.0, 173.0, 171.0, 171.0, 172.0, 169.0], "value": 170.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 65.0, 63.0, 73.0, 67.0, 65.0, 70.0, 71.0, 65.0], "value": 67.2, "unit": "score"}]}]} 20:32:34 INFO - Return code: 0 20:32:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:32:34 INFO - # TBPL SUCCESS # 20:32:34 INFO - Running post-action listener: _package_coverage_data 20:32:34 INFO - Running post-action listener: _resource_record_post_action 20:32:34 INFO - [mozharness: 2017-09-24 03:32:34.645455Z] Finished run-tests step (success) 20:32:34 INFO - Running post-run listener: _resource_record_post_run 20:32:34 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 345296896; Read time: 8; Write time: 420792 20:32:34 INFO - TinderboxPrint: CPU usage
13.1% 20:32:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 20:32:34 INFO - TinderboxPrint: I/O write bytes / time
345,296,896 / 420,792 20:32:34 INFO - TinderboxPrint: CPU idle
8,225.2 (86.8%) 20:32:34 INFO - TinderboxPrint: CPU user
1,193.4 (12.6%) 20:32:34 INFO - TinderboxPrint: Swap in / out
0 / 0 20:32:34 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 20504576; Read time: 0; Write time: 4360 20:32:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 20:32:34 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 0; Write bytes: 324792320; Read time: 0; Write time: 416432 20:32:35 INFO - Running post-run listener: _upload_blobber_files 20:32:35 INFO - Blob upload gear active. 20:32:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:32:35 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 20:32:35 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'] 20:32:35 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 20:32:35 INFO - (blobuploader) - INFO - Open directory for files ... 20:32:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:32:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:32:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:32:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:32:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:32:36 INFO - (blobuploader) - INFO - Done attempting. 20:32:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:32:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:32:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:32:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:32:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:32:43 INFO - (blobuploader) - INFO - Done attempting. 20:32:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:32:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:32:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:32:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:32:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:32:44 INFO - (blobuploader) - INFO - Done attempting. 20:32:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:32:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:32:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:32:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:32:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:32:45 INFO - (blobuploader) - INFO - Done attempting. 20:32:45 INFO - (blobuploader) - INFO - Iteration through files over. 20:32:45 INFO - Return code: 0 20:32:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:32:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:32:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46a9d8ec913d3245e5dd311734bcc2886788f231560c2aeb3e6e2f2858f37329c8fbfca5ebd7a7dc9231203caf4db818a21aaa8b32e68697e8de2443cbc87bbe", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4217d92ac0f38d47ee4fa2c718525094146cbc408fcb6942a074a2faae4d139a986630f550f288c0c114e92b1ce45926cf2249ef693b3fc320a72383c568d284", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b44f3ddfcf58aeccf9cd156ed0cf74e194d87574f2f988c5323bd9394e2baf38778a76c4991e2acfd5022bf7a38e53439a73b1ec680aeded4df125a9e291a639", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85e06e47389e972824e36255a794c50915d9391db0f6023015cee47a097fd8de820d6ba594c1a829708d2baec8844fd788102d92de05de2e8f52a9f0e72c5e4e"} 20:32:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:32:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:32:45 INFO - Contents: 20:32:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46a9d8ec913d3245e5dd311734bcc2886788f231560c2aeb3e6e2f2858f37329c8fbfca5ebd7a7dc9231203caf4db818a21aaa8b32e68697e8de2443cbc87bbe", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4217d92ac0f38d47ee4fa2c718525094146cbc408fcb6942a074a2faae4d139a986630f550f288c0c114e92b1ce45926cf2249ef693b3fc320a72383c568d284", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b44f3ddfcf58aeccf9cd156ed0cf74e194d87574f2f988c5323bd9394e2baf38778a76c4991e2acfd5022bf7a38e53439a73b1ec680aeded4df125a9e291a639", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85e06e47389e972824e36255a794c50915d9391db0f6023015cee47a097fd8de820d6ba594c1a829708d2baec8844fd788102d92de05de2e8f52a9f0e72c5e4e"} 20:32:45 INFO - Running post-run listener: copy_logs_to_upload_dir 20:32:45 INFO - Copying logs to upload dir... 20:32:45 INFO - mkdir: /builds/slave/test/build/upload/logs 20:32:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.837927 ========= master_lag: -0.17 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-23 20:32:44.853936) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-09-23 20:32:44.855107) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46a9d8ec913d3245e5dd311734bcc2886788f231560c2aeb3e6e2f2858f37329c8fbfca5ebd7a7dc9231203caf4db818a21aaa8b32e68697e8de2443cbc87bbe", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4217d92ac0f38d47ee4fa2c718525094146cbc408fcb6942a074a2faae4d139a986630f550f288c0c114e92b1ce45926cf2249ef693b3fc320a72383c568d284", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b44f3ddfcf58aeccf9cd156ed0cf74e194d87574f2f988c5323bd9394e2baf38778a76c4991e2acfd5022bf7a38e53439a73b1ec680aeded4df125a9e291a639", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85e06e47389e972824e36255a794c50915d9391db0f6023015cee47a097fd8de820d6ba594c1a829708d2baec8844fd788102d92de05de2e8f52a9f0e72c5e4e"} build_url:https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011410 build_url: 'https://queue.taskcluster.net/v1/task/JuOc-pfvTaqJ1Q-nyQhMGg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/46a9d8ec913d3245e5dd311734bcc2886788f231560c2aeb3e6e2f2858f37329c8fbfca5ebd7a7dc9231203caf4db818a21aaa8b32e68697e8de2443cbc87bbe", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4217d92ac0f38d47ee4fa2c718525094146cbc408fcb6942a074a2faae4d139a986630f550f288c0c114e92b1ce45926cf2249ef693b3fc320a72383c568d284", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b44f3ddfcf58aeccf9cd156ed0cf74e194d87574f2f988c5323bd9394e2baf38778a76c4991e2acfd5022bf7a38e53439a73b1ec680aeded4df125a9e291a639", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/85e06e47389e972824e36255a794c50915d9391db0f6023015cee47a097fd8de820d6ba594c1a829708d2baec8844fd788102d92de05de2e8f52a9f0e72c5e4e"}' ========= master_lag: 1.15 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-09-23 20:32:46.018831) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:32:46.019164) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506221892.825951-988502895 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004581 ========= master_lag: 0.10 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:32:46.124887) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 20:32:46.125192) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 20:32:46.125565) ========= ========= Total master_lag: 1.24 =========