builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-062 starttime: 1506141450.46 results: success (0) revision: 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.462349) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.462847) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.463118) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.481850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.482253) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-22 21:37:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.0M=0s 2017-09-22 21:37:30 (46.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131230 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.630215) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.630523) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018134 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:30.680755) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-22 21:37:30.681122) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 --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 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 --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=a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-22 21:37:30,726 truncating revision to first 12 chars 2017-09-22 21:37:30,726 Setting DEBUG logging. 2017-09-22 21:37:30,726 attempt 1/10 2017-09-22 21:37:30,726 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9d1fcbc2fdd5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-22 21:37:31,469 unpacking tar archive at: mozilla-central-9d1fcbc2fdd5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.034166 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-22 21:37:31.732502) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:31.732847) ========= script_repo_revision: 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:31.733223) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:31.733544) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 21:37:31.753973) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-09-22 21:37:31.754293) ========= /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=a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:37:31 INFO - MultiFileLogger online at 20170922 21:37:31 in /builds/slave/test 21:37:31 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 21:37:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:37:31 INFO - {'append_to_log': False, 21:37:31 INFO - 'base_work_dir': '/builds/slave/test', 21:37:31 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 21:37:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:37:31 INFO - 'branch': 'Firefox-Non-PGO', 21:37:31 INFO - 'buildbot_json_path': 'buildprops.json', 21:37:31 INFO - 'code_coverage': False, 21:37:31 INFO - 'config_files': ('talos/linux_config.py',), 21:37:31 INFO - 'default_actions': ('clobber', 21:37:31 INFO - 'read-buildbot-config', 21:37:31 INFO - 'download-and-extract', 21:37:31 INFO - 'populate-webroot', 21:37:31 INFO - 'create-virtualenv', 21:37:31 INFO - 'install', 21:37:31 INFO - 'setup-mitmproxy', 21:37:31 INFO - 'run-tests'), 21:37:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:37:31 INFO - 'disable_ccov_upload': False, 21:37:31 INFO - 'disable_stylo': False, 21:37:31 INFO - 'download_minidump_stackwalk': True, 21:37:31 INFO - 'download_symbols': 'ondemand', 21:37:31 INFO - 'enable_stylo': False, 21:37:31 INFO - 'enable_webrender': False, 21:37:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 21:37:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:37:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:37:31 INFO - 'gecko_profile': False, 21:37:31 INFO - 'gecko_profile_interval': 0, 21:37:31 INFO - 'installer_path': 'installer.exe', 21:37:31 INFO - 'log_level': 'info', 21:37:31 INFO - 'log_name': 'talos', 21:37:31 INFO - 'log_to_console': True, 21:37:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:37:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:37:31 INFO - 'opt_config_files': (), 21:37:31 INFO - 'pip_index': False, 21:37:31 INFO - 'suite': 'dromaeojs-e10s', 21:37:31 INFO - 'system_bits': '32', 21:37:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:37:31 INFO - 'title': 'talos-linux64-ix-062', 21:37:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:37:31 INFO - 'use_talos_json': True, 21:37:31 INFO - 'verify': 'False', 21:37:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:37:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:37:31 INFO - 'work_dir': 'build'} 21:37:31 INFO - [mozharness: 2017-09-23 04:37:31.950027Z] Running clobber step. 21:37:31 INFO - Running pre-action listener: _resource_record_pre_action 21:37:31 INFO - Running main action method: clobber 21:37:31 INFO - rmtree: /builds/slave/test/build 21:37:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:37:32 INFO - Running post-action listener: _resource_record_post_action 21:37:32 INFO - [mozharness: 2017-09-23 04:37:32.945523Z] Finished clobber step (success) 21:37:32 INFO - [mozharness: 2017-09-23 04:37:32.945621Z] Running read-buildbot-config step. 21:37:32 INFO - Running pre-action listener: _resource_record_pre_action 21:37:32 INFO - Running main action method: read_buildbot_config 21:37:32 INFO - Using buildbot properties: 21:37:32 INFO - { 21:37:32 INFO - "project": "", 21:37:32 INFO - "product": "firefox", 21:37:32 INFO - "who": "ffxbld@noreply.mozilla.org", 21:37:32 INFO - "installer_path": "public/build/target.tar.bz2", 21:37:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 21:37:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 21:37:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 21:37:32 INFO - "stage_platform": "linux64", 21:37:32 INFO - "basedir": "/builds/slave/test", 21:37:32 INFO - "buildnumber": 1172, 21:37:32 INFO - "platform": "ubuntu64_hw", 21:37:32 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:37:32 INFO - "slavebuilddir": "test", 21:37:32 INFO - "taskId": "HBaq4hS4QaebsnSEQjKsCg", 21:37:32 INFO - "branch": "mozilla-central", 21:37:32 INFO - "script_repo_revision": "production", 21:37:32 INFO - "revision": "9d1fcbc2fdd58011903aa1b987264d58dc1a06b5", 21:37:32 INFO - "slavename": "talos-linux64-ix-062", 21:37:32 INFO - "repo_path": "mozilla-central" 21:37:32 INFO - } 21:37:32 INFO - Finding installer, test and symbols from parent task. 21:37:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HBaq4hS4QaebsnSEQjKsCg'}, attempt #1 21:37:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HBaq4hS4QaebsnSEQjKsCg'}, attempt #1 21:37:33 INFO - Task dependencies: VxRwKolgS2WkH9phigpZaw 21:37:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw'}, attempt #1 21:37:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2 21:37:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.test_packages.json 21:37:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.crashreporter-symbols.zip 21:37:34 INFO - Running post-action listener: _resource_record_post_action 21:37:34 INFO - [mozharness: 2017-09-23 04:37:34.250309Z] Finished read-buildbot-config step (success) 21:37:34 INFO - [mozharness: 2017-09-23 04:37:34.250527Z] Running download-and-extract step. 21:37:34 INFO - Running pre-action listener: _resource_record_pre_action 21:37:34 INFO - Running main action method: download_and_extract 21:37:34 INFO - mkdir: /builds/slave/test/build/tests 21:37: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')]} 21:37:34 INFO - trying https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.test_packages.json 21:37:34 INFO - Downloading https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 21:37:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 21:37:34 INFO - Downloaded 989 bytes. 21:37:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 21:37:34 INFO - Using the following test package requirements: 21:37:34 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 21:37:34 INFO - u'common': [u'target.common.tests.zip'], 21:37:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 21:37:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 21:37:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 21:37:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 21:37:34 INFO - u'mozbase': [u'target.common.tests.zip'], 21:37:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 21:37:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 21:37:34 INFO - u'web-platform': [u'target.common.tests.zip', 21:37:34 INFO - u'target.web-platform.tests.tar.gz'], 21:37:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 21:37:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 21:37:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.common.tests.zip 21:37:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:37:34 INFO - Fetch https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.common.tests.zip into memory 21:37:35 INFO - Content-Length response header: 54892836 21:37:35 INFO - Bytes received: 54892836 21:37:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.talos.tests.zip 21:37:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 21:37:46 INFO - Fetch https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.talos.tests.zip into memory 21:37:47 INFO - Content-Length response header: 14513823 21:37:47 INFO - Bytes received: 14513823 21:37:47 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')]} 21:37:47 INFO - trying https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2 21:37:47 INFO - Downloading https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 21:37:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 21:37:48 INFO - Downloaded 61600446 bytes. 21:37:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2 21:37:48 INFO - mkdir: /builds/slave/test/properties 21:37:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:37:48 INFO - Writing to file /builds/slave/test/properties/build_url 21:37:48 INFO - Contents: 21:37:48 INFO - build_url:https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2 21:37:48 INFO - Running post-action listener: _resource_record_post_action 21:37:48 INFO - Running post-action listener: find_tests_for_verification 21:37:48 INFO - Running post-action listener: set_extra_try_arguments 21:37:48 INFO - [mozharness: 2017-09-23 04:37:48.709728Z] Finished download-and-extract step (success) 21:37:48 INFO - [mozharness: 2017-09-23 04:37:48.709828Z] Running populate-webroot step. 21:37:48 INFO - Running pre-action listener: _resource_record_pre_action 21:37:48 INFO - Running main action method: populate_webroot 21:37:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:37:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:48 INFO - 'tests': ['tresize', 21:37:48 INFO - 'tcanvasmark']}, 21:37:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:37:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:48 INFO - 'tests': ['dromaeo_css', 21:37:48 INFO - 'kraken']}, 21:37:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:37:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:37:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:37:48 INFO - 'talos_options': ['--disable-stylo'], 21:37:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:37:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:37:48 INFO - 'tests': ['damp', 'tps']}, 21:37:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:37:48 INFO - 'talos_options': ['--disable-stylo'], 21:37:48 INFO - 'tests': ['damp', 'tps']}, 21:37:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:37:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:48 INFO - 'tests': ['dromaeo_dom']}, 21:37:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:37:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:48 INFO - 'tests': ['basic_compositor_video', 21:37:48 INFO - 'glvideo']}, 21:37:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 21:37:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 21:37:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:37:48 INFO - 'talos_options': ['--disable-stylo'], 21:37:48 INFO - 'tests': ['ts_paint_webext', 21:37:48 INFO - 'tp5o_webext']}, 21:37:48 INFO - 'other-e10s': {'tests': ['a11yr', 21:37:48 INFO - 'ts_paint', 21:37:48 INFO - 'tpaint', 21:37:48 INFO - 'sessionrestore', 21:37:48 INFO - 'sessionrestore_many_windows', 21:37:48 INFO - 'sessionrestore_no_auto_restore', 21:37:48 INFO - 'tabpaint']}, 21:37:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:48 INFO - 'tests': ['a11yr', 21:37:48 INFO - 'ts_paint', 21:37:48 INFO - 'tpaint', 21:37:48 INFO - 'sessionrestore', 21:37:48 INFO - 'sessionrestore_many_windows', 21:37:48 INFO - 'sessionrestore_no_auto_restore', 21:37:48 INFO - 'tabpaint']}, 21:37:48 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 21:37:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 21:37:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:48 INFO - 'tests': ['perf_reftest_singletons']}, 21:37:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:48 INFO - 'tests': ['perf_reftest']}, 21:37:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:37:48 INFO - 'tsvgr_opacity', 21:37:48 INFO - 'tart', 21:37:48 INFO - 'tscrollx', 21:37:48 INFO - 'tsvg_static']}, 21:37:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:37:48 INFO - 'tests': ['tsvgx', 21:37:48 INFO - 'tsvgr_opacity', 21:37:48 INFO - 'tart', 21:37:48 INFO - 'tscrollx', 21:37:48 INFO - 'tsvg_static']}, 21:37:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:37:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:37:48 INFO - 'talos_options': ['--disable-stylo'], 21:37:48 INFO - 'tests': ['tp5o']}, 21:37:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:37:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 21:37:48 INFO - 'talos_options': ['--mitmproxy', 21:37:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:37:48 INFO - '--firstNonBlankPaint'], 21:37:48 INFO - 'tests': ['tp6_google', 21:37:48 INFO - 'tp6_youtube', 21:37:48 INFO - 'tp6_amazon', 21:37:48 INFO - 'tp6_facebook']}, 21:37:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:37:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 21:37:48 INFO - 'talos_options': ['--disable-stylo', 21:37:48 INFO - '--mitmproxy', 21:37:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:37:48 INFO - '--firstNonBlankPaint'], 21:37:48 INFO - 'tests': ['tp6_google', 21:37:48 INFO - 'tp6_youtube', 21:37:48 INFO - 'tp6_amazon', 21:37:48 INFO - 'tp6_facebook']}, 21:37:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:37:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 21:37:48 INFO - 'talos_options': ['--stylo-threads=1', 21:37:48 INFO - '--mitmproxy', 21:37:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:37:48 INFO - '--firstNonBlankPaint'], 21:37:48 INFO - 'tests': ['tp6_google', 21:37:48 INFO - 'tp6_youtube', 21:37:48 INFO - 'tp6_amazon', 21:37:48 INFO - 'tp6_facebook']}, 21:37:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:37:48 INFO - 'talos_options': ['--xperf_path', 21:37:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:37:48 INFO - 'tests': ['tp5n']}, 21:37:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:37:48 INFO - 'talos_options': ['--disable-stylo', 21:37:48 INFO - '--xperf_path', 21:37:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:37:48 INFO - 'tests': ['tp5n']}}} 21:37:48 INFO - Running post-action listener: _resource_record_post_action 21:37:48 INFO - [mozharness: 2017-09-23 04:37:48.725440Z] Finished populate-webroot step (success) 21:37:48 INFO - [mozharness: 2017-09-23 04:37:48.725530Z] Running create-virtualenv step. 21:37:48 INFO - Running pre-action listener: _resource_record_pre_action 21:37:48 INFO - Running main action method: create_virtualenv 21:37:48 INFO - Creating virtualenv /builds/slave/test/build/venv 21:37:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:37:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 21:37:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 21:37:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:37:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:37:49 INFO - Installing distribute.............................................................................................................................................................................................done. 21:37:49 INFO - Installing pip...............done. 21:37:49 INFO - Return code: 0 21:37:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:37:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:37:49 INFO - Reading from file tmpfile_stdout 21:37:49 INFO - Output received: 21:37:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 21:37:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:37:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ed60>, '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 0x1a2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2fef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', '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 21:37:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:37:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:37:49 INFO - Using env: {'DISPLAY': ':0', 21:37:49 INFO - 'HOME': '/home/cltbld', 21:37:49 INFO - 'LANG': 'en_US.UTF-8', 21:37:49 INFO - 'LANGUAGE': 'en_US:en', 21:37:49 INFO - 'LOGNAME': 'cltbld', 21:37:49 INFO - 'MAIL': '/var/mail/cltbld', 21:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:37:49 INFO - 'MOZ_NO_REMOTE': '1', 21:37:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:37:49 INFO - 'NO_EM_RESTART': '1', 21:37:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:37:49 INFO - 'PWD': '/builds/slave/test', 21:37:49 INFO - 'SHELL': '/bin/bash', 21:37:49 INFO - 'SHLVL': '1', 21:37:49 INFO - 'TERM': 'linux', 21:37:49 INFO - 'TMOUT': '86400', 21:37:49 INFO - 'USER': 'cltbld', 21:37:49 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', 21:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:37:49 INFO - '_': '/tools/buildbot/bin/python'} 21:37:51 INFO - Ignoring indexes: http://pypi.python.org/simple/ 21:37:51 INFO - Downloading/unpacking pip>=1.5 21:37:51 INFO - Running setup.py egg_info for package pip 21:37:51 INFO - warning: no files found matching 'pip/cacert.pem' 21:37:51 INFO - warning: no files found matching '*.html' under directory 'docs' 21:37:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:37:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:37:51 INFO - Installing collected packages: pip 21:37:51 INFO - Found existing installation: pip 1.1 21:37:51 INFO - Uninstalling pip: 21:37:51 INFO - Successfully uninstalled pip 21:37:51 INFO - Running setup.py install for pip 21:37:51 INFO - warning: no files found matching 'pip/cacert.pem' 21:37:51 INFO - warning: no files found matching '*.html' under directory 'docs' 21:37:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:37:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:37:51 INFO - Installing pip script to /builds/slave/test/build/venv/bin 21:37:51 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 21:37:51 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 21:37:51 INFO - Successfully installed pip 21:37:51 INFO - Cleaning up... 21:37:52 INFO - Return code: 0 21:37:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:37:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ed60>, '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 0x1a2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2fef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', '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 21:37:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:37:52 INFO - Using env: {'DISPLAY': ':0', 21:37:52 INFO - 'HOME': '/home/cltbld', 21:37:52 INFO - 'LANG': 'en_US.UTF-8', 21:37:52 INFO - 'LANGUAGE': 'en_US:en', 21:37:52 INFO - 'LOGNAME': 'cltbld', 21:37:52 INFO - 'MAIL': '/var/mail/cltbld', 21:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:37:52 INFO - 'MOZ_NO_REMOTE': '1', 21:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:37:52 INFO - 'NO_EM_RESTART': '1', 21:37:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:37:52 INFO - 'PWD': '/builds/slave/test', 21:37:52 INFO - 'SHELL': '/bin/bash', 21:37:52 INFO - 'SHLVL': '1', 21:37:52 INFO - 'TERM': 'linux', 21:37:52 INFO - 'TMOUT': '86400', 21:37:52 INFO - 'USER': 'cltbld', 21:37:52 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', 21:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:37:52 INFO - '_': '/tools/buildbot/bin/python'} 21:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:37:52 INFO - Downloading/unpacking psutil>=3.1.1 21:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:37:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:37:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:37:53 INFO - Installing collected packages: psutil 21:37:53 INFO - Running setup.py install for psutil 21:37:53 INFO - building 'psutil._psutil_linux' extension 21:37:53 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 21:37:53 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 21:37:53 INFO - building 'psutil._psutil_posix' extension 21:37:53 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 21:37:53 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 21:37:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:37:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:37:54 INFO - Successfully installed psutil 21:37:54 INFO - Cleaning up... 21:37:54 INFO - Return code: 0 21:37:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:37:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:37:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:37:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ed60>, '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 0x1a2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2fef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', '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 21:37:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:37:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:37:54 INFO - Using env: {'DISPLAY': ':0', 21:37:54 INFO - 'HOME': '/home/cltbld', 21:37:54 INFO - 'LANG': 'en_US.UTF-8', 21:37:54 INFO - 'LANGUAGE': 'en_US:en', 21:37:54 INFO - 'LOGNAME': 'cltbld', 21:37:54 INFO - 'MAIL': '/var/mail/cltbld', 21:37:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:37:54 INFO - 'MOZ_NO_REMOTE': '1', 21:37:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:37:54 INFO - 'NO_EM_RESTART': '1', 21:37:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:37:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:37:54 INFO - 'PWD': '/builds/slave/test', 21:37:54 INFO - 'SHELL': '/bin/bash', 21:37:54 INFO - 'SHLVL': '1', 21:37:54 INFO - 'TERM': 'linux', 21:37:54 INFO - 'TMOUT': '86400', 21:37:54 INFO - 'USER': 'cltbld', 21:37:54 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', 21:37:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:37:54 INFO - '_': '/tools/buildbot/bin/python'} 21:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:37:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:37:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:37:56 INFO - Installing collected packages: mozsystemmonitor 21:37:56 INFO - Running setup.py install for mozsystemmonitor 21:37:56 INFO - Successfully installed mozsystemmonitor 21:37:56 INFO - Cleaning up... 21:37:56 INFO - Return code: 0 21:37:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:37:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ed60>, '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 0x1a2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2fef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', '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 21:37:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:37:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:37:56 INFO - Using env: {'DISPLAY': ':0', 21:37:56 INFO - 'HOME': '/home/cltbld', 21:37:56 INFO - 'LANG': 'en_US.UTF-8', 21:37:56 INFO - 'LANGUAGE': 'en_US:en', 21:37:56 INFO - 'LOGNAME': 'cltbld', 21:37:56 INFO - 'MAIL': '/var/mail/cltbld', 21:37:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:37:56 INFO - 'MOZ_NO_REMOTE': '1', 21:37:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:37:56 INFO - 'NO_EM_RESTART': '1', 21:37:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:37:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:37:56 INFO - 'PWD': '/builds/slave/test', 21:37:56 INFO - 'SHELL': '/bin/bash', 21:37:56 INFO - 'SHLVL': '1', 21:37:56 INFO - 'TERM': 'linux', 21:37:56 INFO - 'TMOUT': '86400', 21:37:56 INFO - 'USER': 'cltbld', 21:37:56 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', 21:37:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:37:56 INFO - '_': '/tools/buildbot/bin/python'} 21:37:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:37:56 INFO - Downloading/unpacking jsonschema==2.5.1 21:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:37:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:57 INFO - Downloading functools32-3.2.3-2.tar.gz 21:37:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:37:57 INFO - warning: no files found matching '*.txt' 21:37:57 INFO - no previously-included directories found matching 'build' 21:37:57 INFO - no previously-included directories found matching 'dist' 21:37:57 INFO - no previously-included directories found matching '.git*' 21:37:58 INFO - Installing collected packages: jsonschema, functools32 21:37:58 INFO - Running setup.py install for functools32 21:37:58 INFO - warning: no files found matching '*.txt' 21:37:58 INFO - no previously-included directories found matching 'build' 21:37:58 INFO - no previously-included directories found matching 'dist' 21:37:58 INFO - no previously-included directories found matching '.git*' 21:37:58 INFO - Successfully installed jsonschema functools32 21:37:58 INFO - Cleaning up... 21:37:58 INFO - Return code: 0 21:37:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:37:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ed60>, '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 0x1a2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2fef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', '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 21:37:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:37:58 INFO - Using env: {'DISPLAY': ':0', 21:37:58 INFO - 'HOME': '/home/cltbld', 21:37:58 INFO - 'LANG': 'en_US.UTF-8', 21:37:58 INFO - 'LANGUAGE': 'en_US:en', 21:37:58 INFO - 'LOGNAME': 'cltbld', 21:37:58 INFO - 'MAIL': '/var/mail/cltbld', 21:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:37:58 INFO - 'MOZ_NO_REMOTE': '1', 21:37:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:37:58 INFO - 'NO_EM_RESTART': '1', 21:37:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:37:58 INFO - 'PWD': '/builds/slave/test', 21:37:58 INFO - 'SHELL': '/bin/bash', 21:37:58 INFO - 'SHLVL': '1', 21:37:58 INFO - 'TERM': 'linux', 21:37:58 INFO - 'TMOUT': '86400', 21:37:58 INFO - 'USER': 'cltbld', 21:37:58 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', 21:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:37:58 INFO - '_': '/tools/buildbot/bin/python'} 21:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:37:58 INFO - Cleaning up... 21:37:58 INFO - Return code: 0 21:37:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:37:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x16c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ed60>, '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 0x1a2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2fef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', '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 21:37:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:37:58 INFO - Using env: {'DISPLAY': ':0', 21:37:58 INFO - 'HOME': '/home/cltbld', 21:37:58 INFO - 'LANG': 'en_US.UTF-8', 21:37:58 INFO - 'LANGUAGE': 'en_US:en', 21:37:58 INFO - 'LOGNAME': 'cltbld', 21:37:58 INFO - 'MAIL': '/var/mail/cltbld', 21:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:37:58 INFO - 'MOZ_NO_REMOTE': '1', 21:37:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:37:58 INFO - 'NO_EM_RESTART': '1', 21:37:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:37:58 INFO - 'PWD': '/builds/slave/test', 21:37:58 INFO - 'SHELL': '/bin/bash', 21:37:58 INFO - 'SHLVL': '1', 21:37:58 INFO - 'TERM': 'linux', 21:37:58 INFO - 'TMOUT': '86400', 21:37:58 INFO - 'USER': 'cltbld', 21:37:58 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', 21:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:37:58 INFO - '_': '/tools/buildbot/bin/python'} 21:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:37:58 INFO - Downloading/unpacking blobuploader==1.2.4 21:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:59 INFO - Downloading blobuploader-1.2.4.tar.gz 21:37:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:37:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:38:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:38:00 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 21:38:00 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 21:38:00 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 21:38:00 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 21:38:00 INFO - Downloading docopt-0.6.1.tar.gz 21:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:38:00 INFO - Installing collected packages: blobuploader, requests, docopt 21:38:00 INFO - Running setup.py install for blobuploader 21:38:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:38:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:38:00 INFO - Running setup.py install for requests 21:38:00 INFO - Running setup.py install for docopt 21:38:00 INFO - Successfully installed blobuploader requests docopt 21:38:00 INFO - Cleaning up... 21:38:00 INFO - Return code: 0 21:38:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:00 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 0x16c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ed60>, '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 0x1a2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2fef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', '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 21:38:00 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 21:38:00 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 21:38:00 INFO - Using env: {'DISPLAY': ':0', 21:38:00 INFO - 'HOME': '/home/cltbld', 21:38:00 INFO - 'LANG': 'en_US.UTF-8', 21:38:00 INFO - 'LANGUAGE': 'en_US:en', 21:38:00 INFO - 'LOGNAME': 'cltbld', 21:38:00 INFO - 'MAIL': '/var/mail/cltbld', 21:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:00 INFO - 'MOZ_NO_REMOTE': '1', 21:38:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:00 INFO - 'NO_EM_RESTART': '1', 21:38:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:00 INFO - 'PWD': '/builds/slave/test', 21:38:00 INFO - 'SHELL': '/bin/bash', 21:38:00 INFO - 'SHLVL': '1', 21:38:00 INFO - 'TERM': 'linux', 21:38:00 INFO - 'TMOUT': '86400', 21:38:00 INFO - 'USER': 'cltbld', 21:38:00 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', 21:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:00 INFO - '_': '/tools/buildbot/bin/python'} 21:38:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:38:01 INFO - Running setup.py (path:/tmp/pip-umuobf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:38:01 INFO - Running setup.py (path:/tmp/pip-GLdYcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:38:01 INFO - Running setup.py (path:/tmp/pip-zvIPuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:38:01 INFO - Running setup.py (path:/tmp/pip-7ohLe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:38:01 INFO - Running setup.py (path:/tmp/pip-7n9leC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:38:01 INFO - Running setup.py (path:/tmp/pip-4ERDNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:38:01 INFO - Running setup.py (path:/tmp/pip-LXFGFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:38:01 INFO - Running setup.py (path:/tmp/pip-HmbCUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:38:01 INFO - Running setup.py (path:/tmp/pip-Ms7cYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:38:01 INFO - Running setup.py (path:/tmp/pip-QhxuAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:38:01 INFO - Running setup.py (path:/tmp/pip-kzQ9D6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:38:01 INFO - Running setup.py (path:/tmp/pip-XKceTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:38:02 INFO - Running setup.py (path:/tmp/pip-cL7ED0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:38:02 INFO - Running setup.py (path:/tmp/pip-FSOO2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:38:02 INFO - Running setup.py (path:/tmp/pip-XWgXzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:38:02 INFO - Running setup.py (path:/tmp/pip-11hATn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:38:02 INFO - Running setup.py (path:/tmp/pip-CRkjvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:38:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:38:02 INFO - Running setup.py install for manifestparser 21:38:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:38:02 INFO - Running setup.py install for mozcrash 21:38:02 INFO - Running setup.py install for mozdebug 21:38:02 INFO - Running setup.py install for mozdevice 21:38:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:38:02 INFO - Running setup.py install for mozfile 21:38:02 INFO - Running setup.py install for mozhttpd 21:38:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:38:02 INFO - Running setup.py install for mozinfo 21:38:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:38:03 INFO - Running setup.py install for mozInstall 21:38:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:38:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:38:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:38:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:38:03 INFO - Running setup.py install for mozleak 21:38:03 INFO - Running setup.py install for mozlog 21:38:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:38:03 INFO - Running setup.py install for moznetwork 21:38:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:38:03 INFO - Running setup.py install for mozprocess 21:38:03 INFO - Running setup.py install for mozprofile 21:38:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:38:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:38:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:38:03 INFO - Running setup.py install for mozrunner 21:38:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:38:03 INFO - Running setup.py install for mozscreenshot 21:38:03 INFO - Running setup.py install for moztest 21:38:04 INFO - Running setup.py install for mozversion 21:38:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:38:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:38:04 INFO - Cleaning up... 21:38:04 INFO - Return code: 0 21:38:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:38: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')]} 21:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38: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')]} 21:38:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:04 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 0x16c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ed60>, '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 0x1a2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2fef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', '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 21:38:04 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 21:38:04 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 21:38:04 INFO - Using env: {'DISPLAY': ':0', 21:38:04 INFO - 'HOME': '/home/cltbld', 21:38:04 INFO - 'LANG': 'en_US.UTF-8', 21:38:04 INFO - 'LANGUAGE': 'en_US:en', 21:38:04 INFO - 'LOGNAME': 'cltbld', 21:38:04 INFO - 'MAIL': '/var/mail/cltbld', 21:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:04 INFO - 'MOZ_NO_REMOTE': '1', 21:38:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:04 INFO - 'NO_EM_RESTART': '1', 21:38:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:04 INFO - 'PWD': '/builds/slave/test', 21:38:04 INFO - 'SHELL': '/bin/bash', 21:38:04 INFO - 'SHLVL': '1', 21:38:04 INFO - 'TERM': 'linux', 21:38:04 INFO - 'TMOUT': '86400', 21:38:04 INFO - 'USER': 'cltbld', 21:38:04 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', 21:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:04 INFO - '_': '/tools/buildbot/bin/python'} 21:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:38:04 INFO - Running setup.py (path:/tmp/pip-5naLwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:38:04 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)) 21:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:38:04 INFO - Running setup.py (path:/tmp/pip-sQSeyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:38:04 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)) 21:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:38:04 INFO - Running setup.py (path:/tmp/pip-u93f2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:38:04 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)) 21:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:38:04 INFO - Running setup.py (path:/tmp/pip-hf_tsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:38:04 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)) 21:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:38:04 INFO - Running setup.py (path:/tmp/pip-gy5Dnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:38:04 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)) 21:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:38:04 INFO - Running setup.py (path:/tmp/pip-FCgW1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:38:04 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)) 21:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:38:04 INFO - Running setup.py (path:/tmp/pip-XOvsUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:38:04 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)) 21:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:38:04 INFO - Running setup.py (path:/tmp/pip-z3swq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:38:04 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)) 21:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:38:04 INFO - Running setup.py (path:/tmp/pip-c81_be-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:38:05 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)) 21:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:38:05 INFO - Running setup.py (path:/tmp/pip-uWXbJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:38:05 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)) 21:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:38:05 INFO - Running setup.py (path:/tmp/pip-ATgTPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:38:05 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)) 21:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:38:05 INFO - Running setup.py (path:/tmp/pip-dIuBaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:38:05 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)) 21:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:38:05 INFO - Running setup.py (path:/tmp/pip-eRqnxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:38:05 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)) 21:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:38:05 INFO - Running setup.py (path:/tmp/pip-Fhh2t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:38:05 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)) 21:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:38:05 INFO - Running setup.py (path:/tmp/pip-08eyxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:38:05 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)) 21:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:38:05 INFO - Running setup.py (path:/tmp/pip-ebJF2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:38:05 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)) 21:38:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:38:05 INFO - Running setup.py (path:/tmp/pip-bJEazZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:38:05 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)) 21:38:05 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)) 21:38:05 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)) 21:38:05 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)) 21:38:05 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)) 21:38:05 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)) 21:38:05 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)) 21:38:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:06 INFO - Downloading blessings-1.6.tar.gz 21:38:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:38:06 INFO - Installing collected packages: blessings 21:38:06 INFO - Running setup.py install for blessings 21:38:06 INFO - Successfully installed blessings 21:38:06 INFO - Cleaning up... 21:38:06 INFO - Return code: 0 21:38:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:38:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:38:07 INFO - Reading from file tmpfile_stdout 21:38:07 INFO - Current package versions: 21:38:07 INFO - argparse == 1.2.1 21:38:07 INFO - blessings == 1.6 21:38:07 INFO - blobuploader == 1.2.4 21:38:07 INFO - docopt == 0.6.1 21:38:07 INFO - functools32 == 3.2.3-2 21:38:07 INFO - jsonschema == 2.5.1 21:38:07 INFO - manifestparser == 1.1 21:38:07 INFO - mozInstall == 1.13 21:38:07 INFO - mozcrash == 1.0 21:38:07 INFO - mozdebug == 0.1 21:38:07 INFO - mozdevice == 0.51 21:38:07 INFO - mozfile == 1.2 21:38:07 INFO - mozhttpd == 0.7 21:38:07 INFO - mozinfo == 0.10 21:38:07 INFO - mozleak == 0.1 21:38:07 INFO - mozlog == 3.5 21:38:07 INFO - moznetwork == 0.27 21:38:07 INFO - mozprocess == 0.25 21:38:07 INFO - mozprofile == 0.28 21:38:07 INFO - mozrunner == 6.13 21:38:07 INFO - mozscreenshot == 0.1 21:38:07 INFO - mozsystemmonitor == 0.3 21:38:07 INFO - moztest == 0.8 21:38:07 INFO - mozversion == 1.4 21:38:07 INFO - psutil == 3.1.1 21:38:07 INFO - requests == 1.2.3 21:38:07 INFO - wsgiref == 0.1.2 21:38:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:38:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:07 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 0x16c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ed60>, '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 0x1a2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2fef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', '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 21:38:07 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 21:38:07 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 21:38:07 INFO - Using env: {'DISPLAY': ':0', 21:38:07 INFO - 'HOME': '/home/cltbld', 21:38:07 INFO - 'LANG': 'en_US.UTF-8', 21:38:07 INFO - 'LANGUAGE': 'en_US:en', 21:38:07 INFO - 'LOGNAME': 'cltbld', 21:38:07 INFO - 'MAIL': '/var/mail/cltbld', 21:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:07 INFO - 'MOZ_NO_REMOTE': '1', 21:38:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:07 INFO - 'NO_EM_RESTART': '1', 21:38:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:07 INFO - 'PWD': '/builds/slave/test', 21:38:07 INFO - 'SHELL': '/bin/bash', 21:38:07 INFO - 'SHLVL': '1', 21:38:07 INFO - 'TERM': 'linux', 21:38:07 INFO - 'TMOUT': '86400', 21:38:07 INFO - 'USER': 'cltbld', 21:38:07 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', 21:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:07 INFO - '_': '/tools/buildbot/bin/python'} 21:38:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:07 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)) 21:38:07 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)) 21:38:07 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)) 21:38:07 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)) 21:38:07 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)) 21:38:07 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)) 21:38:07 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)) 21:38:07 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)) 21:38:07 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)) 21:38:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 21:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:38:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 21:38:08 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)) 21:38:08 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)) 21:38:08 INFO - Installing collected packages: simplejson 21:38:08 INFO - Running setup.py install for simplejson 21:38:08 INFO - building 'simplejson._speedups' extension 21:38:08 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 21:38:09 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 21:38:09 INFO - Successfully installed simplejson 21:38:09 INFO - Cleaning up... 21:38:09 INFO - Return code: 0 21:38:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:38:09 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')]} 21:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:38:09 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')]} 21:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:38:09 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 0x16c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x188cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2ed60>, '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 0x1a2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2fef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', '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 21:38:09 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 21:38:09 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 21:38:09 INFO - Using env: {'DISPLAY': ':0', 21:38:09 INFO - 'HOME': '/home/cltbld', 21:38:09 INFO - 'LANG': 'en_US.UTF-8', 21:38:09 INFO - 'LANGUAGE': 'en_US:en', 21:38:09 INFO - 'LOGNAME': 'cltbld', 21:38:09 INFO - 'MAIL': '/var/mail/cltbld', 21:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:09 INFO - 'MOZ_NO_REMOTE': '1', 21:38:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:09 INFO - 'NO_EM_RESTART': '1', 21:38:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:09 INFO - 'PWD': '/builds/slave/test', 21:38:09 INFO - 'SHELL': '/bin/bash', 21:38:09 INFO - 'SHLVL': '1', 21:38:09 INFO - 'TERM': 'linux', 21:38:09 INFO - 'TMOUT': '86400', 21:38:09 INFO - 'USER': 'cltbld', 21:38:09 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', 21:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:09 INFO - '_': '/tools/buildbot/bin/python'} 21:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:38:09 INFO - Cleaning up... 21:38:09 INFO - Return code: 0 21:38:09 INFO - Running post-action listener: _resource_record_post_action 21:38:09 INFO - Running post-action listener: _start_resource_monitoring 21:38:09 INFO - Starting resource monitoring. 21:38:09 INFO - [mozharness: 2017-09-23 04:38:09.942762Z] Finished create-virtualenv step (success) 21:38:09 INFO - [mozharness: 2017-09-23 04:38:09.943354Z] Running install step. 21:38:09 INFO - Running pre-action listener: _resource_record_pre_action 21:38:09 INFO - Running main action method: install 21:38:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:38:10 INFO - Reading from file tmpfile_stdout 21:38:10 INFO - Detecting whether we're running mozinstall >=1.0... 21:38:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:38:10 INFO - Reading from file tmpfile_stdout 21:38:10 INFO - Output received: 21:38:10 INFO - Usage: mozinstall [options] installer 21:38:10 INFO - Options: 21:38:10 INFO - -h, --help show this help message and exit 21:38:10 INFO - -d DEST, --destination=DEST 21:38:10 INFO - Directory to install application into. [default: 21:38:10 INFO - "/builds/slave/test"] 21:38:10 INFO - --app=APP Application being installed. [default: firefox] 21:38:10 INFO - mkdir: /builds/slave/test/build/application 21:38:10 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'] 21:38:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 21:38:28 INFO - Reading from file tmpfile_stdout 21:38:28 INFO - Output received: 21:38:28 INFO - /builds/slave/test/build/application/firefox/firefox 21:38:28 INFO - Running post-action listener: _resource_record_post_action 21:38:28 INFO - [mozharness: 2017-09-23 04:38:28.334660Z] Finished install step (success) 21:38:28 INFO - [mozharness: 2017-09-23 04:38:28.334883Z] Running setup-mitmproxy step. 21:38:28 INFO - Running pre-action listener: _resource_record_pre_action 21:38:28 INFO - Running main action method: setup_mitmproxy 21:38:28 INFO - Skipping: mitmproxy is not required 21:38:28 INFO - Running post-action listener: _resource_record_post_action 21:38:28 INFO - [mozharness: 2017-09-23 04:38:28.335420Z] Finished setup-mitmproxy step (success) 21:38:28 INFO - [mozharness: 2017-09-23 04:38:28.335524Z] Running run-tests step. 21:38:28 INFO - Running pre-action listener: _resource_record_pre_action 21:38:28 INFO - Running pre-action listener: _set_gcov_prefix 21:38:28 INFO - Running main action method: run_tests 21:38:28 WARNING - Try message not found. 21:38:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:38:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:38:28 INFO - Python 2.7.3 21:38:28 INFO - Return code: 0 21:38:28 INFO - grabbing minidump binary from tooltool 21:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:38:28 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 0x1a2ed60>, '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 0x1a2fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a2fef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:38:28 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 21:38:28 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 21:38:28 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 21:38:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:38:28 INFO - Return code: 0 21:38:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:38:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:38:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:38:28 INFO - ENV: RUST_BACKTRACE is now full 21:38:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:38:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:38:28 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/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 21:38:28 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/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 21:38:28 INFO - Using env: {'DISPLAY': ':0', 21:38:28 INFO - 'HOME': '/home/cltbld', 21:38:28 INFO - 'LANG': 'en_US.UTF-8', 21:38:28 INFO - 'LANGUAGE': 'en_US:en', 21:38:28 INFO - 'LOGNAME': 'cltbld', 21:38:28 INFO - 'MAIL': '/var/mail/cltbld', 21:38:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:38:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:28 INFO - 'MOZ_NO_REMOTE': '1', 21:38:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:38:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:28 INFO - 'NO_EM_RESTART': '1', 21:38:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:28 INFO - 'PWD': '/builds/slave/test', 21:38:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:38:28 INFO - 'RUST_BACKTRACE': 'full', 21:38:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:38:28 INFO - 'SHELL': '/bin/bash', 21:38:28 INFO - 'SHLVL': '1', 21:38:28 INFO - 'TERM': 'linux', 21:38:28 INFO - 'TMOUT': '86400', 21:38:28 INFO - 'USER': 'cltbld', 21:38:28 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', 21:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:28 INFO - '_': '/tools/buildbot/bin/python'} 21:38:28 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/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 21:38:28 INFO - mozversion application_buildid: 20170923034137 21:38:28 INFO - mozversion application_changeset: 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 21:38:28 INFO - mozversion application_display_name: Nightly 21:38:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:38:28 INFO - mozversion application_name: Firefox 21:38:28 INFO - mozversion application_remotingname: firefox 21:38:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:38:28 INFO - mozversion application_vendor: Mozilla 21:38:28 INFO - mozversion application_version: 58.0a1 21:38:28 INFO - mozversion platform_buildid: 20170923034137 21:38:28 INFO - mozversion platform_changeset: 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 21:38:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:38:28 INFO - mozversion platform_version: 58.0a1 21:38:28 INFO - using testdate: 1506141508 21:38:28 INFO - actual date: 1506141508 21:38:28 INFO - starting webserver on 'localhost:39373' 21:38:28 INFO - SUITE-START | Running 2 tests 21:38:28 INFO - TEST-START | dromaeo_css 21:38:28 INFO - Initialising browser for dromaeo_css test... 21:38:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39373/getInfo.html -profile /tmp/tmpTFCoNz/profile 21:38:28 INFO - TEST-INFO | started process 5536 (/builds/slave/test/build/application/firefox/firefox http://localhost:39373/getInfo.html) 21:38:33 INFO - TEST-INFO | 5536: exit 0 21:38:33 INFO - Browser initialized. 21:38:33 INFO - Running cycle 1/1 for dromaeo_css test... 21:38:33 INFO - Using env: {'DISPLAY': ':0', 21:38:33 INFO - 'HOME': '/home/cltbld', 21:38:33 INFO - 'JSGC_DISABLE_POISONING': '1', 21:38:33 INFO - 'LANG': 'en_US.UTF-8', 21:38:33 INFO - 'LANGUAGE': 'en_US:en', 21:38:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:38:33 INFO - 'LOGNAME': 'cltbld', 21:38:33 INFO - 'MAIL': '/var/mail/cltbld', 21:38:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:38:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:38:33 INFO - 'MOZ_CRASHREPORTER': '1', 21:38:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:38:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:38:33 INFO - 'MOZ_NO_REMOTE': '1', 21:38:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:38:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:38:33 INFO - 'NO_EM_RESTART': '1', 21:38:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:38:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:38:33 INFO - 'PWD': '/builds/slave/test', 21:38:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:38:33 INFO - 'RUST_BACKTRACE': 'full', 21:38:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:38:33 INFO - 'SHELL': '/bin/bash', 21:38:33 INFO - 'SHLVL': '1', 21:38:33 INFO - 'TERM': 'linux', 21:38:33 INFO - 'TMOUT': '86400', 21:38:33 INFO - 'USER': 'cltbld', 21:38:33 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', 21:38:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:38:33 INFO - '_': '/tools/buildbot/bin/python'} 21:38:33 INFO - TEST-INFO | started process 5768 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTFCoNz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:38:34 INFO - PID 5768 | 21:38:34 INFO - PID 5768 | (/builds/slave/test/build/application/firefox/firefox:5818): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:38:34 INFO - PID 5768 | 21:38:44 INFO - PID 5768 | RSS: Main: 153374720 21:38:44 INFO - PID 5768 | 21:41:42 INFO - PID 5768 | Cycle 1(1): loaded http://localhost:39373/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39373/tests/dromaeo/cssquery-ext.html) 21:41:43 INFO - PID 5768 | RSS: Main: 153862144 21:41:43 INFO - PID 5768 | 21:44:45 INFO - PID 5768 | Cycle 1(1): loaded http://localhost:39373/tests/dromaeo/cssquery-ext.html (next: http://localhost:39373/tests/dromaeo/cssquery-jquery.html) 21:44:45 INFO - PID 5768 | RSS: Main: 152625152 21:44:45 INFO - PID 5768 | 21:47:47 INFO - PID 5768 | Cycle 1(1): loaded http://localhost:39373/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39373/tests/dromaeo/cssquery-mootools.html) 21:47:48 INFO - PID 5768 | RSS: Main: 155901952 21:47:48 INFO - PID 5768 | 21:50:49 INFO - PID 5768 | Cycle 1(1): loaded http://localhost:39373/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39373/tests/dromaeo/cssquery-prototype.html) 21:50:50 INFO - PID 5768 | RSS: Main: 153833472 21:50:50 INFO - PID 5768 | 21:53:53 INFO - PID 5768 | Cycle 1(1): loaded http://localhost:39373/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39373/tests/dromaeo/cssquery-yui.html) 21:53:54 INFO - PID 5768 | RSS: Main: 155598848 21:53:54 INFO - PID 5768 | 21:56:56 INFO - PID 5768 | Cycle 1(1): loaded http://localhost:39373/tests/dromaeo/cssquery-yui.html (next: http://localhost:39373/tests/dromaeo/cssquery-dojo.html) 21:56:56 INFO - PID 5768 | RSS: Main: 155951104 21:56:56 INFO - PID 5768 | 21:56:56 INFO - PID 5768 | __start_tp_report 21:56:56 INFO - PID 5768 | _x_x_mozilla_page_load 21:56:56 INFO - PID 5768 | _x_x_mozilla_page_load_details 21:56:56 INFO - PID 5768 | |i|pagename|runs| 21:56:56 INFO - PID 5768 | |0;dojo.html;3079.8614062367196;3124.328269422074;3143.32418530016;3155.9211019724517;3156.747460203184;6238.4697300729495;6279.434850863421;6290;6319.304876463591;6404.42360187583;6233.1273621693;6352.682365881699;6396.712147953353;6423.421892029716;6425.389587989145;5735.827925162252;5774.3936886626925;5797.072468405056;5866.237389139427;5919.23050003499;5648.435156484339;5691.658500489963;5820.505257053155;5851.356350801409;5881.705914704247;5782.3350314713925;5813.418658134174;5816.069428891357;5817.331006934214;5831.533477321804;5651.2935883014625;5703.686297253649;5729.226554415184;5795.652260864362;5863.120531920204;5682.943170568324;5729.226554415142;5733.799317023926;5811.564132690023;5821.27234095738;6321.209848768904;6408.647524386157;6419.486441084702;6441.677916104176;6471.061696054093;6682.532222744409;6718.697658605374;6785.966070169667;6787.457003439713;6807.0810440590385;6557.114789503404;6589.044588534635;6768.221654509745;6787.660616969181;6861;6248.625082495065;6258.968705156491;6270.404311590437;6331.335209802996;6353.777617783356;6831.726730930806;6996.020557122007;7003.474739394565;7029.121359830021;7032.296770322927;6625.741109189239;6629.966850165815;6718.428933540704;6723;6745.730170793211;6564.8358791030605;6656.966715166393;6681.164854393201;6706.865862682719;6713.2279787824045;6741.8651626967185;6745.831354216184;6751.594904305757;6764.661766911635;6784.050232967388;6721;6728.831779205559;6798.93201067983;6809.182898052265;6816.386525212754;5231.738413079331;5295.629305948547;5306;5344.946550534522;5347.625666203328;4545.431821022372;4584.404027476407;4629.3518907353;4633.930491042637;4666.533346665374;5837.29952405714;5853.034249348895;5863.384344643836;5863.648181109147;5870.7945221917025;6733.3266673333255;6734.831629209309;6739.0902228198565;6763;6830.931690683129;5718.542516598662;5801.7099145042575;5849.298084229919;5857.560682948796;5874.970625146932;5680.7727690892725;5696.715164241771;5770.942290577041;5801.390853960358;5824.388439213906;6335.271443783931;6455.644939528277;6528.053432252392;6545.181852268466;6548.8035358939305;6660.10088638047;6693.431058359899;6734.764283250062;6743.629100399525;6815.284395138439;6461.741530338733;6523.347665233438;6563.04835798816;6599.043138744951;6627.37039981201;6327.96836015817;6391.456726178327;6406.263279722705;6523.739050437929;6685.465162787063;6644.534882558174;6695.163104611924;6706.027625994301;6709.463242940651;6815.761448349284;6580.243272023778;6619.702113405005;6633.668316584248;6638.037484564615;6644.534882558174;6546.705398257185;6550.574212676161;6551.148350714377;6559.704813283318;6621.1392518972225;6324.6521441321165;6465.709043093166;6470.967645161744;6471.417572418505;6546.607203567801;6535.215774107138;6566.8358291043105;6580;6625.734970601122;6654.134962455044;6158.476529494864;6260.906086408617;6372.330905254881;6461.903071454026;6462 21:56:56 INFO - PID 5768 | |1;ext.html;879.2131042716769;904.5974541329111;905.1310741687979;909.1999040844056;910.9954450227748;39314.60685393145;42912.57087429125;43303.133937321276;43324.35013474796;43580.12839743203;39874.40188397176;39976.20047599046;40019.19961600766;40394.19211615766;40444.79777601115;7261.927380726192;7348.889766653505;7379.151397589292;7405.481616286862;7417.147028091757;7315.5244909081;7329.853402931938;7376.7786966391095;7385.889211661829;7424.851502969965;7347.63261836906;7946.0464744230485;8232.341412687001;8244.258016778489;8245.134260902609;8134.959325203366;8212.507249564986;8213.383996200278;8222;8223.301019413297;7880.605969701549;8022.558759268238;8063.032436107646;8066.637001334954;8079.919200807975;39403.60596394028;39906.40140397896;39958.60041399607;39988.40017399741;40170.79914600408;3555.928881422357;3599.5680518337704;3608.1340478285283;3608.4046132388125;3611.133328001261;32845.835770820995;32892.50661240083;32908.50637240443;32934.50598241029;32960.1759956003;5230.241614965808;5322.1484562470205;5334.1732031535175;5339.359276886759;5346.518813306821;196010;196959;197124;199089;199156;44005.33991990123;44713.329300060526;49541.50458495441;50144.74927625339;50203.497965020615;52567.21149182766;52802.471975280525;53034.46965530373;53063;53068.20397694038;16103.919480402757;16408.917955410387;16415.91792041032;16518.6696266074;16583.6683266334;13407.262600557064;13414.463421463031;13532.052756306965;13539.593812185452;13669.521566745116;14244.430222790972;14447.133172009713;14447.422103115969;14556.053856499293;14612.85387146115;5796.565257605696;5839.357670656224;5876.3536011039605;5947.613405128653;5990.580659353804;1015.8476228565627;1027.157730660851;1034.1468288661883;1038.5222797513077;1041.8749750030045;23043;23098.53802923932;23194.65208021881;23228.070877164722;23241.53516929652;1116.1684545014016;1118.5917140243914;1126.183516950221;1129.3788416370962;1137.1073707139856;12271.202371845802;12374.257544547356;12456.501739930483;12460.563880264148;12529.43617537213;230623;231668;232697;232843;232944;53132;53146.468535314925;53259.73370133124;53299;53385.7330713344;1450.4995776457106;1470.3456961651962;1472.992635036818;1473.9363702955775;1475.3950880139091;22695.886520567292;22746.317610471713;22853.542929141655;22907.427314317276;22913.88543057274;38050.2389952205;38088.238235235694;38099.80950095232;38122.42816357812;38149;195673;196087;196143;196168;196497;31483.84258078695;31806;34271.14322141966;35005;35012;44013.11973760443;44853.55146448494;45028.549714502435;45211.773941130086;45399.546004540854;32155.19612009625;32276;32314.192145196557;32947.67052329446;33533.49699754457;26626.600600991387;26975.730242697322;27581.172564823086;27739.861300693367;27831.721682783722;36419.27161456809;36521.81739091394;36779.63220367762;36941.07647308839;36973.81513092417;31128.844355778077;31831;31899.68100318967;31986.6801331993;32834.50748238731;22967.58329085067;23019.66879894725;23614.88192559095;23742.525149497258;23823.761762382153 21:56:56 INFO - PID 5768 | |2;jquery.html;558.5950186115065;585.306411901896;591.5858898770862;603.4207161125319;609.7073404765713;22173;22608.886955565198;22671;22685.886570567145;22922.312330630088;22807.201747938827;23339.416514587145;25093.372665683368;25297.494050118985;25590.360240993985;6309.558330916838;6507.51193660475;6523.836904077389;6542.836429089287;6570.441512471446;6228.314885362606;6431.935680643181;6471.514636402265;6508.54440189187;6518.543701940866;6467.126937863399;6473.708683109271;6486.643234622094;6537.673116344164;6542.476601871839;6274.623522588659;6530.412262896362;6544.051112588696;6547.116139321179;6548.8035358939305;6050.6369617823075;6092.939070609282;6102.237220347457;6102.511799056065;6104.4505994460715;28635.713642863513;29086;29303.267418314714;29332.853335733398;29377.265568360748;2046.92835750748;2055.506678397174;2067.9793202068086;2079.386580958621;2079.9688004679942;19138;19641.80358196414;20456.590868182702;20682.793172068235;20788.272410465714;2473.9010439582576;2501.7873480754156;2537.1246919812634;2539.0986199898953;2543.2751665775163;122889;128543.99999999907;136326;138810.30594847063;145235;14947.757836324638;14963.850361496246;14985.475508357154;15172.620684482976;15387.692246155239;23710.644340334908;23757.524849502912;23809.166679166145;23998.640020399707;24092.277231683078;10194.694159175237;10249.231307651957;10286.948565257275;10309.587616495406;10513.369197848153;9436.339456238135;9521.28590355726;9556.76108097303;9674.322797404255;9690.418574885529;9166.129217724305;9284.353959121618;9378.765530861647;9463.148316651534;9515.904840951502;2789.860506974643;2825.8587070646795;2854.372038151603;2872.1814282929263;2872.7989040767366;943.42451104826;967.9467629280457;975.3952549419264;977.3158788847835;985.4235272365603;19531.51171220731;19558.11988460523;19614.803851961296;19777.604447910962;20149.59700806005;1034.9793004139874;1045.1116550931647;1045.6131231444415;1049.3546468921559;1058.5606973106228;10778.514966826511;10836.349819010884;10850.240483166102;10935.343879367263;11044.613438529614;479046;482495;490169;497139;500599;111288.44355778169;111639;114442;114740.42629786798;115248.42375788235;1154.9133814963911;1187.8693343732182;1240.621610408816;1256.1583738894963;1267.7781388257017;20992.160313587287;21173;21193.470163246042;21310.467238319165;21564;57497;57934.4206557929;58346;59664.403355965886;60402.39597604144;73751.63124184345;74439.2556074454;74659.88010179743;74771.25228747643;75037.24962750303;14275.785863211853;14926;14962.17708026069;15063;15079.924600376928;20888.1644734215;22119.66820497726;22483;22536.323910282717;22566.774332256467;17160.22778974974;17175.7423638643;17406.216720247874;17477.300907963538;17595.20821563007;15595.922020389826;15892.364305427653;16391.836081639507;16409.83590164131;16514.66970660604;18072.277108916023;18712.90643546828;18823.152958117193;18884.716729248797;19002.714959275898;14717.411303547738;14774.926125369304;14806.111633302036;14872.851271487147;14892.925535372255;17315.740263896303;17554.824451755318;17596.29614815393;18055.638887222445;18102.81897181064 21:56:56 INFO - PID 5768 | |3;mootools.html;1278.2522224498668;1369.246914197192;1382.5161193582253;1385.549696348687;1386.7018591002932;15688.45090421834;16169.35322587098;16194.838051619481;16201.513954581353;16222.188890555455;15781.368745250176;15822.129782861937;15858;15918.204089795521;15927.601809954756;2774.9861250693716;2810.466011457827;2815.746582807547;2821.8730157142877;2823.167165686117;2759.489494443524;2780.8609569521545;2805.270629636289;2811.90158344458;2831.957520637192;2717.0897749254023;2835.943281134376;2842.0052981456533;2851.272925404019;2857.7999540032206;2794.1058861164433;2812.1282402455304;2816.605675205473;2821.7742580593504;2830.957535636967;2730.3993121513236;2749.587561865716;2768.404792969501;2769.1830909881633;2812.4937511247967;14987.32557034936;15124.168170750605;15215;15288.847111528965;15345.30946107428;8174.591270436455;8494.532800695959;8498.320134389296;8518.914810851937;8559.186877246651;5372;5679.261695979496;5697.515711164556;5716.742746576372;5819.098039803837;1887.2168050259127;1945.2899691612602;1945.4260993007097;1967.1246295398553;1993.2425678242175;28220.43559128806;28274.858625706944;28355.71644283572;28486.715132848614;28583.428331433464;6681.966590167018;6871.725130994804;6937.306269373023;7008.159020917421;7040.295970402919;17731.73402398965;17743.467695969142;17756.55608609795;17825.197866096056;17847.28610855577;8049.315808156255;8209.712660056988;8214.05538363096;8219.630116644765;8235.423520353517;7456.962715186498;7477.401807855358;7523.473356864967;7527.435442341846;7546.434017448713;5315.176147697113;5346.224797404432;5372.059889519319;5423.403425623178;5461.972690136524;1402.754517959353;1437.1233547535994;1566.3343079191297;1617.579429348378;1629.6007478167924;876.7939534209455;878.2139984713646;887.2281115429489;890.4879694175875;899.5052721003552;7309.744158954518;7334.816629584303;7346.33882950537;7364.410847132217;7431.85136297271;945.5083356654501;973.1193270090579;984.6110786239395;993.4635296939572;1005.9597616095274;4409.162259170747;4466.531014243523;4468.486124095705;4479.529649386833;4606.700564463299;20665.89667051655;21386;21408.143674253166;21480.57038859214;21583.460413489473;20554.794452055587;21194.78805211959;21239.89380053121;21353.14587416486;21417.57164856694;11233.550657973752;11243.269187502787;11467.139964502696;11485.02377297939;11559.537618495335;8507.404481686222;8562.100979397073;8572.013195390677;8662.393632445921;8764;17544.736828947833;18278.725819112457;18352.082395879886;18714.625707485502;18843.434696959113;18582.349617763313;18728.906355468134;19083.61832763365;19208.615827683647;19505;10571.04860562553;10719.785604287714;10735.570577176826;10750;10857.67426977192;13570.117942333716;13755.656108597364;13778.517751878637;13794.448222071005;13918.234497102756;11186.496607652512;11402.20184587104;11524.078073753912;11618.244814087057;11754.76490470203;10927.125829933428;11156.274842135235;11210.83183752228;11239.775204495701;11509.654710358367;9963.701088967342;10045.593616893786;10102;10116.393016419039;10132.392056476636;7596.430267730053;7613.390928725799;7672.271134242238;7677.193894640982;7678.616069196406;7217.891731624135;7358.190599033995;7410.555366678017;7428.888566671612;7466.178720340867 21:56:56 INFO - PID 5768 | |4;prototype.html;502.44981744989235;504.1731560241207;523.9030779305829;535.7642637239614;541.842865568985;6140.942784167304;6212.5961812482155;6263.749450021994;6278.968605156968;6315.40003699649;5993;6057.303410107839;6125.2941970108;6129.908051379233;6214;4984.177610694231;5028.220625803006;5033.169527028036;5041.395032596093;5050.823221187259;4910.901781964359;5039.697618142924;5059.949400505985;5061.772220250097;5064.455341120956;5004.674696144757;5012.248162775594;5013.197888337867;5046.520580544842;5087.414947281054;4946.3817022872145;4958.975205123951;4995.975020124876;5008.6744361616375;5060.54455099041;4495.865124046284;4538.954610453886;4554.517787805654;4579.107074120548;4580.748058856761;6104.633721976696;6178.289496707846;6214.130021796952;6218.689065546704;6245.313015568284;5677.233573467571;5721.656700597978;5763.6541807491685;5776.6534007959235;5790.392008839095;6550;6595.680863827206;6612;6633.867322653568;6665.833354166136;4972.129877271499;4986.307394007232;5026.045051440251;5027.271045698353;5027.371578552681;6823.93176068238;6832.760853370109;6868.519203655746;6899.517033807636;6937.7224911003495;4999.175136102575;5079.415867175248;5085.703145697823;5094.337736094284;5096.847094587094;5076.289319495199;5106.4382917879;5113.53978141969;5136;5151.201563757623;5634.154876768517;5682;5686.289213848269;5767.826965191051;5775;6267.185265915493;6298.590591611531;6393.1688880445245;6456.225252969673;6478.967605162038;1706.1895599590343;1735.4186347573452;1740.1647209339587;1748.606563523197;1754.798198207197;1053.3363980692116;1227.9570215042431;1229.2255878796302;1230.698478872682;1240.9875901241053;5573.832785016456;5630.817528319017;5651.660900345993;5683.772649094072;5763.452472015152;6537.182852143482;6566.997065894852;6596.340365963461;6621.933780662228;6657.700403481855;5681.971590142023;5709.571782116358;5772.134179872969;5779.68812475005;5779.88440231193;3551.3785087609567;3560.1099725068734;3565.35823551758;3597.190632107755;3613.855445782192;6477.611343319416;6487.870242595121;6492.474268546887;6496.881496881429;6609.834754131185;6787.762428314985;6792.660366981631;6799.388055075067;6851.623160726108;6932.896006559906;6531.791618550584;6565.767639002762;6584.242812076577;6612.86774264512;6639.070530125786;6640.402363787282;6679.632620205741;6680.5323627345615;6688.531802773759;6745.696443660146;6526.552542796451;6636.502262330441;6644.90032649501;6667.366600172976;6691.498137639599;6491.545591808717;6497.8700425992165;6542.247641521096;6575.375339342755;6577.6053436793945;5024.296598476143;5047.671901326402;5108.3103780989095;5109.5912327013;5120.436751957207;6317;6463.644499552382;6601.933980660324;6645.767398141235;6679.165104361955;6329.841753956188;6381.8723625528155;6412.9358706414205;6560.343965603402;6574.671266436563;6395.552311338161;6447.2263328400895;6477.255115661759;6541.266564360353;6592.90110648331;6376.649284289409;6393.29673735898;6403.327650596807;6482.120185146443;6502.967485162733;6577.704003319958;6644.368784965421;6672.228522328248;6672.399484046459;6690.933090669031;6325.367463253822;6372.36276372359;6392.52056095804;6403.359664033559;6439.195100612424 21:56:56 INFO - PID 5768 | |5;yui.html;895.6506962284706;900.2797761790567;935.1630290889653;936.1293996583179;944.5135755086129;18919.148638311293;19528.609427811436;19542.41372758816;19567.804321956777;19664.90167549162;16286.51140465785;16305.755413668774;16383.42658006967;18297.634047319047;19025.238990440364;963.8457846744504;967.042627798478;978.4814048554277;979.2508730820928;980.3725615606018;994.5077186792512;998.4708104704482;998.9850152247722;1002.6290272599113;1002.9648962286321;991.2862738828032;1040.859483969662;1048.8426735989624;1049.826778581533;1049.8320268757;988.4415305352525;1034.4052170002242;1035.461559988809;1039.3504059962524;1040.7398150462384;742.4654248940755;744.6276861569215;750.5196674128562;751.7143485475539;753.0323534258506;19937.900310498353;20002.599948001105;20166.69749953752;20169.193232270693;20296.289629863033;21487.462813429633;21930.780692193035;22336.664950025763;22469.21357752487;22599.661005084938;16683.49949501517;16883.15584220796;16984.74522882158;17139.57151071221;17302.480925572254;3740.1023754299044;3771.7925514096987;3776.9622303776887;3789.5452545694693;3795.430685397185;128575.35712321472;128805;133927;137558.31220843832;138452;16449.753253701205;16571.751423728652;16783.83216167847;16875.662486750196;17143.142842857807;30485.542716859265;30948.535771963438;31008.0697579073;31206;31588.210294742355;8464.44981076228;8546.273566746897;8593.484390936565;8615.61229744663;8628.482291062557;4220.07158425146;4266.146770645822;4336.934945975813;4345.739255644671;4369.541198174209;5411.458854114557;5428.511433970962;5502.064649009678;5514.338279406416;5538.113901775697;1024.3495380433478;1043.9739006524899;1058.7406085509099;1060.7295139739317;1062.9202809789297;662.2681936460239;682.8941514065327;688.2979361051799;690.2028157478121;700.4221517248291;11264.267822591506;11494.885051149382;11787.528498859949;11952.282863028073;12067.034637229;665.710415969055;674.6626686656672;675.9831004224934;691.1360799001249;694.198201077756;5348.839534813884;5410.053240682943;5466.398696143421;5508.6970216637665;5667.1499275108235;115872;120515;121453;122025.38987305007;124555.3772231133;108468.4576577112;111351;111674;112151.43924280489;112527.4373628143;93403;93666;95064;96390.55414168793;99550;10001.199904007808;10049.14582260516;10050.547725352522;10087.092161705188;10114.898851011396;43542;44140.11719765651;44872.32691509695;45779;46048.539514604425;58871;59145.40854591399;60689.69655151696;60903;61527.692361537906;15315.004524705859;15425;15465.304061317043;15677.137757422995;15696;16826.831731683014;17216.139193040548;17229;17257.309707611555;17524.123793810515;15518;15784.526464206092;15905.204739763198;15992.92003539975;16000.279987400827;15962.840371596136;16032.919835400748;16121.274542645375;16155.838441615433;16178.595535111246;13518.188908665512;13600.251986140462;13608.183508989492;13681;13733.107348022346;11948.880511194777;11972.880271197526;11991.82012269834;12149.210301330386;12186.634400967985;10471.162307015267;10481.475926203811;10803.513841877291;10864;11009.724756881142 21:56:56 INFO - PID 5768 | __end_tp_report 21:56:56 INFO - PID 5768 | __start_cc_report 21:56:56 INFO - PID 5768 | _x_x_mozilla_cycle_collect,298 21:56:56 INFO - PID 5768 | __end_cc_report 21:56:56 INFO - PID 5768 | __startTimestamp1506142616477__endTimestamp 21:56:56 INFO - PID 5768 | 21:56:56 INFO - PID 5768 | ------- Summary: start ------- 21:56:56 INFO - PID 5768 | Number of tests: 6 21:56:56 INFO - PID 5768 | 21:56:56 INFO - PID 5768 | [#0] dojo.html Cycles:165 Average:6193.80 Median:6455.64 stddev:745.98 (11.6%) stddev-sans-first:707.14 21:56:56 INFO - PID 5768 | Values: 3079.9 3124.3 3143.3 3155.9 3156.7 6238.5 6279.4 6290.0 6319.3 6404.4 6233.1 6352.7 6396.7 6423.4 6425.4 5735.8 5774.4 5797.1 5866.2 5919.2 5648.4 5691.7 5820.5 5851.4 5881.7 5782.3 5813.4 5816.1 5817.3 5831.5 5651.3 5703.7 5729.2 5795.7 5863.1 5682.9 5729.2 5733.8 5811.6 5821.3 6321.2 6408.6 6419.5 6441.7 6471.1 6682.5 6718.7 6786.0 6787.5 6807.1 6557.1 6589.0 6768.2 6787.7 6861.0 6248.6 6259.0 6270.4 6331.3 6353.8 6831.7 6996.0 7003.5 7029.1 7032.3 6625.7 6630.0 6718.4 6723.0 6745.7 6564.8 6657.0 6681.2 6706.9 6713.2 6741.9 6745.8 6751.6 6764.7 6784.1 6721.0 6728.8 6798.9 6809.2 6816.4 5231.7 5295.6 5306.0 5344.9 5347.6 4545.4 4584.4 4629.4 4633.9 4666.5 5837.3 5853.0 5863.4 5863.6 5870.8 6733.3 6734.8 6739.1 6763.0 6830.9 5718.5 5801.7 5849.3 5857.6 5875.0 5680.8 5696.7 5770.9 5801.4 5824.4 6335.3 6455.6 6528.1 6545.2 6548.8 6660.1 6693.4 6734.8 6743.6 6815.3 6461.7 6523.3 6563.0 6599.0 6627.4 6328.0 6391.5 6406.3 6523.7 6685.5 6644.5 6695.2 6706.0 6709.5 6815.8 6580.2 6619.7 6633.7 6638.0 6644.5 6546.7 6550.6 6551.1 6559.7 6621.1 6324.7 6465.7 6471.0 6471.4 6546.6 6535.2 6566.8 6580.0 6625.7 6654.1 6158.5 6260.9 6372.3 6461.9 6462.0 21:56:56 INFO - PID 5768 | 21:56:56 INFO - PID 5768 | [#1] ext.html Cycles:180 Average:37914.23 Median:3577.75 stddev:54228.59 (1515.7%) stddev-sans-first:54309.42 21:56:56 INFO - PID 5768 | Values: 879.2 904.6 905.1 909.2 911.0 39314.6 42912.6 43303.1 43324.4 43580.1 39874.4 39976.2 40019.2 40394.2 40444.8 7261.9 7348.9 7379.2 7405.5 7417.1 7315.5 7329.9 7376.8 7385.9 7424.9 7347.6 7946.0 8232.3 8244.3 8245.1 8135.0 8212.5 8213.4 8222.0 8223.3 7880.6 8022.6 8063.0 8066.6 8079.9 39403.6 39906.4 39958.6 39988.4 40170.8 3555.9 3599.6 3608.1 3608.4 3611.1 32845.8 32892.5 32908.5 32934.5 32960.2 5230.2 5322.1 5334.2 5339.4 5346.5 196010.0 196959.0 197124.0 199089.0 199156.0 44005.3 44713.3 49541.5 50144.7 50203.5 52567.2 52802.5 53034.5 53063.0 53068.2 16103.9 16408.9 16415.9 16518.7 16583.7 13407.3 13414.5 13532.1 13539.6 13669.5 14244.4 14447.1 14447.4 14556.1 14612.9 5796.6 5839.4 5876.4 5947.6 5990.6 1015.8 1027.2 1034.1 1038.5 1041.9 23043.0 23098.5 23194.7 23228.1 23241.5 1116.2 1118.6 1126.2 1129.4 1137.1 12271.2 12374.3 12456.5 12460.6 12529.4 230623.0 231668.0 232697.0 232843.0 232944.0 53132.0 53146.5 53259.7 53299.0 53385.7 1450.5 1470.3 1473.0 1473.9 1475.4 22695.9 22746.3 22853.5 22907.4 22913.9 38050.2 38088.2 38099.8 38122.4 38149.0 195673.0 196087.0 196143.0 196168.0 196497.0 31483.8 31806.0 34271.1 35005.0 35012.0 44013.1 44853.6 45028.5 45211.8 45399.5 32155.2 32276.0 32314.2 32947.7 33533.5 26626.6 26975.7 27581.2 27739.9 27831.7 36419.3 36521.8 36779.6 36941.1 36973.8 31128.8 31831.0 31899.7 31986.7 32834.5 22967.6 23019.7 23614.9 23742.5 23823.8 21:56:56 INFO - PID 5768 | 21:56:56 INFO - PID 5768 | [#2] jquery.html Cycles:180 Average:34789.59 Median:22587.83 stddev:82435.60 (365.0%) stddev-sans-first:82626.79 21:56:56 INFO - PID 5768 | Values: 558.6 585.3 591.6 603.4 609.7 22173.0 22608.9 22671.0 22685.9 22922.3 22807.2 23339.4 25093.4 25297.5 25590.4 6309.6 6507.5 6523.8 6542.8 6570.4 6228.3 6431.9 6471.5 6508.5 6518.5 6467.1 6473.7 6486.6 6537.7 6542.5 6274.6 6530.4 6544.1 6547.1 6548.8 6050.6 6092.9 6102.2 6102.5 6104.5 28635.7 29086.0 29303.3 29332.9 29377.3 2046.9 2055.5 2068.0 2079.4 2080.0 19138.0 19641.8 20456.6 20682.8 20788.3 2473.9 2501.8 2537.1 2539.1 2543.3 122889.0 128544.0 136326.0 138810.3 145235.0 14947.8 14963.9 14985.5 15172.6 15387.7 23710.6 23757.5 23809.2 23998.6 24092.3 10194.7 10249.2 10286.9 10309.6 10513.4 9436.3 9521.3 9556.8 9674.3 9690.4 9166.1 9284.4 9378.8 9463.1 9515.9 2789.9 2825.9 2854.4 2872.2 2872.8 943.4 967.9 975.4 977.3 985.4 19531.5 19558.1 19614.8 19777.6 20149.6 1035.0 1045.1 1045.6 1049.4 1058.6 10778.5 10836.3 10850.2 10935.3 11044.6 479046.0 482495.0 490169.0 497139.0 500599.0 111288.4 111639.0 114442.0 114740.4 115248.4 1154.9 1187.9 1240.6 1256.2 1267.8 20992.2 21173.0 21193.5 21310.5 21564.0 57497.0 57934.4 58346.0 59664.4 60402.4 73751.6 74439.3 74659.9 74771.3 75037.2 14275.8 14926.0 14962.2 15063.0 15079.9 20888.2 22119.7 22483.0 22536.3 22566.8 17160.2 17175.7 17406.2 17477.3 17595.2 15595.9 15892.4 16391.8 16409.8 16514.7 18072.3 18712.9 18823.2 18884.7 19002.7 14717.4 14774.9 14806.1 14872.9 14892.9 17315.7 17554.8 17596.3 18055.6 18102.8 21:56:56 INFO - PID 5768 | 21:56:56 INFO - PID 5768 | [#3] mootools.html Cycles:180 Average:9446.96 Median:2772.08 stddev:6806.26 (245.5%) stddev-sans-first:6797.68 21:56:56 INFO - PID 5768 | Values: 1278.3 1369.2 1382.5 1385.5 1386.7 15688.5 16169.4 16194.8 16201.5 16222.2 15781.4 15822.1 15858.0 15918.2 15927.6 2775.0 2810.5 2815.7 2821.9 2823.2 2759.5 2780.9 2805.3 2811.9 2832.0 2717.1 2835.9 2842.0 2851.3 2857.8 2794.1 2812.1 2816.6 2821.8 2831.0 2730.4 2749.6 2768.4 2769.2 2812.5 14987.3 15124.2 15215.0 15288.8 15345.3 8174.6 8494.5 8498.3 8518.9 8559.2 5372.0 5679.3 5697.5 5716.7 5819.1 1887.2 1945.3 1945.4 1967.1 1993.2 28220.4 28274.9 28355.7 28486.7 28583.4 6682.0 6871.7 6937.3 7008.2 7040.3 17731.7 17743.5 17756.6 17825.2 17847.3 8049.3 8209.7 8214.1 8219.6 8235.4 7457.0 7477.4 7523.5 7527.4 7546.4 5315.2 5346.2 5372.1 5423.4 5462.0 1402.8 1437.1 1566.3 1617.6 1629.6 876.8 878.2 887.2 890.5 899.5 7309.7 7334.8 7346.3 7364.4 7431.9 945.5 973.1 984.6 993.5 1006.0 4409.2 4466.5 4468.5 4479.5 4606.7 20665.9 21386.0 21408.1 21480.6 21583.5 20554.8 21194.8 21239.9 21353.1 21417.6 11233.6 11243.3 11467.1 11485.0 11559.5 8507.4 8562.1 8572.0 8662.4 8764.0 17544.7 18278.7 18352.1 18714.6 18843.4 18582.3 18728.9 19083.6 19208.6 19505.0 10571.0 10719.8 10735.6 10750.0 10857.7 13570.1 13755.7 13778.5 13794.4 13918.2 11186.5 11402.2 11524.1 11618.2 11754.8 10927.1 11156.3 11210.8 11239.8 11509.7 9963.7 10045.6 10102.0 10116.4 10132.4 7596.4 7613.4 7672.3 7677.2 7678.6 7217.9 7358.2 7410.6 7428.9 7466.2 21:56:56 INFO - PID 5768 | 21:56:56 INFO - PID 5768 | [#4] prototype.html Cycles:180 Average:5515.10 Median:6159.62 stddev:1533.68 (24.9%) stddev-sans-first:1491.12 21:56:56 INFO - PID 5768 | Values: 502.4 504.2 523.9 535.8 541.8 6140.9 6212.6 6263.7 6279.0 6315.4 5993.0 6057.3 6125.3 6129.9 6214.0 4984.2 5028.2 5033.2 5041.4 5050.8 4910.9 5039.7 5059.9 5061.8 5064.5 5004.7 5012.2 5013.2 5046.5 5087.4 4946.4 4959.0 4996.0 5008.7 5060.5 4495.9 4539.0 4554.5 4579.1 4580.7 6104.6 6178.3 6214.1 6218.7 6245.3 5677.2 5721.7 5763.7 5776.7 5790.4 6550.0 6595.7 6612.0 6633.9 6665.8 4972.1 4986.3 5026.0 5027.3 5027.4 6823.9 6832.8 6868.5 6899.5 6937.7 4999.2 5079.4 5085.7 5094.3 5096.8 5076.3 5106.4 5113.5 5136.0 5151.2 5634.2 5682.0 5686.3 5767.8 5775.0 6267.2 6298.6 6393.2 6456.2 6479.0 1706.2 1735.4 1740.2 1748.6 1754.8 1053.3 1228.0 1229.2 1230.7 1241.0 5573.8 5630.8 5651.7 5683.8 5763.5 6537.2 6567.0 6596.3 6621.9 6657.7 5682.0 5709.6 5772.1 5779.7 5779.9 3551.4 3560.1 3565.4 3597.2 3613.9 6477.6 6487.9 6492.5 6496.9 6609.8 6787.8 6792.7 6799.4 6851.6 6932.9 6531.8 6565.8 6584.2 6612.9 6639.1 6640.4 6679.6 6680.5 6688.5 6745.7 6526.6 6636.5 6644.9 6667.4 6691.5 6491.5 6497.9 6542.2 6575.4 6577.6 5024.3 5047.7 5108.3 5109.6 5120.4 6317.0 6463.6 6601.9 6645.8 6679.2 6329.8 6381.9 6412.9 6560.3 6574.7 6395.6 6447.2 6477.3 6541.3 6592.9 6376.6 6393.3 6403.3 6482.1 6503.0 6577.7 6644.4 6672.2 6672.4 6690.9 6325.4 6372.4 6392.5 6403.4 6439.2 21:56:56 INFO - PID 5768 | 21:56:56 INFO - PID 5768 | [#5] yui.html Cycles:180 Average:24129.56 Median:19276.92 stddev:34925.72 (181.2%) stddev-sans-first:34980.12 21:56:56 INFO - PID 5768 | Values: 895.7 900.3 935.2 936.1 944.5 18919.1 19528.6 19542.4 19567.8 19664.9 16286.5 16305.8 16383.4 18297.6 19025.2 963.8 967.0 978.5 979.3 980.4 994.5 998.5 999.0 1002.6 1003.0 991.3 1040.9 1048.8 1049.8 1049.8 988.4 1034.4 1035.5 1039.4 1040.7 742.5 744.6 750.5 751.7 753.0 19937.9 20002.6 20166.7 20169.2 20296.3 21487.5 21930.8 22336.7 22469.2 22599.7 16683.5 16883.2 16984.7 17139.6 17302.5 3740.1 3771.8 3777.0 3789.5 3795.4 128575.4 128805.0 133927.0 137558.3 138452.0 16449.8 16571.8 16783.8 16875.7 17143.1 30485.5 30948.5 31008.1 31206.0 31588.2 8464.4 8546.3 8593.5 8615.6 8628.5 4220.1 4266.1 4336.9 4345.7 4369.5 5411.5 5428.5 5502.1 5514.3 5538.1 1024.3 1044.0 1058.7 1060.7 1062.9 662.3 682.9 688.3 690.2 700.4 11264.3 11494.9 11787.5 11952.3 12067.0 665.7 674.7 676.0 691.1 694.2 5348.8 5410.1 5466.4 5508.7 5667.1 115872.0 120515.0 121453.0 122025.4 124555.4 108468.5 111351.0 111674.0 112151.4 112527.4 93403.0 93666.0 95064.0 96390.6 99550.0 10001.2 10049.1 10050.5 10087.1 10114.9 43542.0 44140.1 44872.3 45779.0 46048.5 58871.0 59145.4 60689.7 60903.0 61527.7 15315.0 15425.0 15465.3 15677.1 15696.0 16826.8 17216.1 17229.0 17257.3 17524.1 15518.0 15784.5 15905.2 15992.9 16000.3 15962.8 16032.9 16121.3 16155.8 16178.6 13518.2 13600.3 13608.2 13681.0 13733.1 11948.9 11972.9 11991.8 12149.2 12186.6 10471.2 10481.5 10803.5 10864.0 11009.7 21:56:56 INFO - PID 5768 | -------- Summary: end -------- 21:56:56 INFO - PID 5768 | 21:56:57 INFO - TEST-INFO | 5768: exit 0 21:56:57 INFO - TEST-OK | dromaeo_css | took 1108443ms 21:56:57 INFO - TEST-START | kraken 21:56:57 INFO - Initialising browser for kraken test... 21:56:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39373/getInfo.html -profile /tmp/tmp26UTuD/profile 21:56:57 INFO - TEST-INFO | started process 6136 (/builds/slave/test/build/application/firefox/firefox http://localhost:39373/getInfo.html) 21:57:01 INFO - TEST-INFO | 6136: exit 0 21:57:01 INFO - Browser initialized. 21:57:01 INFO - Running cycle 1/1 for kraken test... 21:57:01 INFO - Using env: {'DISPLAY': ':0', 21:57:01 INFO - 'HOME': '/home/cltbld', 21:57:01 INFO - 'JSGC_DISABLE_POISONING': '1', 21:57:01 INFO - 'LANG': 'en_US.UTF-8', 21:57:01 INFO - 'LANGUAGE': 'en_US:en', 21:57:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:57:01 INFO - 'LOGNAME': 'cltbld', 21:57:01 INFO - 'MAIL': '/var/mail/cltbld', 21:57:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:57:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:57:01 INFO - 'MOZ_CRASHREPORTER': '1', 21:57:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:57:01 INFO - 'MOZ_NO_REMOTE': '1', 21:57:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:57:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:57:01 INFO - 'NO_EM_RESTART': '1', 21:57:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:57:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:01 INFO - 'PWD': '/builds/slave/test', 21:57:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:57:01 INFO - 'RUST_BACKTRACE': 'full', 21:57:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:57:01 INFO - 'SHELL': '/bin/bash', 21:57:01 INFO - 'SHLVL': '1', 21:57:01 INFO - 'TERM': 'linux', 21:57:01 INFO - 'TMOUT': '86400', 21:57:01 INFO - 'USER': 'cltbld', 21:57:01 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825', 21:57:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:01 INFO - '_': '/tools/buildbot/bin/python'} 21:57:01 INFO - TEST-INFO | started process 6368 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp26UTuD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:57:02 INFO - PID 6368 | 21:57:02 INFO - PID 6368 | (/builds/slave/test/build/application/firefox/firefox:6418): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:57:02 INFO - PID 6368 | 21:57:12 INFO - PID 6368 | RSS: Main: 153288704 21:57:12 INFO - PID 6368 | 21:57:15 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39373/tests/kraken/driver.html?testName=audio-beat-detection) 21:57:16 INFO - PID 6368 | RSS: Main: 158949376 21:57:16 INFO - PID 6368 | 21:57:19 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39373/tests/kraken/driver.html?testName=audio-dft) 21:57:19 INFO - PID 6368 | RSS: Main: 159170560 21:57:19 INFO - PID 6368 | 21:57:22 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39373/tests/kraken/driver.html?testName=audio-fft) 21:57:23 INFO - PID 6368 | RSS: Main: 156217344 21:57:23 INFO - PID 6368 | 21:57:25 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39373/tests/kraken/driver.html?testName=audio-oscillator) 21:57:26 INFO - PID 6368 | RSS: Main: 156426240 21:57:26 INFO - PID 6368 | 21:57:28 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39373/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:57:28 INFO - PID 6368 | RSS: Main: 156696576 21:57:28 INFO - PID 6368 | 21:57:32 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39373/tests/kraken/driver.html?testName=imaging-darkroom) 21:57:32 INFO - PID 6368 | RSS: Main: 158990336 21:57:32 INFO - PID 6368 | 21:57:36 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39373/tests/kraken/driver.html?testName=imaging-desaturate) 21:57:36 INFO - PID 6368 | RSS: Main: 159629312 21:57:36 INFO - PID 6368 | 21:57:39 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39373/tests/kraken/driver.html?testName=json-parse-financial) 21:57:40 INFO - PID 6368 | RSS: Main: 159637504 21:57:40 INFO - PID 6368 | 21:57:42 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39373/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:57:42 INFO - PID 6368 | RSS: Main: 159539200 21:57:42 INFO - PID 6368 | 21:57:44 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39373/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:57:45 INFO - PID 6368 | RSS: Main: 159649792 21:57:45 INFO - PID 6368 | 21:57:47 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39373/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:57:47 INFO - PID 6368 | RSS: Main: 156311552 21:57:47 INFO - PID 6368 | 21:57:50 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39373/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:57:51 INFO - PID 6368 | RSS: Main: 156753920 21:57:51 INFO - PID 6368 | 21:57:53 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39373/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:57:54 INFO - PID 6368 | RSS: Main: 156708864 21:57:54 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | Cycle 1(1): loaded http://localhost:39373/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39373/tests/kraken/driver.html?testName=ai-astar) 21:57:56 INFO - PID 6368 | RSS: Main: 158044160 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | __start_tp_report 21:57:56 INFO - PID 6368 | _x_x_mozilla_page_load 21:57:56 INFO - PID 6368 | _x_x_mozilla_page_load_details 21:57:56 INFO - PID 6368 | |i|pagename|runs| 21:57:56 INFO - PID 6368 | |0;ai-astar;107;107;108;108;108;109;112;109;108;108 21:57:56 INFO - PID 6368 | |1;audio-beat-detection;118;118;117;117;118;122;117;118;131;117 21:57:56 INFO - PID 6368 | |2;audio-dft;165;167;164;163;166;165;165;166;168;167 21:57:56 INFO - PID 6368 | |3;audio-fft;74;77;76;75;74;82;74;73;74;74 21:57:56 INFO - PID 6368 | |4;audio-oscillator;80;80;80;78;80;79;80;80;80;80 21:57:56 INFO - PID 6368 | |5;imaging-gaussian-blur;115;114;114;115;114;114;117;115;114;114 21:57:56 INFO - PID 6368 | |6;imaging-darkroom;149;148;148;149;150;149;150;150;149;150 21:57:56 INFO - PID 6368 | |7;imaging-desaturate;101;95;94;95;94;94;95;94;95;94 21:57:56 INFO - PID 6368 | |8;json-parse-financial;54;56;53;54;53;51;54;52;53;53 21:57:56 INFO - PID 6368 | |9;json-stringify-tinderbox;38;38;39;39;38;38;38;39;38;38 21:57:56 INFO - PID 6368 | |10;stanford-crypto-aes;79;76;76;82;75;78;74;76;75;74 21:57:56 INFO - PID 6368 | |11;stanford-crypto-ccm;114;107;110;113;112;117;128;111;112;110 21:57:56 INFO - PID 6368 | |12;stanford-crypto-pbkdf2;176;172;171;169;170;178;174;170;171;170 21:57:56 INFO - PID 6368 | |13;stanford-crypto-sha256-iterative;68;64;67;63;74;75;66;72;65;68 21:57:56 INFO - PID 6368 | __end_tp_report 21:57:56 INFO - PID 6368 | __start_cc_report 21:57:56 INFO - PID 6368 | _x_x_mozilla_cycle_collect,740 21:57:56 INFO - PID 6368 | __end_cc_report 21:57:56 INFO - PID 6368 | __startTimestamp1506142676639__endTimestamp 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | ------- Summary: start ------- 21:57:56 INFO - PID 6368 | Number of tests: 14 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#0] ai-astar Cycles:10 Average:108.40 Median:108.00 stddev:1.43 (1.3%) stddev-sans-first:1.42 21:57:56 INFO - PID 6368 | Values: 107.0 107.0 108.0 108.0 108.0 109.0 112.0 109.0 108.0 108.0 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#1] audio-beat-detection Cycles:10 Average:119.30 Median:118.00 stddev:4.37 (3.7%) stddev-sans-first:4.61 21:57:56 INFO - PID 6368 | Values: 118.0 118.0 117.0 117.0 118.0 122.0 117.0 118.0 131.0 117.0 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#2] audio-dft Cycles:10 Average:165.60 Median:166.00 stddev:1.51 (0.9%) stddev-sans-first:1.58 21:57:56 INFO - PID 6368 | Values: 165.0 167.0 164.0 163.0 166.0 165.0 165.0 166.0 168.0 167.0 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#3] audio-fft Cycles:10 Average:75.30 Median:74.50 stddev:2.63 (3.5%) stddev-sans-first:2.74 21:57:56 INFO - PID 6368 | Values: 74.0 77.0 76.0 75.0 74.0 82.0 74.0 73.0 74.0 74.0 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#4] audio-oscillator Cycles:10 Average:79.70 Median:80.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 21:57:56 INFO - PID 6368 | Values: 80.0 80.0 80.0 78.0 80.0 79.0 80.0 80.0 80.0 80.0 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:114.50 stddev:0.97 (0.8%) stddev-sans-first:1.01 21:57:56 INFO - PID 6368 | Values: 115.0 114.0 114.0 115.0 114.0 114.0 117.0 115.0 114.0 114.0 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.50 stddev:0.79 (0.5%) stddev-sans-first:0.83 21:57:56 INFO - PID 6368 | Values: 149.0 148.0 148.0 149.0 150.0 149.0 150.0 150.0 149.0 150.0 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:94.50 stddev:2.13 (2.3%) stddev-sans-first:0.53 21:57:56 INFO - PID 6368 | Values: 101.0 95.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 94.0 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.50 stddev:1.34 (2.5%) stddev-sans-first:1.39 21:57:56 INFO - PID 6368 | Values: 54.0 56.0 53.0 54.0 53.0 51.0 54.0 52.0 53.0 53.0 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.00 stddev:0.48 (1.3%) stddev-sans-first:0.50 21:57:56 INFO - PID 6368 | Values: 38.0 38.0 39.0 39.0 38.0 38.0 38.0 39.0 38.0 38.0 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#10] stanford-crypto-aes Cycles:10 Average:76.50 Median:76.00 stddev:2.51 (3.3%) stddev-sans-first:2.49 21:57:56 INFO - PID 6368 | Values: 79.0 76.0 76.0 82.0 75.0 78.0 74.0 76.0 75.0 74.0 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#11] stanford-crypto-ccm Cycles:10 Average:113.40 Median:112.50 stddev:5.78 (5.1%) stddev-sans-first:6.12 21:57:56 INFO - PID 6368 | Values: 114.0 107.0 110.0 113.0 112.0 117.0 128.0 111.0 112.0 110.0 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.10 Median:171.50 stddev:2.96 (1.7%) stddev-sans-first:2.78 21:57:56 INFO - PID 6368 | Values: 176.0 172.0 171.0 169.0 170.0 178.0 174.0 170.0 171.0 170.0 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:4.16 (6.1%) stddev-sans-first:4.41 21:57:56 INFO - PID 6368 | Values: 68.0 64.0 67.0 63.0 74.0 75.0 66.0 72.0 65.0 68.0 21:57:56 INFO - PID 6368 | -------- Summary: end -------- 21:57:56 INFO - PID 6368 | 21:57:56 INFO - PID 6368 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 21:57:57 INFO - TEST-INFO | 6368: exit 0 21:57:57 INFO - TEST-OK | kraken | took 60118ms 21:57:57 INFO - SUITE-END | took 1168s 21:57:57 INFO - Completed test suite (00:19:28) 21:57:57 INFO - javascript benchmark 21:57:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8541.936136368215, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3079.8614062367196, 3124.328269422074, 3143.32418530016, 3155.9211019724517, 3156.747460203184, 6238.4697300729495, 6279.434850863421, 6290.0, 6319.304876463591, 6404.42360187583, 6233.1273621693, 6352.682365881699, 6396.712147953353, 6423.421892029716, 6425.389587989145, 5735.827925162252, 5774.3936886626925, 5797.072468405056, 5866.237389139427, 5919.23050003499, 5648.435156484339, 5691.658500489963, 5820.505257053155, 5851.356350801409, 5881.705914704247, 5782.3350314713925, 5813.418658134174, 5816.069428891357, 5817.331006934214, 5831.533477321804, 5651.2935883014625, 5703.686297253649, 5729.226554415184, 5795.652260864362, 5863.120531920204, 5682.943170568324, 5729.226554415142, 5733.799317023926, 5811.564132690023, 5821.27234095738, 6321.209848768904, 6408.647524386157, 6419.486441084702, 6441.677916104176, 6471.061696054093, 6682.532222744409, 6718.697658605374, 6785.966070169667, 6787.457003439713, 6807.0810440590385, 6557.114789503404, 6589.044588534635, 6768.221654509745, 6787.660616969181, 6861.0, 6248.625082495065, 6258.968705156491, 6270.404311590437, 6331.335209802996, 6353.777617783356, 6831.726730930806, 6996.020557122007, 7003.474739394565, 7029.121359830021, 7032.296770322927, 6625.741109189239, 6629.966850165815, 6718.428933540704, 6723.0, 6745.730170793211, 6564.8358791030605, 6656.966715166393, 6681.164854393201, 6706.865862682719, 6713.2279787824045, 6741.8651626967185, 6745.831354216184, 6751.594904305757, 6764.661766911635, 6784.050232967388, 6721.0, 6728.831779205559, 6798.93201067983, 6809.182898052265, 6816.386525212754, 5231.738413079331, 5295.629305948547, 5306.0, 5344.946550534522, 5347.625666203328, 4545.431821022372, 4584.404027476407, 4629.3518907353, 4633.930491042637, 4666.533346665374, 5837.29952405714, 5853.034249348895, 5863.384344643836, 5863.648181109147, 5870.7945221917025, 6733.3266673333255, 6734.831629209309, 6739.0902228198565, 6763.0, 6830.931690683129, 5718.542516598662, 5801.7099145042575, 5849.298084229919, 5857.560682948796, 5874.970625146932, 5680.7727690892725, 5696.715164241771, 5770.942290577041, 5801.390853960358, 5824.388439213906, 6335.271443783931, 6455.644939528277, 6528.053432252392, 6545.181852268466, 6548.8035358939305, 6660.10088638047, 6693.431058359899, 6734.764283250062, 6743.629100399525, 6815.284395138439, 6461.741530338733, 6523.347665233438, 6563.04835798816, 6599.043138744951, 6627.37039981201, 6327.96836015817, 6391.456726178327, 6406.263279722705, 6523.739050437929, 6685.465162787063, 6644.534882558174, 6695.163104611924, 6706.027625994301, 6709.463242940651, 6815.761448349284, 6580.243272023778, 6619.702113405005, 6633.668316584248, 6638.037484564615, 6644.534882558174, 6546.705398257185, 6550.574212676161, 6551.148350714377, 6559.704813283318, 6621.1392518972225, 6324.6521441321165, 6465.709043093166, 6470.967645161744, 6471.417572418505, 6546.607203567801, 6535.215774107138, 6566.8358291043105, 6580.0, 6625.734970601122, 6654.134962455044, 6158.476529494864, 6260.906086408617, 6372.330905254881, 6461.903071454026, 6462.0], "value": 6136.3757556219325, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [879.2131042716769, 904.5974541329111, 905.1310741687979, 909.1999040844056, 910.9954450227748, 39314.60685393145, 42912.57087429125, 43303.133937321276, 43324.35013474796, 43580.12839743203, 39874.40188397176, 39976.20047599046, 40019.19961600766, 40394.19211615766, 40444.79777601115, 7261.927380726192, 7348.889766653505, 7379.151397589292, 7405.481616286862, 7417.147028091757, 7315.5244909081, 7329.853402931938, 7376.7786966391095, 7385.889211661829, 7424.851502969965, 7347.63261836906, 7946.0464744230485, 8232.341412687001, 8244.258016778489, 8245.134260902609, 8134.959325203366, 8212.507249564986, 8213.383996200278, 8222.0, 8223.301019413297, 7880.605969701549, 8022.558759268238, 8063.032436107646, 8066.637001334954, 8079.919200807975, 39403.60596394028, 39906.40140397896, 39958.60041399607, 39988.40017399741, 40170.79914600408, 3555.928881422357, 3599.5680518337704, 3608.1340478285283, 3608.4046132388125, 3611.133328001261, 32845.835770820995, 32892.50661240083, 32908.50637240443, 32934.50598241029, 32960.1759956003, 5230.241614965808, 5322.1484562470205, 5334.1732031535175, 5339.359276886759, 5346.518813306821, 196010.0, 196959.0, 197124.0, 199089.0, 199156.0, 44005.33991990123, 44713.329300060526, 49541.50458495441, 50144.74927625339, 50203.497965020615, 52567.21149182766, 52802.471975280525, 53034.46965530373, 53063.0, 53068.20397694038, 16103.919480402757, 16408.917955410387, 16415.91792041032, 16518.6696266074, 16583.6683266334, 13407.262600557064, 13414.463421463031, 13532.052756306965, 13539.593812185452, 13669.521566745116, 14244.430222790972, 14447.133172009713, 14447.422103115969, 14556.053856499293, 14612.85387146115, 5796.565257605696, 5839.357670656224, 5876.3536011039605, 5947.613405128653, 5990.580659353804, 1015.8476228565627, 1027.157730660851, 1034.1468288661883, 1038.5222797513077, 1041.8749750030045, 23043.0, 23098.53802923932, 23194.65208021881, 23228.070877164722, 23241.53516929652, 1116.1684545014016, 1118.5917140243914, 1126.183516950221, 1129.3788416370962, 1137.1073707139856, 12271.202371845802, 12374.257544547356, 12456.501739930483, 12460.563880264148, 12529.43617537213, 230623.0, 231668.0, 232697.0, 232843.0, 232944.0, 53132.0, 53146.468535314925, 53259.73370133124, 53299.0, 53385.7330713344, 1450.4995776457106, 1470.3456961651962, 1472.992635036818, 1473.9363702955775, 1475.3950880139091, 22695.886520567292, 22746.317610471713, 22853.542929141655, 22907.427314317276, 22913.88543057274, 38050.2389952205, 38088.238235235694, 38099.80950095232, 38122.42816357812, 38149.0, 195673.0, 196087.0, 196143.0, 196168.0, 196497.0, 31483.84258078695, 31806.0, 34271.14322141966, 35005.0, 35012.0, 44013.11973760443, 44853.55146448494, 45028.549714502435, 45211.773941130086, 45399.546004540854, 32155.19612009625, 32276.0, 32314.192145196557, 32947.67052329446, 33533.49699754457, 26626.600600991387, 26975.730242697322, 27581.172564823086, 27739.861300693367, 27831.721682783722, 36419.27161456809, 36521.81739091394, 36779.63220367762, 36941.07647308839, 36973.81513092417, 31128.844355778077, 31831.0, 31899.68100318967, 31986.6801331993, 32834.50748238731, 22967.58329085067, 23019.66879894725, 23614.88192559095, 23742.525149497258, 23823.761762382153], "value": 17333.47088279249, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.5950186115065, 585.306411901896, 591.5858898770862, 603.4207161125319, 609.7073404765713, 22173.0, 22608.886955565198, 22671.0, 22685.886570567145, 22922.312330630088, 22807.201747938827, 23339.416514587145, 25093.372665683368, 25297.494050118985, 25590.360240993985, 6309.558330916838, 6507.51193660475, 6523.836904077389, 6542.836429089287, 6570.441512471446, 6228.314885362606, 6431.935680643181, 6471.514636402265, 6508.54440189187, 6518.543701940866, 6467.126937863399, 6473.708683109271, 6486.643234622094, 6537.673116344164, 6542.476601871839, 6274.623522588659, 6530.412262896362, 6544.051112588696, 6547.116139321179, 6548.8035358939305, 6050.6369617823075, 6092.939070609282, 6102.237220347457, 6102.511799056065, 6104.4505994460715, 28635.713642863513, 29086.0, 29303.267418314714, 29332.853335733398, 29377.265568360748, 2046.92835750748, 2055.506678397174, 2067.9793202068086, 2079.386580958621, 2079.9688004679942, 19138.0, 19641.80358196414, 20456.590868182702, 20682.793172068235, 20788.272410465714, 2473.9010439582576, 2501.7873480754156, 2537.1246919812634, 2539.0986199898953, 2543.2751665775163, 122889.0, 128543.99999999907, 136326.0, 138810.30594847063, 145235.0, 14947.757836324638, 14963.850361496246, 14985.475508357154, 15172.620684482976, 15387.692246155239, 23710.644340334908, 23757.524849502912, 23809.166679166145, 23998.640020399707, 24092.277231683078, 10194.694159175237, 10249.231307651957, 10286.948565257275, 10309.587616495406, 10513.369197848153, 9436.339456238135, 9521.28590355726, 9556.76108097303, 9674.322797404255, 9690.418574885529, 9166.129217724305, 9284.353959121618, 9378.765530861647, 9463.148316651534, 9515.904840951502, 2789.860506974643, 2825.8587070646795, 2854.372038151603, 2872.1814282929263, 2872.7989040767366, 943.42451104826, 967.9467629280457, 975.3952549419264, 977.3158788847835, 985.4235272365603, 19531.51171220731, 19558.11988460523, 19614.803851961296, 19777.604447910962, 20149.59700806005, 1034.9793004139874, 1045.1116550931647, 1045.6131231444415, 1049.3546468921559, 1058.5606973106228, 10778.514966826511, 10836.349819010884, 10850.240483166102, 10935.343879367263, 11044.613438529614, 479046.0, 482495.0, 490169.0, 497139.0, 500599.0, 111288.44355778169, 111639.0, 114442.0, 114740.42629786798, 115248.42375788235, 1154.9133814963911, 1187.8693343732182, 1240.621610408816, 1256.1583738894963, 1267.7781388257017, 20992.160313587287, 21173.0, 21193.470163246042, 21310.467238319165, 21564.0, 57497.0, 57934.4206557929, 58346.0, 59664.403355965886, 60402.39597604144, 73751.63124184345, 74439.2556074454, 74659.88010179743, 74771.25228747643, 75037.24962750303, 14275.785863211853, 14926.0, 14962.17708026069, 15063.0, 15079.924600376928, 20888.1644734215, 22119.66820497726, 22483.0, 22536.323910282717, 22566.774332256467, 17160.22778974974, 17175.7423638643, 17406.216720247874, 17477.300907963538, 17595.20821563007, 15595.922020389826, 15892.364305427653, 16391.836081639507, 16409.83590164131, 16514.66970660604, 18072.277108916023, 18712.90643546828, 18823.152958117193, 18884.716729248797, 19002.714959275898, 14717.411303547738, 14774.926125369304, 14806.111633302036, 14872.851271487147, 14892.925535372255, 17315.740263896303, 17554.824451755318, 17596.29614815393, 18055.638887222445, 18102.81897181064], "value": 12061.268472446332, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1278.2522224498668, 1369.246914197192, 1382.5161193582253, 1385.549696348687, 1386.7018591002932, 15688.45090421834, 16169.35322587098, 16194.838051619481, 16201.513954581353, 16222.188890555455, 15781.368745250176, 15822.129782861937, 15858.0, 15918.204089795521, 15927.601809954756, 2774.9861250693716, 2810.466011457827, 2815.746582807547, 2821.8730157142877, 2823.167165686117, 2759.489494443524, 2780.8609569521545, 2805.270629636289, 2811.90158344458, 2831.957520637192, 2717.0897749254023, 2835.943281134376, 2842.0052981456533, 2851.272925404019, 2857.7999540032206, 2794.1058861164433, 2812.1282402455304, 2816.605675205473, 2821.7742580593504, 2830.957535636967, 2730.3993121513236, 2749.587561865716, 2768.404792969501, 2769.1830909881633, 2812.4937511247967, 14987.32557034936, 15124.168170750605, 15215.0, 15288.847111528965, 15345.30946107428, 8174.591270436455, 8494.532800695959, 8498.320134389296, 8518.914810851937, 8559.186877246651, 5372.0, 5679.261695979496, 5697.515711164556, 5716.742746576372, 5819.098039803837, 1887.2168050259127, 1945.2899691612602, 1945.4260993007097, 1967.1246295398553, 1993.2425678242175, 28220.43559128806, 28274.858625706944, 28355.71644283572, 28486.715132848614, 28583.428331433464, 6681.966590167018, 6871.725130994804, 6937.306269373023, 7008.159020917421, 7040.295970402919, 17731.73402398965, 17743.467695969142, 17756.55608609795, 17825.197866096056, 17847.28610855577, 8049.315808156255, 8209.712660056988, 8214.05538363096, 8219.630116644765, 8235.423520353517, 7456.962715186498, 7477.401807855358, 7523.473356864967, 7527.435442341846, 7546.434017448713, 5315.176147697113, 5346.224797404432, 5372.059889519319, 5423.403425623178, 5461.972690136524, 1402.754517959353, 1437.1233547535994, 1566.3343079191297, 1617.579429348378, 1629.6007478167924, 876.7939534209455, 878.2139984713646, 887.2281115429489, 890.4879694175875, 899.5052721003552, 7309.744158954518, 7334.816629584303, 7346.33882950537, 7364.410847132217, 7431.85136297271, 945.5083356654501, 973.1193270090579, 984.6110786239395, 993.4635296939572, 1005.9597616095274, 4409.162259170747, 4466.531014243523, 4468.486124095705, 4479.529649386833, 4606.700564463299, 20665.89667051655, 21386.0, 21408.143674253166, 21480.57038859214, 21583.460413489473, 20554.794452055587, 21194.78805211959, 21239.89380053121, 21353.14587416486, 21417.57164856694, 11233.550657973752, 11243.269187502787, 11467.139964502696, 11485.02377297939, 11559.537618495335, 8507.404481686222, 8562.100979397073, 8572.013195390677, 8662.393632445921, 8764.0, 17544.736828947833, 18278.725819112457, 18352.082395879886, 18714.625707485502, 18843.434696959113, 18582.349617763313, 18728.906355468134, 19083.61832763365, 19208.615827683647, 19505.0, 10571.04860562553, 10719.785604287714, 10735.570577176826, 10750.0, 10857.67426977192, 13570.117942333716, 13755.656108597364, 13778.517751878637, 13794.448222071005, 13918.234497102756, 11186.496607652512, 11402.20184587104, 11524.078073753912, 11618.244814087057, 11754.76490470203, 10927.125829933428, 11156.274842135235, 11210.83183752228, 11239.775204495701, 11509.654710358367, 9963.701088967342, 10045.593616893786, 10102.0, 10116.393016419039, 10132.392056476636, 7596.430267730053, 7613.390928725799, 7672.271134242238, 7677.193894640982, 7678.616069196406, 7217.891731624135, 7358.190599033995, 7410.555366678017, 7428.888566671612, 7466.178720340867], "value": 6750.78516141702, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.44981744989235, 504.1731560241207, 523.9030779305829, 535.7642637239614, 541.842865568985, 6140.942784167304, 6212.5961812482155, 6263.749450021994, 6278.968605156968, 6315.40003699649, 5993.0, 6057.303410107839, 6125.2941970108, 6129.908051379233, 6214.0, 4984.177610694231, 5028.220625803006, 5033.169527028036, 5041.395032596093, 5050.823221187259, 4910.901781964359, 5039.697618142924, 5059.949400505985, 5061.772220250097, 5064.455341120956, 5004.674696144757, 5012.248162775594, 5013.197888337867, 5046.520580544842, 5087.414947281054, 4946.3817022872145, 4958.975205123951, 4995.975020124876, 5008.6744361616375, 5060.54455099041, 4495.865124046284, 4538.954610453886, 4554.517787805654, 4579.107074120548, 4580.748058856761, 6104.633721976696, 6178.289496707846, 6214.130021796952, 6218.689065546704, 6245.313015568284, 5677.233573467571, 5721.656700597978, 5763.6541807491685, 5776.6534007959235, 5790.392008839095, 6550.0, 6595.680863827206, 6612.0, 6633.867322653568, 6665.833354166136, 4972.129877271499, 4986.307394007232, 5026.045051440251, 5027.271045698353, 5027.371578552681, 6823.93176068238, 6832.760853370109, 6868.519203655746, 6899.517033807636, 6937.7224911003495, 4999.175136102575, 5079.415867175248, 5085.703145697823, 5094.337736094284, 5096.847094587094, 5076.289319495199, 5106.4382917879, 5113.53978141969, 5136.0, 5151.201563757623, 5634.154876768517, 5682.0, 5686.289213848269, 5767.826965191051, 5775.0, 6267.185265915493, 6298.590591611531, 6393.1688880445245, 6456.225252969673, 6478.967605162038, 1706.1895599590343, 1735.4186347573452, 1740.1647209339587, 1748.606563523197, 1754.798198207197, 1053.3363980692116, 1227.9570215042431, 1229.2255878796302, 1230.698478872682, 1240.9875901241053, 5573.832785016456, 5630.817528319017, 5651.660900345993, 5683.772649094072, 5763.452472015152, 6537.182852143482, 6566.997065894852, 6596.340365963461, 6621.933780662228, 6657.700403481855, 5681.971590142023, 5709.571782116358, 5772.134179872969, 5779.68812475005, 5779.88440231193, 3551.3785087609567, 3560.1099725068734, 3565.35823551758, 3597.190632107755, 3613.855445782192, 6477.611343319416, 6487.870242595121, 6492.474268546887, 6496.881496881429, 6609.834754131185, 6787.762428314985, 6792.660366981631, 6799.388055075067, 6851.623160726108, 6932.896006559906, 6531.791618550584, 6565.767639002762, 6584.242812076577, 6612.86774264512, 6639.070530125786, 6640.402363787282, 6679.632620205741, 6680.5323627345615, 6688.531802773759, 6745.696443660146, 6526.552542796451, 6636.502262330441, 6644.90032649501, 6667.366600172976, 6691.498137639599, 6491.545591808717, 6497.8700425992165, 6542.247641521096, 6575.375339342755, 6577.6053436793945, 5024.296598476143, 5047.671901326402, 5108.3103780989095, 5109.5912327013, 5120.436751957207, 6317.0, 6463.644499552382, 6601.933980660324, 6645.767398141235, 6679.165104361955, 6329.841753956188, 6381.8723625528155, 6412.9358706414205, 6560.343965603402, 6574.671266436563, 6395.552311338161, 6447.2263328400895, 6477.255115661759, 6541.266564360353, 6592.90110648331, 6376.649284289409, 6393.29673735898, 6403.327650596807, 6482.120185146443, 6502.967485162733, 6577.704003319958, 6644.368784965421, 6672.228522328248, 6672.399484046459, 6690.933090669031, 6325.367463253822, 6372.36276372359, 6392.52056095804, 6403.359664033559, 6439.195100612424], "value": 5062.1582739051655, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [895.6506962284706, 900.2797761790567, 935.1630290889653, 936.1293996583179, 944.5135755086129, 18919.148638311293, 19528.609427811436, 19542.41372758816, 19567.804321956777, 19664.90167549162, 16286.51140465785, 16305.755413668774, 16383.42658006967, 18297.634047319047, 19025.238990440364, 963.8457846744504, 967.042627798478, 978.4814048554277, 979.2508730820928, 980.3725615606018, 994.5077186792512, 998.4708104704482, 998.9850152247722, 1002.6290272599113, 1002.9648962286321, 991.2862738828032, 1040.859483969662, 1048.8426735989624, 1049.826778581533, 1049.8320268757, 988.4415305352525, 1034.4052170002242, 1035.461559988809, 1039.3504059962524, 1040.7398150462384, 742.4654248940755, 744.6276861569215, 750.5196674128562, 751.7143485475539, 753.0323534258506, 19937.900310498353, 20002.599948001105, 20166.69749953752, 20169.193232270693, 20296.289629863033, 21487.462813429633, 21930.780692193035, 22336.664950025763, 22469.21357752487, 22599.661005084938, 16683.49949501517, 16883.15584220796, 16984.74522882158, 17139.57151071221, 17302.480925572254, 3740.1023754299044, 3771.7925514096987, 3776.9622303776887, 3789.5452545694693, 3795.430685397185, 128575.35712321472, 128805.0, 133927.0, 137558.31220843832, 138452.0, 16449.753253701205, 16571.751423728652, 16783.83216167847, 16875.662486750196, 17143.142842857807, 30485.542716859265, 30948.535771963438, 31008.0697579073, 31206.0, 31588.210294742355, 8464.44981076228, 8546.273566746897, 8593.484390936565, 8615.61229744663, 8628.482291062557, 4220.07158425146, 4266.146770645822, 4336.934945975813, 4345.739255644671, 4369.541198174209, 5411.458854114557, 5428.511433970962, 5502.064649009678, 5514.338279406416, 5538.113901775697, 1024.3495380433478, 1043.9739006524899, 1058.7406085509099, 1060.7295139739317, 1062.9202809789297, 662.2681936460239, 682.8941514065327, 688.2979361051799, 690.2028157478121, 700.4221517248291, 11264.267822591506, 11494.885051149382, 11787.528498859949, 11952.282863028073, 12067.034637229, 665.710415969055, 674.6626686656672, 675.9831004224934, 691.1360799001249, 694.198201077756, 5348.839534813884, 5410.053240682943, 5466.398696143421, 5508.6970216637665, 5667.1499275108235, 115872.0, 120515.0, 121453.0, 122025.38987305007, 124555.3772231133, 108468.4576577112, 111351.0, 111674.0, 112151.43924280489, 112527.4373628143, 93403.0, 93666.0, 95064.0, 96390.55414168793, 99550.0, 10001.199904007808, 10049.14582260516, 10050.547725352522, 10087.092161705188, 10114.898851011396, 43542.0, 44140.11719765651, 44872.32691509695, 45779.0, 46048.539514604425, 58871.0, 59145.40854591399, 60689.69655151696, 60903.0, 61527.692361537906, 15315.004524705859, 15425.0, 15465.304061317043, 15677.137757422995, 15696.0, 16826.831731683014, 17216.139193040548, 17229.0, 17257.309707611555, 17524.123793810515, 15518.0, 15784.526464206092, 15905.204739763198, 15992.92003539975, 16000.279987400827, 15962.840371596136, 16032.919835400748, 16121.274542645375, 16155.838441615433, 16178.595535111246, 13518.188908665512, 13600.251986140462, 13608.183508989492, 13681.0, 13733.107348022346, 11948.880511194777, 11972.880271197526, 11991.82012269834, 12149.210301330386, 12186.634400967985, 10471.162307015267, 10481.475926203811, 10803.513841877291, 10864.0, 11009.724756881142], "value": 8859.955316533787, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1428.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 108.0, 108.0, 108.0, 109.0, 112.0, 109.0, 108.0, 108.0], "value": 108.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 118.0, 122.0, 117.0, 118.0, 131.0, 117.0], "value": 119.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 164.0, 163.0, 166.0, 165.0, 165.0, 166.0, 168.0, 167.0], "value": 165.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 76.0, 75.0, 74.0, 82.0, 74.0, 73.0, 74.0, 74.0], "value": 75.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 80.0, 78.0, 80.0, 79.0, 80.0, 80.0, 80.0, 80.0], "value": 79.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 115.0, 114.0, 114.0, 117.0, 115.0, 114.0, 114.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 149.0, 150.0, 149.0, 150.0, 150.0, 149.0, 150.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 53.0, 54.0, 53.0, 51.0, 54.0, 52.0, 53.0, 53.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 39.0, 39.0, 38.0, 38.0, 38.0, 39.0, 38.0, 38.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 76.0, 82.0, 75.0, 78.0, 74.0, 76.0, 75.0, 74.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 107.0, 110.0, 113.0, 112.0, 117.0, 128.0, 111.0, 112.0, 110.0], "value": 113.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 172.0, 171.0, 169.0, 170.0, 178.0, 174.0, 170.0, 171.0, 170.0], "value": 172.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 67.0, 63.0, 74.0, 75.0, 66.0, 72.0, 65.0, 68.0], "value": 68.2, "unit": "score"}]}]} 21:57:57 INFO - Return code: 0 21:57:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:57:57 INFO - # TBPL SUCCESS # 21:57:57 INFO - Running post-action listener: _package_coverage_data 21:57:57 INFO - Running post-action listener: _resource_record_post_action 21:57:57 INFO - [mozharness: 2017-09-23 04:57:57.429802Z] Finished run-tests step (success) 21:57:57 INFO - Running post-run listener: _resource_record_post_run 21:57:57 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 16384; Write bytes: 336752640; Read time: 416; Write time: 213400 21:57:57 INFO - TinderboxPrint: CPU usage
13.2% 21:57:57 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 416 21:57:57 INFO - TinderboxPrint: I/O write bytes / time
336,752,640 / 213,400 21:57:57 INFO - TinderboxPrint: CPU idle
8,215.0 (86.8%) 21:57:57 INFO - TinderboxPrint: CPU user
1,195.4 (12.6%) 21:57:57 INFO - TinderboxPrint: Swap in / out
0 / 0 21:57:57 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10158080; Read time: 0; Write time: 956 21:57:57 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:57:57 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 12288; Write bytes: 326594560; Read time: 408; Write time: 212444 21:57:58 INFO - Running post-run listener: _upload_blobber_files 21:57:58 INFO - Blob upload gear active. 21:57:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:57:58 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 21:57:58 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'] 21:57:58 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 21:57:58 INFO - (blobuploader) - INFO - Open directory for files ... 21:57:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:57:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:57:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:57:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:57:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:57:59 INFO - (blobuploader) - INFO - Done attempting. 21:57:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:57:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:57:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:58:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:58:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:58:00 INFO - (blobuploader) - INFO - Done attempting. 21:58:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:58:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:58:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:58:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:58:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:58:07 INFO - (blobuploader) - INFO - Done attempting. 21:58:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:58:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:58:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:58:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:58:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:58:08 INFO - (blobuploader) - INFO - Done attempting. 21:58:08 INFO - (blobuploader) - INFO - Iteration through files over. 21:58:08 INFO - Return code: 0 21:58:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:58:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:58:08 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66ba9d2f1cbfeb09d52fdcfc660bcd1ab834be00c3593230a6744da886b49ea92aeb2411ebd116508537733c861bed063fc7a9c7fcdf555db2ce296d1a3de4e7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e078adb995852b31f873f07e5477d5ef3cb2d5d1fcc0046bbbf43df9ccb44fd2bcf3820d592ce877bef037678589d5a5153b54273f22217816c02cb48b894ec", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/88e54a26ef1c843a3c4de5478e3b8a0a10b9cfcd9fde55101607678d456c0e37b6bcdad76eba4fc5755d32ace2a10750e2b2062c26af1d7c053ce2a0538f70c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2f67cf4aa500c1b8933b3ac7c40b4a18fc76f53886e1de637657049949b76e2eafc57877b72687092a374d2684daf887277eaa68daef1cf0f6a2ef2c90a7022c"} 21:58:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:58:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:58:08 INFO - Contents: 21:58:08 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66ba9d2f1cbfeb09d52fdcfc660bcd1ab834be00c3593230a6744da886b49ea92aeb2411ebd116508537733c861bed063fc7a9c7fcdf555db2ce296d1a3de4e7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e078adb995852b31f873f07e5477d5ef3cb2d5d1fcc0046bbbf43df9ccb44fd2bcf3820d592ce877bef037678589d5a5153b54273f22217816c02cb48b894ec", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/88e54a26ef1c843a3c4de5478e3b8a0a10b9cfcd9fde55101607678d456c0e37b6bcdad76eba4fc5755d32ace2a10750e2b2062c26af1d7c053ce2a0538f70c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2f67cf4aa500c1b8933b3ac7c40b4a18fc76f53886e1de637657049949b76e2eafc57877b72687092a374d2684daf887277eaa68daef1cf0f6a2ef2c90a7022c"} 21:58:08 INFO - Running post-run listener: copy_logs_to_upload_dir 21:58:08 INFO - Copying logs to upload dir... 21:58:08 INFO - mkdir: /builds/slave/test/build/upload/logs 21:58:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.626539 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-09-22 21:58:08.413962) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 21:58:08.417983) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66ba9d2f1cbfeb09d52fdcfc660bcd1ab834be00c3593230a6744da886b49ea92aeb2411ebd116508537733c861bed063fc7a9c7fcdf555db2ce296d1a3de4e7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e078adb995852b31f873f07e5477d5ef3cb2d5d1fcc0046bbbf43df9ccb44fd2bcf3820d592ce877bef037678589d5a5153b54273f22217816c02cb48b894ec", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/88e54a26ef1c843a3c4de5478e3b8a0a10b9cfcd9fde55101607678d456c0e37b6bcdad76eba4fc5755d32ace2a10750e2b2062c26af1d7c053ce2a0538f70c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2f67cf4aa500c1b8933b3ac7c40b4a18fc76f53886e1de637657049949b76e2eafc57877b72687092a374d2684daf887277eaa68daef1cf0f6a2ef2c90a7022c"} build_url:https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011206 build_url: 'https://queue.taskcluster.net/v1/task/VxRwKolgS2WkH9phigpZaw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/66ba9d2f1cbfeb09d52fdcfc660bcd1ab834be00c3593230a6744da886b49ea92aeb2411ebd116508537733c861bed063fc7a9c7fcdf555db2ce296d1a3de4e7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9e078adb995852b31f873f07e5477d5ef3cb2d5d1fcc0046bbbf43df9ccb44fd2bcf3820d592ce877bef037678589d5a5153b54273f22217816c02cb48b894ec", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/88e54a26ef1c843a3c4de5478e3b8a0a10b9cfcd9fde55101607678d456c0e37b6bcdad76eba4fc5755d32ace2a10750e2b2062c26af1d7c053ce2a0538f70c4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2f67cf4aa500c1b8933b3ac7c40b4a18fc76f53886e1de637657049949b76e2eafc57877b72687092a374d2684daf887277eaa68daef1cf0f6a2ef2c90a7022c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 21:58:08.447284) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:58:08.447663) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1506133880.775334-2113676825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004466 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 21:58:08.532688) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 21:58:08.533036) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 21:58:08.533375) ========= ========= Total master_lag: 0.20 =========