{"level":"warn","ts":"2026-08-24T20:32:33.147611Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T20:32:33.147693Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-08-24T20:32:33.147781Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T20:32:33.147794Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-08-24T20:32:33.147811Z","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-08-24T20:32:33.150922Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-08-24T20:32:33.152711Z","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":8,"max-cpu-available":8,"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://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-08-24T20:32:33.160346Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.103545ms"} {"level":"info","ts":"2026-08-24T20:32:33.165749Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-08-24T20:32:33.165832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T20:32:33.165856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-08-24T20:32:33.165866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T20:32:33.165876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-08-24T20:32:33.165942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-08-24T20:32:33.174671Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T20:32:33.176332Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T20:32:33.183029Z","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-08-24T20:32:33.185676Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T20:32:33.185975Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T20:32:33.186209Z","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-08-24T20:32:33.18631Z","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-08-24T20:32:33.186321Z","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-08-24T20:32:33.188181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-08-24T20:32:33.188373Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-08-24T20:32:33.189432Z","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-08-24T20:32:33.189625Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-08-24T20:32:33.189654Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-08-24T20:32:33.189839Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T20:32:33.189871Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T20:32:33.567068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T20:32:33.567114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T20:32:33.567134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-08-24T20:32:33.567147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-08-24T20:32:33.567153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-08-24T20:32:33.567161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-08-24T20:32:33.567168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-08-24T20:32:33.590099Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:32:33.592392Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T20:32:33.59262Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T20:32:33.592684Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T20:32:33.59389Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:32:33.593998Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:32:33.594052Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:32:33.595971Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-08-24T20:32:33.595986Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T20:32:33.596235Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T20:32:33.596261Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-08-24T20:32:37.043477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.351822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:32:37.043585Z","caller":"traceutil/trace.go:171","msg":"trace[1810825119] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"360.527525ms","start":"2026-08-24T20:32:36.683041Z","end":"2026-08-24T20:32:37.043568Z","steps":["trace[1810825119] 'process raft request' (duration: 92.553715ms)","trace[1810825119] 'compare' (duration: 267.192383ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:32:37.043677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:32:36.683025Z","time spent":"360.627005ms","remote":"127.0.0.1:48642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":756,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T20:32:37.480829Z","caller":"traceutil/trace.go:171","msg":"trace[489353254] linearizableReadLoop","detail":"{readStateIndex:186; appliedIndex:185; }","duration":"265.673897ms","start":"2026-08-24T20:32:37.215135Z","end":"2026-08-24T20:32:37.480809Z","steps":["trace[489353254] 'read index received' (duration: 265.502227ms)","trace[489353254] 'applied index is now lower than readState.Index' (duration: 170.94µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:32:37.481028Z","caller":"traceutil/trace.go:171","msg":"trace[1490504077] transaction","detail":"{read_only:false; response_revision:182; number_of_response:1; }","duration":"423.739907ms","start":"2026-08-24T20:32:37.057279Z","end":"2026-08-24T20:32:37.481019Z","steps":["trace[1490504077] 'process raft request' (duration: 423.407378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:32:37.48115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:32:37.05727Z","time spent":"423.816207ms","remote":"127.0.0.1:48642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T20:32:37.481212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.237057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-24T20:32:37.4812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.029906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T20:32:37.48127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.177556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"info","ts":"2026-08-24T20:32:37.481292Z","caller":"traceutil/trace.go:171","msg":"trace[347378989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:182; }","duration":"230.367316ms","start":"2026-08-24T20:32:37.250907Z","end":"2026-08-24T20:32:37.481275Z","steps":["trace[347378989] 'agreement among raft nodes before linearized reading' (duration: 230.164397ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:32:37.481312Z","caller":"traceutil/trace.go:171","msg":"trace[267995334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:182; }","duration":"266.197904ms","start":"2026-08-24T20:32:37.215094Z","end":"2026-08-24T20:32:37.481292Z","steps":["trace[267995334] 'agreement among raft nodes before linearized reading' (duration: 266.017155ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:32:37.481337Z","caller":"traceutil/trace.go:171","msg":"trace[67471616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:182; }","duration":"230.308496ms","start":"2026-08-24T20:32:37.251011Z","end":"2026-08-24T20:32:37.481319Z","steps":["trace[67471616] 'agreement among raft nodes before linearized reading' (duration: 230.154027ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:10.835079Z","caller":"traceutil/trace.go:171","msg":"trace[1846140069] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"143.732123ms","start":"2026-08-24T20:33:10.691321Z","end":"2026-08-24T20:33:10.835054Z","steps":["trace[1846140069] 'process raft request' (duration: 137.422446ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:10.835466Z","caller":"traceutil/trace.go:171","msg":"trace[1850247470] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"122.624607ms","start":"2026-08-24T20:33:10.712807Z","end":"2026-08-24T20:33:10.835431Z","steps":["trace[1850247470] 'process raft request' (duration: 122.183978ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:13.951038Z","caller":"traceutil/trace.go:171","msg":"trace[729416720] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"222.402969ms","start":"2026-08-24T20:33:13.728605Z","end":"2026-08-24T20:33:13.951008Z","steps":["trace[729416720] 'read index received' (duration: 221.79289ms)","trace[729416720] 'applied index is now lower than readState.Index' (duration: 609.459µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:33:13.951101Z","caller":"traceutil/trace.go:171","msg":"trace[1763390120] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"296.040309ms","start":"2026-08-24T20:33:13.655028Z","end":"2026-08-24T20:33:13.951069Z","steps":["trace[1763390120] 'process raft request' (duration: 295.64118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:13.951306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.500058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T20:33:13.951375Z","caller":"traceutil/trace.go:171","msg":"trace[1359063896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"222.595548ms","start":"2026-08-24T20:33:13.728752Z","end":"2026-08-24T20:33:13.951348Z","steps":["trace[1359063896] 'agreement among raft nodes before linearized reading' (duration: 222.402459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:13.951919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.252739ms","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-08-24T20:33:13.951972Z","caller":"traceutil/trace.go:171","msg":"trace[1590188984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"114.302049ms","start":"2026-08-24T20:33:13.837651Z","end":"2026-08-24T20:33:13.951953Z","steps":["trace[1590188984] 'agreement among raft nodes before linearized reading' (duration: 113.51564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:13.952059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.457867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T20:33:13.952098Z","caller":"traceutil/trace.go:171","msg":"trace[899555295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"223.532317ms","start":"2026-08-24T20:33:13.728556Z","end":"2026-08-24T20:33:13.952089Z","steps":["trace[899555295] 'agreement among raft nodes before linearized reading' (duration: 222.572099ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:17.042918Z","caller":"traceutil/trace.go:171","msg":"trace[1906345568] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"114.541833ms","start":"2026-08-24T20:33:16.928348Z","end":"2026-08-24T20:33:17.04289Z","steps":["trace[1906345568] 'process raft request' (duration: 75.925059ms)","trace[1906345568] 'compare' (duration: 38.475724ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:33:17.777039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.711015ms","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-08-24T20:33:17.777153Z","caller":"traceutil/trace.go:171","msg":"trace[1717152207] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:596; }","duration":"193.836545ms","start":"2026-08-24T20:33:17.583299Z","end":"2026-08-24T20:33:17.777135Z","steps":["trace[1717152207] 'range keys from in-memory index tree' (duration: 193.695325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:17.777149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.639026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T20:33:17.777156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.846873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dstq5\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-24T20:33:17.777237Z","caller":"traceutil/trace.go:171","msg":"trace[591992106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"424.783746ms","start":"2026-08-24T20:33:17.35243Z","end":"2026-08-24T20:33:17.777214Z","steps":["trace[591992106] 'range keys from in-memory index tree' (duration: 424.516617ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:17.777242Z","caller":"traceutil/trace.go:171","msg":"trace[852485225] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dstq5; range_end:; response_count:1; response_revision:596; }","duration":"396.972383ms","start":"2026-08-24T20:33:17.38025Z","end":"2026-08-24T20:33:17.777222Z","steps":["trace[852485225] 'range keys from in-memory index tree' (duration: 396.725493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:17.777342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:33:17.352417Z","time spent":"424.903116ms","remote":"127.0.0.1:48308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T20:33:17.777379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:33:17.380235Z","time spent":"397.131842ms","remote":"127.0.0.1:48494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-dstq5\" "} {"level":"info","ts":"2026-08-24T20:33:17.78152Z","caller":"traceutil/trace.go:171","msg":"trace[600755073] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"296.093112ms","start":"2026-08-24T20:33:17.485347Z","end":"2026-08-24T20:33:17.78144Z","steps":["trace[600755073] 'read index received' (duration: 282.705558ms)","trace[600755073] 'applied index is now lower than readState.Index' (duration: 13.385324ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:33:17.783016Z","caller":"traceutil/trace.go:171","msg":"trace[109487136] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"324.970324ms","start":"2026-08-24T20:33:17.457982Z","end":"2026-08-24T20:33:17.782952Z","steps":["trace[109487136] 'process raft request' (duration: 310.516193ms)","trace[109487136] 'compare' (duration: 10.895639ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:33:17.783436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:33:17.457953Z","time spent":"325.163004ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:33:17.783924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.552682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-24T20:33:17.783971Z","caller":"traceutil/trace.go:171","msg":"trace[682564079] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:597; }","duration":"165.620082ms","start":"2026-08-24T20:33:17.618328Z","end":"2026-08-24T20:33:17.783948Z","steps":["trace[682564079] 'agreement among raft nodes before linearized reading' (duration: 165.525542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:17.78506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.713605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T20:33:17.785116Z","caller":"traceutil/trace.go:171","msg":"trace[2085382484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"299.798275ms","start":"2026-08-24T20:33:17.485296Z","end":"2026-08-24T20:33:17.785095Z","steps":["trace[2085382484] 'agreement among raft nodes before linearized reading' (duration: 299.535965ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:33:53.755653Z","caller":"traceutil/trace.go:171","msg":"trace[790821835] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"181.293066ms","start":"2026-08-24T20:33:53.574339Z","end":"2026-08-24T20:33:53.755632Z","steps":["trace[790821835] 'process raft request' (duration: 181.18442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:33:54.075188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.204115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T20:33:54.075287Z","caller":"traceutil/trace.go:171","msg":"trace[571773794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1115; }","duration":"138.363685ms","start":"2026-08-24T20:33:53.936904Z","end":"2026-08-24T20:33:54.075268Z","steps":["trace[571773794] 'range keys from in-memory index tree' (duration: 138.000214ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:34:00.93421Z","caller":"traceutil/trace.go:171","msg":"trace[1751533286] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"302.076574ms","start":"2026-08-24T20:34:00.632109Z","end":"2026-08-24T20:34:00.934185Z","steps":["trace[1751533286] 'process raft request' (duration: 301.922885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:34:00.934365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:34:00.632086Z","time spent":"302.19688ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:34:16.292008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.223053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-24T20:34:16.292179Z","caller":"traceutil/trace.go:171","msg":"trace[242182264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"100.427362ms","start":"2026-08-24T20:34:16.191734Z","end":"2026-08-24T20:34:16.292162Z","steps":["trace[242182264] 'range keys from in-memory index tree' (duration: 100.069647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:34:23.019783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.339175ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-24T20:34:23.01992Z","caller":"traceutil/trace.go:171","msg":"trace[935250070] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1462; }","duration":"135.006794ms","start":"2026-08-24T20:34:22.884893Z","end":"2026-08-24T20:34:23.0199Z","steps":["trace[935250070] 'read index received' (duration: 17.573946ms)","trace[935250070] 'applied index is now lower than readState.Index' (duration: 117.431118ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:34:23.0201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.196251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-24T20:34:23.020137Z","caller":"traceutil/trace.go:171","msg":"trace[565992231] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1416; }","duration":"135.279035ms","start":"2026-08-24T20:34:22.884846Z","end":"2026-08-24T20:34:23.020125Z","steps":["trace[565992231] 'agreement among raft nodes before linearized reading' (duration: 135.110548ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:34:35.090862Z","caller":"traceutil/trace.go:171","msg":"trace[334387289] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"102.364307ms","start":"2026-08-24T20:34:34.988433Z","end":"2026-08-24T20:34:35.090797Z","steps":["trace[334387289] 'process raft request' (duration: 67.450997ms)","trace[334387289] 'compare' (duration: 34.197776ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:34:46.710151Z","caller":"traceutil/trace.go:171","msg":"trace[1385213928] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1886; }","duration":"358.620499ms","start":"2026-08-24T20:34:46.351503Z","end":"2026-08-24T20:34:46.710123Z","steps":["trace[1385213928] 'read index received' (duration: 322.37741ms)","trace[1385213928] 'applied index is now lower than readState.Index' (duration: 36.241919ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:34:46.710517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.891703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T20:34:46.710518Z","caller":"traceutil/trace.go:171","msg":"trace[57595716] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"481.929712ms","start":"2026-08-24T20:34:46.228548Z","end":"2026-08-24T20:34:46.710478Z","steps":["trace[57595716] 'process raft request' (duration: 445.425546ms)","trace[57595716] 'compare' (duration: 35.708973ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:34:46.710565Z","caller":"traceutil/trace.go:171","msg":"trace[1288672586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1813; }","duration":"197.987755ms","start":"2026-08-24T20:34:46.512565Z","end":"2026-08-24T20:34:46.710552Z","steps":["trace[1288672586] 'agreement among raft nodes before linearized reading' (duration: 197.841151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:34:46.710632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.996729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-24T20:34:46.710656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:34:46.228529Z","time spent":"482.048706ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:34:46.71068Z","caller":"traceutil/trace.go:171","msg":"trace[55141195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1813; }","duration":"192.071661ms","start":"2026-08-24T20:34:46.518592Z","end":"2026-08-24T20:34:46.710663Z","steps":["trace[55141195] 'agreement among raft nodes before linearized reading' (duration: 191.818544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:34:46.710534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.793686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-24T20:34:46.710733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.35196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T20:34:46.710804Z","caller":"traceutil/trace.go:171","msg":"trace[1806547090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1813; }","duration":"244.481294ms","start":"2026-08-24T20:34:46.466308Z","end":"2026-08-24T20:34:46.710789Z","steps":["trace[1806547090] 'agreement among raft nodes before linearized reading' (duration: 244.220317ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:34:46.710781Z","caller":"traceutil/trace.go:171","msg":"trace[56074364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1813; }","duration":"253.027773ms","start":"2026-08-24T20:34:46.45772Z","end":"2026-08-24T20:34:46.710747Z","steps":["trace[56074364] 'agreement among raft nodes before linearized reading' (duration: 252.677653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:34:46.711221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.519355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:34:46.711299Z","caller":"traceutil/trace.go:171","msg":"trace[237494442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1813; }","duration":"359.820293ms","start":"2026-08-24T20:34:46.351462Z","end":"2026-08-24T20:34:46.711282Z","steps":["trace[237494442] 'agreement among raft nodes before linearized reading' (duration: 359.0224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:34:46.711342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:34:46.351444Z","time spent":"359.890076ms","remote":"127.0.0.1:48308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T20:34:46.951039Z","caller":"traceutil/trace.go:171","msg":"trace[102742685] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"111.795157ms","start":"2026-08-24T20:34:46.839209Z","end":"2026-08-24T20:34:46.951004Z","steps":["trace[102742685] 'process raft request' (duration: 111.502718ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:34:47.410862Z","caller":"traceutil/trace.go:171","msg":"trace[960608139] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"247.343049ms","start":"2026-08-24T20:34:47.16349Z","end":"2026-08-24T20:34:47.410833Z","steps":["trace[960608139] 'process raft request' (duration: 218.106985ms)","trace[960608139] 'compare' (duration: 28.947096ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:35:01.13122Z","caller":"traceutil/trace.go:171","msg":"trace[909845139] linearizableReadLoop","detail":"{readStateIndex:2009; appliedIndex:2008; }","duration":"281.695049ms","start":"2026-08-24T20:35:00.849508Z","end":"2026-08-24T20:35:01.131203Z","steps":["trace[909845139] 'read index received' (duration: 281.475474ms)","trace[909845139] 'applied index is now lower than readState.Index' (duration: 218.625µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:35:01.131256Z","caller":"traceutil/trace.go:171","msg":"trace[1018276617] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"368.450885ms","start":"2026-08-24T20:35:00.762779Z","end":"2026-08-24T20:35:01.131229Z","steps":["trace[1018276617] 'process raft request' (duration: 368.267141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:01.131372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.845983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T20:35:01.131395Z","caller":"traceutil/trace.go:171","msg":"trace[1450156974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1932; }","duration":"281.914115ms","start":"2026-08-24T20:35:00.849473Z","end":"2026-08-24T20:35:01.131387Z","steps":["trace[1450156974] 'agreement among raft nodes before linearized reading' (duration: 281.820423ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:01.131544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.928529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T20:35:01.131591Z","caller":"traceutil/trace.go:171","msg":"trace[959704407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1932; }","duration":"263.995801ms","start":"2026-08-24T20:35:00.867582Z","end":"2026-08-24T20:35:01.131578Z","steps":["trace[959704407] 'agreement among raft nodes before linearized reading' (duration: 263.848407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:01.131636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.027833ms","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-08-24T20:35:01.131653Z","caller":"traceutil/trace.go:171","msg":"trace[1876697468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1932; }","duration":"262.063974ms","start":"2026-08-24T20:35:00.869584Z","end":"2026-08-24T20:35:01.131648Z","steps":["trace[1876697468] 'agreement among raft nodes before linearized reading' (duration: 262.020083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:01.13302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.504799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-24T20:35:01.133064Z","caller":"traceutil/trace.go:171","msg":"trace[3524690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1932; }","duration":"263.57079ms","start":"2026-08-24T20:35:00.869481Z","end":"2026-08-24T20:35:01.133052Z","steps":["trace[3524690] 'agreement among raft nodes before linearized reading' (duration: 262.276269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:01.131395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:35:00.762757Z","time spent":"368.562507ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:35:21.375583Z","caller":"traceutil/trace.go:171","msg":"trace[2147185280] linearizableReadLoop","detail":"{readStateIndex:2138; appliedIndex:2137; }","duration":"133.093087ms","start":"2026-08-24T20:35:21.242466Z","end":"2026-08-24T20:35:21.375559Z","steps":["trace[2147185280] 'read index received' (duration: 132.936864ms)","trace[2147185280] 'applied index is now lower than readState.Index' (duration: 155.463µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:35:21.375703Z","caller":"traceutil/trace.go:171","msg":"trace[1860849048] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"154.966367ms","start":"2026-08-24T20:35:21.220722Z","end":"2026-08-24T20:35:21.375689Z","steps":["trace[1860849048] 'process raft request' (duration: 154.584299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:21.375988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.95322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-24T20:35:21.37604Z","caller":"traceutil/trace.go:171","msg":"trace[1972286674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2057; }","duration":"124.038601ms","start":"2026-08-24T20:35:21.251986Z","end":"2026-08-24T20:35:21.376025Z","steps":["trace[1972286674] 'agreement among raft nodes before linearized reading' (duration: 123.859677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:21.376088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.241723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-24T20:35:21.376193Z","caller":"traceutil/trace.go:171","msg":"trace[1249446489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2057; }","duration":"128.431137ms","start":"2026-08-24T20:35:21.247745Z","end":"2026-08-24T20:35:21.376176Z","steps":["trace[1249446489] 'agreement among raft nodes before linearized reading' (duration: 128.019088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:21.376344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.215284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-08-24T20:35:21.37641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.941865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-24T20:35:21.376479Z","caller":"traceutil/trace.go:171","msg":"trace[1022001140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2057; }","duration":"134.048476ms","start":"2026-08-24T20:35:21.242415Z","end":"2026-08-24T20:35:21.376464Z","steps":["trace[1022001140] 'agreement among raft nodes before linearized reading' (duration: 133.851383ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:35:21.376584Z","caller":"traceutil/trace.go:171","msg":"trace[1634354754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2057; }","duration":"126.652633ms","start":"2026-08-24T20:35:21.24979Z","end":"2026-08-24T20:35:21.376443Z","steps":["trace[1634354754] 'agreement among raft nodes before linearized reading' (duration: 126.148332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:35:21.918249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.216036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:35:21.918418Z","caller":"traceutil/trace.go:171","msg":"trace[476841452] linearizableReadLoop","detail":"{readStateIndex:2144; appliedIndex:2143; }","duration":"288.988863ms","start":"2026-08-24T20:35:21.629406Z","end":"2026-08-24T20:35:21.918394Z","steps":["trace[476841452] 'read index received' (duration: 93.41084ms)","trace[476841452] 'applied index is now lower than readState.Index' (duration: 195.576313ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:35:21.918547Z","caller":"traceutil/trace.go:171","msg":"trace[1954769052] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"370.563691ms","start":"2026-08-24T20:35:21.547965Z","end":"2026-08-24T20:35:21.918529Z","steps":["trace[1954769052] 'process raft request' (duration: 174.959908ms)","trace[1954769052] 'compare' (duration: 195.061023ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:35:21.918644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:35:21.547946Z","time spent":"370.648973ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:35:21.918792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.35843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:35:21.918963Z","caller":"traceutil/trace.go:171","msg":"trace[1995113163] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2063; }","duration":"289.570874ms","start":"2026-08-24T20:35:21.629368Z","end":"2026-08-24T20:35:21.918939Z","steps":["trace[1995113163] 'agreement among raft nodes before linearized reading' (duration: 289.313449ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:35:22.159105Z","caller":"traceutil/trace.go:171","msg":"trace[1143324637] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"235.538414ms","start":"2026-08-24T20:35:21.92353Z","end":"2026-08-24T20:35:22.159068Z","steps":["trace[1143324637] 'process raft request' (duration: 146.029608ms)","trace[1143324637] 'compare' (duration: 89.273291ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:35:22.159144Z","caller":"traceutil/trace.go:171","msg":"trace[1110102826] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"235.535913ms","start":"2026-08-24T20:35:21.923556Z","end":"2026-08-24T20:35:22.159092Z","steps":["trace[1110102826] 'process raft request' (duration: 235.436501ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:35:22.159359Z","caller":"traceutil/trace.go:171","msg":"trace[1217113946] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"124.204338ms","start":"2026-08-24T20:35:22.035134Z","end":"2026-08-24T20:35:22.159339Z","steps":["trace[1217113946] 'process raft request' (duration: 123.921413ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:35:22.159267Z","caller":"traceutil/trace.go:171","msg":"trace[1773077860] linearizableReadLoop","detail":"{readStateIndex:2147; appliedIndex:2144; }","duration":"101.747723ms","start":"2026-08-24T20:35:22.057484Z","end":"2026-08-24T20:35:22.159232Z","steps":["trace[1773077860] 'read index received' (duration: 12.083854ms)","trace[1773077860] 'applied index is now lower than readState.Index' (duration: 89.661889ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:35:22.159593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.093629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-24T20:35:22.159659Z","caller":"traceutil/trace.go:171","msg":"trace[813343910] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2066; }","duration":"102.211682ms","start":"2026-08-24T20:35:22.057428Z","end":"2026-08-24T20:35:22.15964Z","steps":["trace[813343910] 'agreement among raft nodes before linearized reading' (duration: 101.982097ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:35:22.443515Z","caller":"traceutil/trace.go:171","msg":"trace[820152106] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"279.448859ms","start":"2026-08-24T20:35:22.164037Z","end":"2026-08-24T20:35:22.443486Z","steps":["trace[820152106] 'process raft request' (duration: 279.175263ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:36:26.08907Z","caller":"traceutil/trace.go:171","msg":"trace[129688847] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"328.44226ms","start":"2026-08-24T20:36:25.760604Z","end":"2026-08-24T20:36:26.089046Z","steps":["trace[129688847] 'process raft request' (duration: 328.330629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:36:26.089259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:36:25.760593Z","time spent":"328.553361ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:36:26.091132Z","caller":"traceutil/trace.go:171","msg":"trace[1455372803] linearizableReadLoop","detail":"{readStateIndex:2607; appliedIndex:2606; }","duration":"317.975262ms","start":"2026-08-24T20:36:25.773134Z","end":"2026-08-24T20:36:26.091109Z","steps":["trace[1455372803] 'read index received' (duration: 315.98495ms)","trace[1455372803] 'applied index is now lower than readState.Index' (duration: 1.986962ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:36:26.091289Z","caller":"traceutil/trace.go:171","msg":"trace[121429060] transaction","detail":"{read_only:false; response_revision:2511; number_of_response:1; }","duration":"329.341971ms","start":"2026-08-24T20:36:25.761887Z","end":"2026-08-24T20:36:26.091229Z","steps":["trace[121429060] 'process raft request' (duration: 329.106968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:36:26.091363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.212055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T20:36:26.091401Z","caller":"traceutil/trace.go:171","msg":"trace[834527832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2511; }","duration":"318.299746ms","start":"2026-08-24T20:36:25.773088Z","end":"2026-08-24T20:36:26.091388Z","steps":["trace[834527832] 'agreement among raft nodes before linearized reading' (duration: 318.140235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:36:26.091434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:36:25.773068Z","time spent":"318.357997ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T20:36:26.091422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.399645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-24T20:36:26.091445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:36:25.76187Z","time spent":"329.488042ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T20:36:26.091503Z","caller":"traceutil/trace.go:171","msg":"trace[739377299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2511; }","duration":"316.493165ms","start":"2026-08-24T20:36:25.774987Z","end":"2026-08-24T20:36:26.09148Z","steps":["trace[739377299] 'agreement among raft nodes before linearized reading' (duration: 316.298603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:36:26.091553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:36:25.774979Z","time spent":"316.560996ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-24T20:37:00.404692Z","caller":"traceutil/trace.go:171","msg":"trace[1384517796] linearizableReadLoop","detail":"{readStateIndex:2803; appliedIndex:2802; }","duration":"112.379481ms","start":"2026-08-24T20:37:00.292283Z","end":"2026-08-24T20:37:00.404662Z","steps":["trace[1384517796] 'read index received' (duration: 112.29315ms)","trace[1384517796] 'applied index is now lower than readState.Index' (duration: 85.191µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:37:00.404815Z","caller":"traceutil/trace.go:171","msg":"trace[2068036377] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"116.653861ms","start":"2026-08-24T20:37:00.288121Z","end":"2026-08-24T20:37:00.404775Z","steps":["trace[2068036377] 'process raft request' (duration: 116.343528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:37:00.405273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.947996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-24T20:37:00.405363Z","caller":"traceutil/trace.go:171","msg":"trace[899638715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2699; }","duration":"113.085297ms","start":"2026-08-24T20:37:00.292256Z","end":"2026-08-24T20:37:00.405341Z","steps":["trace[899638715] 'agreement among raft nodes before linearized reading' (duration: 112.530062ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:37:00.683494Z","caller":"traceutil/trace.go:171","msg":"trace[229657294] linearizableReadLoop","detail":"{readStateIndex:2804; appliedIndex:2803; }","duration":"278.62718ms","start":"2026-08-24T20:37:00.404842Z","end":"2026-08-24T20:37:00.683469Z","steps":["trace[229657294] 'read index received' (duration: 193.404681ms)","trace[229657294] 'applied index is now lower than readState.Index' (duration: 85.221439ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:37:00.683564Z","caller":"traceutil/trace.go:171","msg":"trace[2024287338] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"384.687331ms","start":"2026-08-24T20:37:00.298836Z","end":"2026-08-24T20:37:00.683523Z","steps":["trace[2024287338] 'process raft request' (duration: 299.294191ms)","trace[2024287338] 'compare' (duration: 85.218329ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:37:00.683851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.633329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T20:37:00.6839Z","caller":"traceutil/trace.go:171","msg":"trace[2095552591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2700; }","duration":"389.71028ms","start":"2026-08-24T20:37:00.294177Z","end":"2026-08-24T20:37:00.683887Z","steps":["trace[2095552591] 'agreement among raft nodes before linearized reading' (duration: 389.395436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:37:00.683934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:37:00.294169Z","time spent":"389.75721ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T20:37:00.683821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:37:00.298824Z","time spent":"384.822932ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:37:00.684273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.060634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:37:00.684358Z","caller":"traceutil/trace.go:171","msg":"trace[1253256406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2700; }","duration":"333.178615ms","start":"2026-08-24T20:37:00.351161Z","end":"2026-08-24T20:37:00.684339Z","steps":["trace[1253256406] 'agreement among raft nodes before linearized reading' (duration: 333.027653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:37:00.684407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:37:00.35115Z","time spent":"333.248765ms","remote":"127.0.0.1:48308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T20:37:00.685772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.772398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T20:37:00.685804Z","caller":"traceutil/trace.go:171","msg":"trace[2139507986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2700; }","duration":"390.818659ms","start":"2026-08-24T20:37:00.294979Z","end":"2026-08-24T20:37:00.685797Z","steps":["trace[2139507986] 'agreement among raft nodes before linearized reading' (duration: 389.101273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:37:00.685821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:37:00.294972Z","time spent":"390.845129ms","remote":"127.0.0.1:48568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-24T20:37:00.687478Z","caller":"traceutil/trace.go:171","msg":"trace[140998512] transaction","detail":"{read_only:false; response_revision:2701; number_of_response:1; }","duration":"277.367889ms","start":"2026-08-24T20:37:00.410093Z","end":"2026-08-24T20:37:00.68746Z","steps":["trace[140998512] 'process raft request' (duration: 276.995455ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:37:00.687706Z","caller":"traceutil/trace.go:171","msg":"trace[1559650026] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"152.663004ms","start":"2026-08-24T20:37:00.534987Z","end":"2026-08-24T20:37:00.68765Z","steps":["trace[1559650026] 'process raft request' (duration: 152.395642ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:37:00.68782Z","caller":"traceutil/trace.go:171","msg":"trace[353658542] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"227.534697ms","start":"2026-08-24T20:37:00.460276Z","end":"2026-08-24T20:37:00.68781Z","steps":["trace[353658542] 'process raft request' (duration: 226.971722ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:46.012447Z","caller":"traceutil/trace.go:171","msg":"trace[637471970] transaction","detail":"{read_only:false; response_revision:4499; number_of_response:1; }","duration":"126.619415ms","start":"2026-08-24T20:40:45.885797Z","end":"2026-08-24T20:40:46.012417Z","steps":["trace[637471970] 'process raft request' (duration: 126.43503ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:49.751151Z","caller":"traceutil/trace.go:171","msg":"trace[18317344] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"135.605962ms","start":"2026-08-24T20:40:49.615519Z","end":"2026-08-24T20:40:49.751125Z","steps":["trace[18317344] 'process raft request' (duration: 135.423158ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:40:49.775335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.482383ms","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-08-24T20:40:49.775395Z","caller":"traceutil/trace.go:171","msg":"trace[1878412533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4597; }","duration":"108.569636ms","start":"2026-08-24T20:40:49.666813Z","end":"2026-08-24T20:40:49.775382Z","steps":["trace[1878412533] 'agreement among raft nodes before linearized reading' (duration: 108.417513ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:49.77542Z","caller":"traceutil/trace.go:171","msg":"trace[1598619121] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"113.941298ms","start":"2026-08-24T20:40:49.661453Z","end":"2026-08-24T20:40:49.775395Z","steps":["trace[1598619121] 'process raft request' (duration: 113.696523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:40:49.775662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.220922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-7d6hd-tls\" ","response":"range_response_count:1 size:1966"} {"level":"info","ts":"2026-08-24T20:40:49.775736Z","caller":"traceutil/trace.go:171","msg":"trace[176130056] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-7d6hd-tls; range_end:; response_count:1; response_revision:4597; }","duration":"103.343185ms","start":"2026-08-24T20:40:49.67237Z","end":"2026-08-24T20:40:49.775713Z","steps":["trace[176130056] 'agreement among raft nodes before linearized reading' (duration: 103.125621ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:53.867982Z","caller":"traceutil/trace.go:171","msg":"trace[198711599] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"102.125506ms","start":"2026-08-24T20:40:53.765832Z","end":"2026-08-24T20:40:53.867958Z","steps":["trace[198711599] 'process raft request' (duration: 72.508477ms)","trace[198711599] 'compare' (duration: 29.508777ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:07.875556Z","caller":"traceutil/trace.go:171","msg":"trace[383538180] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"121.929764ms","start":"2026-08-24T20:41:07.753595Z","end":"2026-08-24T20:41:07.875524Z","steps":["trace[383538180] 'process raft request' (duration: 85.165483ms)","trace[383538180] 'compare' (duration: 36.577258ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:43.550411Z","caller":"traceutil/trace.go:171","msg":"trace[1441874154] linearizableReadLoop","detail":"{readStateIndex:5422; appliedIndex:5421; }","duration":"174.406543ms","start":"2026-08-24T20:41:43.375987Z","end":"2026-08-24T20:41:43.550393Z","steps":["trace[1441874154] 'read index received' (duration: 174.231901ms)","trace[1441874154] 'applied index is now lower than readState.Index' (duration: 173.942µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:43.550646Z","caller":"traceutil/trace.go:171","msg":"trace[614189508] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"177.674896ms","start":"2026-08-24T20:41:43.372959Z","end":"2026-08-24T20:41:43.550634Z","steps":["trace[614189508] 'process raft request' (duration: 177.28603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:43.550955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.954222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:41:43.550991Z","caller":"traceutil/trace.go:171","msg":"trace[871550857] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5225; }","duration":"175.018113ms","start":"2026-08-24T20:41:43.375964Z","end":"2026-08-24T20:41:43.550982Z","steps":["trace[871550857] 'agreement among raft nodes before linearized reading' (duration: 174.894551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:43.551102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.174299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:41:43.551173Z","caller":"traceutil/trace.go:171","msg":"trace[1726300693] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5225; }","duration":"174.274971ms","start":"2026-08-24T20:41:43.376881Z","end":"2026-08-24T20:41:43.551156Z","steps":["trace[1726300693] 'agreement among raft nodes before linearized reading' (duration: 174.148529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:43.551357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.743386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-08-24T20:41:43.551465Z","caller":"traceutil/trace.go:171","msg":"trace[1046970115] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5225; }","duration":"172.93196ms","start":"2026-08-24T20:41:43.37851Z","end":"2026-08-24T20:41:43.551442Z","steps":["trace[1046970115] 'agreement among raft nodes before linearized reading' (duration: 172.547914ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:56.587369Z","caller":"traceutil/trace.go:171","msg":"trace[1485548019] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"115.747113ms","start":"2026-08-24T20:41:56.471597Z","end":"2026-08-24T20:41:56.587344Z","steps":["trace[1485548019] 'process raft request' (duration: 85.964936ms)","trace[1485548019] 'compare' (duration: 29.590464ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:56.587651Z","caller":"traceutil/trace.go:171","msg":"trace[558154871] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"115.537199ms","start":"2026-08-24T20:41:56.472076Z","end":"2026-08-24T20:41:56.587613Z","steps":["trace[558154871] 'process raft request' (duration: 115.224534ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:56.588335Z","caller":"traceutil/trace.go:171","msg":"trace[895460928] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"116.212689ms","start":"2026-08-24T20:41:56.472092Z","end":"2026-08-24T20:41:56.588305Z","steps":["trace[895460928] 'process raft request' (duration: 115.395317ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:42:33.949992Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2877} {"level":"info","ts":"2026-08-24T20:42:34.029683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2877,"took":"78.090053ms","hash":964356915,"current-db-size-bytes":53739520,"current-db-size":"54 MB","current-db-size-in-use-bytes":29614080,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-24T20:42:34.029727Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":964356915,"revision":2877,"compact-revision":-1} {"level":"info","ts":"2026-08-24T20:44:29.391569Z","caller":"traceutil/trace.go:171","msg":"trace[1662348496] transaction","detail":"{read_only:false; response_revision:6402; number_of_response:1; }","duration":"117.993454ms","start":"2026-08-24T20:44:29.273543Z","end":"2026-08-24T20:44:29.391536Z","steps":["trace[1662348496] 'process raft request' (duration: 55.895969ms)","trace[1662348496] 'compare' (duration: 61.743081ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:44:54.32111Z","caller":"traceutil/trace.go:171","msg":"trace[896401198] transaction","detail":"{read_only:false; response_revision:6586; number_of_response:1; }","duration":"162.850236ms","start":"2026-08-24T20:44:54.158241Z","end":"2026-08-24T20:44:54.321091Z","steps":["trace[896401198] 'process raft request' (duration: 162.748025ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:44:54.466756Z","caller":"traceutil/trace.go:171","msg":"trace[346265192] transaction","detail":"{read_only:false; response_revision:6587; number_of_response:1; }","duration":"105.283331ms","start":"2026-08-24T20:44:54.361445Z","end":"2026-08-24T20:44:54.466728Z","steps":["trace[346265192] 'process raft request' (duration: 56.309369ms)","trace[346265192] 'compare' (duration: 48.606318ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:47:33.956542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5616} {"level":"info","ts":"2026-08-24T20:47:34.030122Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5616,"took":"72.202959ms","hash":1762917435,"current-db-size-bytes":53739520,"current-db-size":"54 MB","current-db-size-in-use-bytes":18259968,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-24T20:47:34.030165Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1762917435,"revision":5616,"compact-revision":2877}