{"level":"warn","ts":"2026-03-13T17:12:04.818799Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T17:12:04.818908Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-13T17:12:04.818993Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T17:12:04.819007Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-03-13T17:12:04.819037Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-13T17:12:04.819636Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-03-13T17:12:04.819703Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-13T17:12:04.82354Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.61257ms"} {"level":"info","ts":"2026-03-13T17:12:04.83262Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-03-13T17:12:04.832739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T17:12:04.832779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-03-13T17:12:04.832792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T17:12:04.832807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-03-13T17:12:04.832859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-03-13T17:12:04.840359Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T17:12:04.843503Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T17:12:04.845084Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-13T17:12:04.847805Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T17:12:04.847936Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T17:12:04.848085Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-13T17:12:04.848177Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-13T17:12:04.848196Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-13T17:12:04.849033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-03-13T17:12:04.849184Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-03-13T17:12:04.851248Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-13T17:12:04.851463Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-03-13T17:12:04.851526Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-03-13T17:12:04.851508Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T17:12:04.851807Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T17:12:05.033856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T17:12:05.033957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T17:12:05.033987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-03-13T17:12:05.034012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-03-13T17:12:05.034024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-03-13T17:12:05.034041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-03-13T17:12:05.034056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-03-13T17:12:05.035339Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T17:12:05.035337Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T17:12:05.035467Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T17:12:05.035524Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T17:12:05.035685Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T17:12:05.035715Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T17:12:05.037443Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T17:12:05.037698Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T17:12:05.037768Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T17:12:05.03908Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-13T17:12:05.040193Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-03-13T17:12:06.741373Z","caller":"traceutil/trace.go:171","msg":"trace[932992015] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"137.93041ms","start":"2026-03-13T17:12:06.603409Z","end":"2026-03-13T17:12:06.741339Z","steps":["trace[932992015] 'read index received' (duration: 126.522803ms)","trace[932992015] 'applied index is now lower than readState.Index' (duration: 11.406287ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:12:06.741674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.162686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189c75d80f5dfc63\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-03-13T17:12:06.74172Z","caller":"traceutil/trace.go:171","msg":"trace[563677751] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"143.489014ms","start":"2026-03-13T17:12:06.598014Z","end":"2026-03-13T17:12:06.741503Z","steps":["trace[563677751] 'process raft request' (duration: 131.947224ms)","trace[563677751] 'compare' (duration: 11.208681ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:12:06.74183Z","caller":"traceutil/trace.go:171","msg":"trace[2083516782] range","detail":"{range_begin:/registry/events/default/instance.189c75d80f5dfc63; range_end:; response_count:1; response_revision:73; }","duration":"138.393423ms","start":"2026-03-13T17:12:06.603377Z","end":"2026-03-13T17:12:06.74177Z","steps":["trace[2083516782] 'agreement among raft nodes before linearized reading' (duration: 138.148806ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:20.933781Z","caller":"traceutil/trace.go:171","msg":"trace[1594768075] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"109.534891ms","start":"2026-03-13T17:12:20.824213Z","end":"2026-03-13T17:12:20.933748Z","steps":["trace[1594768075] 'process raft request' (duration: 109.299944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:12:37.616615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.991097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:12:37.616749Z","caller":"traceutil/trace.go:171","msg":"trace[1752520353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"196.179362ms","start":"2026-03-13T17:12:37.420543Z","end":"2026-03-13T17:12:37.616722Z","steps":["trace[1752520353] 'range keys from in-memory index tree' (duration: 195.787742ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:41.795933Z","caller":"traceutil/trace.go:171","msg":"trace[1619915631] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"153.966329ms","start":"2026-03-13T17:12:41.641926Z","end":"2026-03-13T17:12:41.795892Z","steps":["trace[1619915631] 'process raft request' (duration: 153.719832ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:41.976578Z","caller":"traceutil/trace.go:171","msg":"trace[1197001197] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"176.395858ms","start":"2026-03-13T17:12:41.800136Z","end":"2026-03-13T17:12:41.976531Z","steps":["trace[1197001197] 'process raft request' (duration: 176.260124ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:12:43.291475Z","caller":"traceutil/trace.go:171","msg":"trace[757077506] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"108.37684ms","start":"2026-03-13T17:12:43.18307Z","end":"2026-03-13T17:12:43.291447Z","steps":["trace[757077506] 'process raft request' (duration: 103.901674ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:48.573789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.51821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-13T17:13:48.573864Z","caller":"traceutil/trace.go:171","msg":"trace[1386362877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1580; }","duration":"261.666405ms","start":"2026-03-13T17:13:48.312182Z","end":"2026-03-13T17:13:48.573849Z","steps":["trace[1386362877] 'range keys from in-memory index tree' (duration: 261.322425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:48.573853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.635529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:13:48.573944Z","caller":"traceutil/trace.go:171","msg":"trace[1981618772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1580; }","duration":"152.804334ms","start":"2026-03-13T17:13:48.421116Z","end":"2026-03-13T17:13:48.57392Z","steps":["trace[1981618772] 'range keys from in-memory index tree' (duration: 152.447563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:13:48.574155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.879971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-03-13T17:13:48.574266Z","caller":"traceutil/trace.go:171","msg":"trace[69857336] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1580; }","duration":"123.058126ms","start":"2026-03-13T17:13:48.451184Z","end":"2026-03-13T17:13:48.574242Z","steps":["trace[69857336] 'range keys from in-memory index tree' (duration: 122.540612ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:13:48.986056Z","caller":"traceutil/trace.go:171","msg":"trace[37917725] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"198.068193ms","start":"2026-03-13T17:13:48.787957Z","end":"2026-03-13T17:13:48.986025Z","steps":["trace[37917725] 'process raft request' (duration: 197.879418ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:14:37.925589Z","caller":"traceutil/trace.go:171","msg":"trace[679428965] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"109.373614ms","start":"2026-03-13T17:14:37.816183Z","end":"2026-03-13T17:14:37.925557Z","steps":["trace[679428965] 'process raft request' (duration: 109.186579ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:14:43.956884Z","caller":"traceutil/trace.go:171","msg":"trace[734918219] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"214.002743ms","start":"2026-03-13T17:14:43.742841Z","end":"2026-03-13T17:14:43.956844Z","steps":["trace[734918219] 'process raft request' (duration: 175.750138ms)","trace[734918219] 'compare' (duration: 38.016159ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:14:44.234583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.267316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T17:14:44.234715Z","caller":"traceutil/trace.go:171","msg":"trace[842521786] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1985; }","duration":"222.334043ms","start":"2026-03-13T17:14:44.01236Z","end":"2026-03-13T17:14:44.234694Z","steps":["trace[842521786] 'read index received' (duration: 79.03785ms)","trace[842521786] 'applied index is now lower than readState.Index' (duration: 143.293543ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:14:44.234762Z","caller":"traceutil/trace.go:171","msg":"trace[771219503] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"272.193509ms","start":"2026-03-13T17:14:43.96253Z","end":"2026-03-13T17:14:44.234723Z","steps":["trace[771219503] 'process raft request' (duration: 128.928037ms)","trace[771219503] 'compare' (duration: 141.690929ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:14:44.234894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.125297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T17:14:44.234938Z","caller":"traceutil/trace.go:171","msg":"trace[1833477523] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1911; }","duration":"102.205039ms","start":"2026-03-13T17:14:44.13272Z","end":"2026-03-13T17:14:44.234925Z","steps":["trace[1833477523] 'agreement among raft nodes before linearized reading' (duration: 102.116106ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:14:44.234939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.55918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:14:44.235074Z","caller":"traceutil/trace.go:171","msg":"trace[882783341] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1911; }","duration":"222.701763ms","start":"2026-03-13T17:14:44.012312Z","end":"2026-03-13T17:14:44.235014Z","steps":["trace[882783341] 'agreement among raft nodes before linearized reading' (duration: 222.452866ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:15:17.700399Z","caller":"traceutil/trace.go:171","msg":"trace[1278005021] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"130.855239ms","start":"2026-03-13T17:15:17.569503Z","end":"2026-03-13T17:15:17.700358Z","steps":["trace[1278005021] 'process raft request' (duration: 130.619313ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:15:51.516368Z","caller":"traceutil/trace.go:171","msg":"trace[1610114099] transaction","detail":"{read_only:false; response_revision:2438; number_of_response:1; }","duration":"155.914103ms","start":"2026-03-13T17:15:51.360389Z","end":"2026-03-13T17:15:51.516303Z","steps":["trace[1610114099] 'process raft request' (duration: 155.504002ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:15:51.748999Z","caller":"traceutil/trace.go:171","msg":"trace[286066820] linearizableReadLoop","detail":"{readStateIndex:2536; appliedIndex:2535; }","duration":"231.673843ms","start":"2026-03-13T17:15:51.517289Z","end":"2026-03-13T17:15:51.748963Z","steps":["trace[286066820] 'read index received' (duration: 172.606815ms)","trace[286066820] 'applied index is now lower than readState.Index' (duration: 59.065148ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:15:51.749069Z","caller":"traceutil/trace.go:171","msg":"trace[745377976] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"326.113972ms","start":"2026-03-13T17:15:51.422924Z","end":"2026-03-13T17:15:51.749038Z","steps":["trace[745377976] 'process raft request' (duration: 266.971062ms)","trace[745377976] 'compare' (duration: 58.813611ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:15:51.749158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.860789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:15:51.749211Z","caller":"traceutil/trace.go:171","msg":"trace[591369229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2439; }","duration":"231.958361ms","start":"2026-03-13T17:15:51.517237Z","end":"2026-03-13T17:15:51.749196Z","steps":["trace[591369229] 'agreement among raft nodes before linearized reading' (duration: 231.860149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:15:51.749218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T17:15:51.422893Z","time spent":"326.227596ms","remote":"127.0.0.1:43868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T17:18:17.671594Z","caller":"traceutil/trace.go:171","msg":"trace[1800477420] linearizableReadLoop","detail":"{readStateIndex:3467; appliedIndex:3466; }","duration":"184.940166ms","start":"2026-03-13T17:18:17.48663Z","end":"2026-03-13T17:18:17.67157Z","steps":["trace[1800477420] 'read index received' (duration: 184.830113ms)","trace[1800477420] 'applied index is now lower than readState.Index' (duration: 108.973µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:18:17.671683Z","caller":"traceutil/trace.go:171","msg":"trace[1660041822] transaction","detail":"{read_only:false; response_revision:3334; number_of_response:1; }","duration":"211.222859ms","start":"2026-03-13T17:18:17.460415Z","end":"2026-03-13T17:18:17.671638Z","steps":["trace[1660041822] 'process raft request' (duration: 210.994883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:18:17.671842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.188593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-13T17:18:17.671925Z","caller":"traceutil/trace.go:171","msg":"trace[1513941515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3334; }","duration":"185.305856ms","start":"2026-03-13T17:18:17.486601Z","end":"2026-03-13T17:18:17.671907Z","steps":["trace[1513941515] 'agreement among raft nodes before linearized reading' (duration: 185.107361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:18:17.67495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.195636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T17:18:17.675012Z","caller":"traceutil/trace.go:171","msg":"trace[152879973] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:3335; }","duration":"188.298638ms","start":"2026-03-13T17:18:17.486698Z","end":"2026-03-13T17:18:17.674997Z","steps":["trace[152879973] 'agreement among raft nodes before linearized reading' (duration: 188.136034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:19:33.241651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.928562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-03-13T17:19:33.241698Z","caller":"traceutil/trace.go:171","msg":"trace[1467790965] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4184; }","duration":"107.094917ms","start":"2026-03-13T17:19:33.134595Z","end":"2026-03-13T17:19:33.24169Z","steps":["trace[1467790965] 'agreement among raft nodes before linearized reading' (duration: 106.83601ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:19:33.241356Z","caller":"traceutil/trace.go:171","msg":"trace[1689140577] transaction","detail":"{read_only:false; response_revision:4184; number_of_response:1; }","duration":"107.496308ms","start":"2026-03-13T17:19:33.133843Z","end":"2026-03-13T17:19:33.241339Z","steps":["trace[1689140577] 'process raft request' (duration: 96.431592ms)","trace[1689140577] 'compare' (duration: 10.868281ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:19:33.241306Z","caller":"traceutil/trace.go:171","msg":"trace[1778144757] linearizableReadLoop","detail":"{readStateIndex:4352; appliedIndex:4351; }","duration":"106.635964ms","start":"2026-03-13T17:19:33.134626Z","end":"2026-03-13T17:19:33.241262Z","steps":["trace[1778144757] 'read index received' (duration: 95.66076ms)","trace[1778144757] 'applied index is now lower than readState.Index' (duration: 10.974424ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:19:33.24337Z","caller":"traceutil/trace.go:171","msg":"trace[1232631597] transaction","detail":"{read_only:false; response_revision:4187; number_of_response:1; }","duration":"107.5888ms","start":"2026-03-13T17:19:33.135753Z","end":"2026-03-13T17:19:33.243342Z","steps":["trace[1232631597] 'process raft request' (duration: 107.529539ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:19:33.243567Z","caller":"traceutil/trace.go:171","msg":"trace[1149798149] transaction","detail":"{read_only:false; response_revision:4185; number_of_response:1; }","duration":"108.566488ms","start":"2026-03-13T17:19:33.134988Z","end":"2026-03-13T17:19:33.243554Z","steps":["trace[1149798149] 'process raft request' (duration: 108.033512ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:19:33.243616Z","caller":"traceutil/trace.go:171","msg":"trace[722356645] transaction","detail":"{read_only:false; response_revision:4186; number_of_response:1; }","duration":"107.92154ms","start":"2026-03-13T17:19:33.135636Z","end":"2026-03-13T17:19:33.243558Z","steps":["trace[722356645] 'process raft request' (duration: 107.568289ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:19:42.14611Z","caller":"traceutil/trace.go:171","msg":"trace[2134550504] linearizableReadLoop","detail":"{readStateIndex:4634; appliedIndex:4633; }","duration":"110.205303ms","start":"2026-03-13T17:19:42.035878Z","end":"2026-03-13T17:19:42.146083Z","steps":["trace[2134550504] 'read index received' (duration: 109.996577ms)","trace[2134550504] 'applied index is now lower than readState.Index' (duration: 207.696µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:19:42.146466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.558604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f79041d4-848f-4252-ad52-4b386ba6611c\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-03-13T17:19:42.146559Z","caller":"traceutil/trace.go:171","msg":"trace[1929262847] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f79041d4-848f-4252-ad52-4b386ba6611c; range_end:; response_count:1; response_revision:4458; }","duration":"110.677787ms","start":"2026-03-13T17:19:42.035865Z","end":"2026-03-13T17:19:42.146542Z","steps":["trace[1929262847] 'agreement among raft nodes before linearized reading' (duration: 110.345067ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:19:42.146908Z","caller":"traceutil/trace.go:171","msg":"trace[1913928142] transaction","detail":"{read_only:false; response_revision:4458; number_of_response:1; }","duration":"113.179206ms","start":"2026-03-13T17:19:42.032979Z","end":"2026-03-13T17:19:42.146159Z","steps":["trace[1913928142] 'process raft request' (duration: 112.928729ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:19:42.1983Z","caller":"traceutil/trace.go:171","msg":"trace[980424097] transaction","detail":"{read_only:false; response_revision:4459; number_of_response:1; }","duration":"142.358974ms","start":"2026-03-13T17:19:42.055915Z","end":"2026-03-13T17:19:42.198274Z","steps":["trace[980424097] 'process raft request' (duration: 141.935462ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:19:42.198628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.852432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-13T17:19:42.198668Z","caller":"traceutil/trace.go:171","msg":"trace[1509790950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4459; }","duration":"127.962616ms","start":"2026-03-13T17:19:42.070697Z","end":"2026-03-13T17:19:42.19866Z","steps":["trace[1509790950] 'agreement among raft nodes before linearized reading' (duration: 127.807111ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:19:42.462961Z","caller":"traceutil/trace.go:171","msg":"trace[1985590299] transaction","detail":"{read_only:false; response_revision:4470; number_of_response:1; }","duration":"143.826295ms","start":"2026-03-13T17:19:42.319104Z","end":"2026-03-13T17:19:42.46293Z","steps":["trace[1985590299] 'process raft request' (duration: 108.520617ms)","trace[1985590299] 'compare' (duration: 35.136863ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:19:42.498309Z","caller":"traceutil/trace.go:171","msg":"trace[192908704] transaction","detail":"{read_only:false; response_revision:4471; number_of_response:1; }","duration":"164.564429ms","start":"2026-03-13T17:19:42.333718Z","end":"2026-03-13T17:19:42.498282Z","steps":["trace[192908704] 'process raft request' (duration: 164.343583ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:19:53.116789Z","caller":"traceutil/trace.go:171","msg":"trace[1258224364] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"104.424094ms","start":"2026-03-13T17:19:53.012337Z","end":"2026-03-13T17:19:53.116762Z","steps":["trace[1258224364] 'process raft request' (duration: 104.225567ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:20:00.578981Z","caller":"traceutil/trace.go:171","msg":"trace[2139586432] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"141.473849ms","start":"2026-03-13T17:20:00.43747Z","end":"2026-03-13T17:20:00.578944Z","steps":["trace[2139586432] 'process raft request' (duration: 141.012796ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:20:00.766158Z","caller":"traceutil/trace.go:171","msg":"trace[1366322312] linearizableReadLoop","detail":"{readStateIndex:5029; appliedIndex:5028; }","duration":"144.993808ms","start":"2026-03-13T17:20:00.621147Z","end":"2026-03-13T17:20:00.766141Z","steps":["trace[1366322312] 'read index received' (duration: 144.875275ms)","trace[1366322312] 'applied index is now lower than readState.Index' (duration: 117.823µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:20:00.766279Z","caller":"traceutil/trace.go:171","msg":"trace[2145772153] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"174.521545ms","start":"2026-03-13T17:20:00.591736Z","end":"2026-03-13T17:20:00.766258Z","steps":["trace[2145772153] 'process raft request' (duration: 174.281339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:20:00.766368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.202084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee55250e-40fd-48fa-beec-6135a8747b1f\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-03-13T17:20:00.766419Z","caller":"traceutil/trace.go:171","msg":"trace[87439058] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ee55250e-40fd-48fa-beec-6135a8747b1f; range_end:; response_count:1; response_revision:4842; }","duration":"145.303566ms","start":"2026-03-13T17:20:00.621088Z","end":"2026-03-13T17:20:00.766392Z","steps":["trace[87439058] 'agreement among raft nodes before linearized reading' (duration: 145.153962ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:20:00.880038Z","caller":"traceutil/trace.go:171","msg":"trace[121176360] linearizableReadLoop","detail":"{readStateIndex:5030; appliedIndex:5029; }","duration":"113.788003ms","start":"2026-03-13T17:20:00.766225Z","end":"2026-03-13T17:20:00.880013Z","steps":["trace[121176360] 'read index received' (duration: 59.963022ms)","trace[121176360] 'applied index is now lower than readState.Index' (duration: 53.823931ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:20:00.88016Z","caller":"traceutil/trace.go:171","msg":"trace[360053283] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"120.682113ms","start":"2026-03-13T17:20:00.759445Z","end":"2026-03-13T17:20:00.880127Z","steps":["trace[360053283] 'process raft request' (duration: 66.672947ms)","trace[360053283] 'compare' (duration: 53.77683ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T17:20:00.880241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.122659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:20:00.880306Z","caller":"traceutil/trace.go:171","msg":"trace[715599587] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4843; }","duration":"242.237652ms","start":"2026-03-13T17:20:00.638051Z","end":"2026-03-13T17:20:00.880289Z","steps":["trace[715599587] 'agreement among raft nodes before linearized reading' (duration: 242.086538ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:20:00.944055Z","caller":"traceutil/trace.go:171","msg":"trace[7979620] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"169.864017ms","start":"2026-03-13T17:20:00.774163Z","end":"2026-03-13T17:20:00.944027Z","steps":["trace[7979620] 'process raft request' (duration: 169.667812ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:20:00.944257Z","caller":"traceutil/trace.go:171","msg":"trace[303363578] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"170.032982ms","start":"2026-03-13T17:20:00.774207Z","end":"2026-03-13T17:20:00.94424Z","steps":["trace[303363578] 'process raft request' (duration: 169.745044ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:20:00.944659Z","caller":"traceutil/trace.go:171","msg":"trace[913253475] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"170.129214ms","start":"2026-03-13T17:20:00.774512Z","end":"2026-03-13T17:20:00.944641Z","steps":["trace[913253475] 'process raft request' (duration: 169.667871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:20:00.944648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.637306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-13T17:20:00.94473Z","caller":"traceutil/trace.go:171","msg":"trace[1254057032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4846; }","duration":"101.77969ms","start":"2026-03-13T17:20:00.842929Z","end":"2026-03-13T17:20:00.944709Z","steps":["trace[1254057032] 'agreement among raft nodes before linearized reading' (duration: 101.538523ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:22:05.134734Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2797} {"level":"info","ts":"2026-03-13T17:22:05.192217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2797,"took":"55.8938ms","hash":3847517584,"current-db-size-bytes":42225664,"current-db-size":"42 MB","current-db-size-in-use-bytes":31805440,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-13T17:22:05.192342Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3847517584,"revision":2797,"compact-revision":-1} {"level":"info","ts":"2026-03-13T17:26:13.044208Z","caller":"traceutil/trace.go:171","msg":"trace[1925773614] transaction","detail":"{read_only:false; response_revision:7525; number_of_response:1; }","duration":"138.016338ms","start":"2026-03-13T17:26:12.905995Z","end":"2026-03-13T17:26:13.044012Z","steps":["trace[1925773614] 'process raft request' (duration: 137.889725ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:26:21.86088Z","caller":"traceutil/trace.go:171","msg":"trace[1494725233] linearizableReadLoop","detail":"{readStateIndex:7844; appliedIndex:7843; }","duration":"100.749897ms","start":"2026-03-13T17:26:21.760104Z","end":"2026-03-13T17:26:21.860854Z","steps":["trace[1494725233] 'read index received' (duration: 100.526661ms)","trace[1494725233] 'applied index is now lower than readState.Index' (duration: 222.116µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:26:21.860924Z","caller":"traceutil/trace.go:171","msg":"trace[188061667] transaction","detail":"{read_only:false; response_revision:7568; number_of_response:1; }","duration":"188.402536ms","start":"2026-03-13T17:26:21.672495Z","end":"2026-03-13T17:26:21.860897Z","steps":["trace[188061667] 'process raft request' (duration: 188.18354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:26:21.861042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.919492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:26:21.861084Z","caller":"traceutil/trace.go:171","msg":"trace[1049061255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7568; }","duration":"101.023754ms","start":"2026-03-13T17:26:21.760048Z","end":"2026-03-13T17:26:21.861072Z","steps":["trace[1049061255] 'agreement among raft nodes before linearized reading' (duration: 100.934512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:26:22.536442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.447689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:26:22.536597Z","caller":"traceutil/trace.go:171","msg":"trace[352718722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7571; }","duration":"116.688055ms","start":"2026-03-13T17:26:22.41989Z","end":"2026-03-13T17:26:22.536578Z","steps":["trace[352718722] 'range keys from in-memory index tree' (duration: 116.349906ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:27:04.958141Z","caller":"traceutil/trace.go:171","msg":"trace[173859339] transaction","detail":"{read_only:false; response_revision:7870; number_of_response:1; }","duration":"256.058339ms","start":"2026-03-13T17:27:04.702049Z","end":"2026-03-13T17:27:04.958107Z","steps":["trace[173859339] 'process raft request' (duration: 211.45434ms)","trace[173859339] 'compare' (duration: 44.470106ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:27:05.089973Z","caller":"traceutil/trace.go:171","msg":"trace[790826632] transaction","detail":"{read_only:false; response_revision:7872; number_of_response:1; }","duration":"113.223494ms","start":"2026-03-13T17:27:04.976717Z","end":"2026-03-13T17:27:05.089941Z","steps":["trace[790826632] 'process raft request' (duration: 108.762191ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:27:05.115469Z","caller":"traceutil/trace.go:171","msg":"trace[378679204] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7873; }","duration":"137.017425ms","start":"2026-03-13T17:27:04.978423Z","end":"2026-03-13T17:27:05.115441Z","steps":["trace[378679204] 'process raft request' (duration: 136.731577ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:27:05.115728Z","caller":"traceutil/trace.go:171","msg":"trace[2077351643] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7873; }","duration":"134.634948ms","start":"2026-03-13T17:27:04.981044Z","end":"2026-03-13T17:27:05.115679Z","steps":["trace[2077351643] 'process raft request' (duration: 134.35057ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:27:05.175614Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5802} {"level":"info","ts":"2026-03-13T17:27:05.445814Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5802,"took":"267.840517ms","hash":3622752059,"current-db-size-bytes":43065344,"current-db-size":"43 MB","current-db-size-in-use-bytes":23625728,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-13T17:27:05.445955Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3622752059,"revision":5802,"compact-revision":2797} {"level":"warn","ts":"2026-03-13T17:27:05.784999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.775814ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T17:27:05.785136Z","caller":"traceutil/trace.go:171","msg":"trace[327837694] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7878; }","duration":"151.928998ms","start":"2026-03-13T17:27:05.633182Z","end":"2026-03-13T17:27:05.785111Z","steps":["trace[327837694] 'range keys from in-memory index tree' (duration: 151.758393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:27:05.785796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.329937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T17:27:05.786083Z","caller":"traceutil/trace.go:171","msg":"trace[2117632190] linearizableReadLoop","detail":"{readStateIndex:8172; appliedIndex:8170; }","duration":"115.25424ms","start":"2026-03-13T17:27:05.670808Z","end":"2026-03-13T17:27:05.786062Z","steps":["trace[2117632190] 'read index received' (duration: 28.471µs)","trace[2117632190] 'applied index is now lower than readState.Index' (duration: 115.224689ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:27:05.786127Z","caller":"traceutil/trace.go:171","msg":"trace[112944158] transaction","detail":"{read_only:false; response_revision:7879; number_of_response:1; }","duration":"269.242216ms","start":"2026-03-13T17:27:05.51684Z","end":"2026-03-13T17:27:05.786082Z","steps":["trace[112944158] 'process raft request' (duration: 47.981432ms)","trace[112944158] 'compare' (duration: 220.021469ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T17:27:05.786304Z","caller":"traceutil/trace.go:171","msg":"trace[1881499402] transaction","detail":"{read_only:false; response_revision:7880; number_of_response:1; }","duration":"268.500125ms","start":"2026-03-13T17:27:05.517746Z","end":"2026-03-13T17:27:05.786246Z","steps":["trace[1881499402] 'process raft request' (duration: 268.188446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:27:05.786423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.580689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-77b4d5f985-w6c2b.189c769c001ad247\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-03-13T17:27:05.786509Z","caller":"traceutil/trace.go:171","msg":"trace[1655897674] range","detail":"{range_begin:/registry/events/openstack/barbican-api-77b4d5f985-w6c2b.189c769c001ad247; range_end:; response_count:1; response_revision:7880; }","duration":"115.712693ms","start":"2026-03-13T17:27:05.670772Z","end":"2026-03-13T17:27:05.786485Z","steps":["trace[1655897674] 'agreement among raft nodes before linearized reading' (duration: 115.475686ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:27:05.832352Z","caller":"traceutil/trace.go:171","msg":"trace[886029476] transaction","detail":"{read_only:false; response_revision:7881; number_of_response:1; }","duration":"130.76778ms","start":"2026-03-13T17:27:05.701544Z","end":"2026-03-13T17:27:05.832312Z","steps":["trace[886029476] 'process raft request' (duration: 130.569674ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T17:27:05.832597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.498773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hxp6x\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-13T17:27:05.833675Z","caller":"traceutil/trace.go:171","msg":"trace[1721429360] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hxp6x; range_end:; response_count:1; response_revision:7881; }","duration":"152.796482ms","start":"2026-03-13T17:27:05.679895Z","end":"2026-03-13T17:27:05.832692Z","steps":["trace[1721429360] 'agreement among raft nodes before linearized reading' (duration: 152.37357ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:28:05.782684Z","caller":"traceutil/trace.go:171","msg":"trace[147016931] transaction","detail":"{read_only:false; response_revision:8506; number_of_response:1; }","duration":"111.091679ms","start":"2026-03-13T17:28:05.671555Z","end":"2026-03-13T17:28:05.782646Z","steps":["trace[147016931] 'process raft request' (duration: 110.80544ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T17:30:48.283748Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6a9ba61e55fef5be","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-13T17:30:48.28972Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-13T17:30:48.289815Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-13T17:32:05.182117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7874} {"level":"info","ts":"2026-03-13T17:32:05.245193Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7874,"took":"60.872082ms","hash":2449963527,"current-db-size-bytes":43065344,"current-db-size":"43 MB","current-db-size-in-use-bytes":25899008,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-13T17:32:05.245265Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2449963527,"revision":7874,"compact-revision":5802} {"level":"info","ts":"2026-03-13T17:37:05.188395Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10030} {"level":"info","ts":"2026-03-13T17:37:05.268083Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10030,"took":"77.215268ms","hash":2107805138,"current-db-size-bytes":43065344,"current-db-size":"43 MB","current-db-size-in-use-bytes":16769024,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-13T17:37:05.268147Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2107805138,"revision":10030,"compact-revision":7874}