{"level":"warn","ts":"2026-08-04T15:16:57.891712Z","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-04T15:16:57.891826Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-04T15:16:57.891908Z","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-04T15:16:57.891926Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-08-04T15:16:57.89195Z","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-04T15:16:57.892794Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-08-04T15:16:57.892901Z","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://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-04T15:16:57.913101Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.441498ms"} {"level":"info","ts":"2026-08-04T15:16:57.919733Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-08-04T15:16:57.919808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-08-04T15:16:57.91986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-08-04T15:16:57.919878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-04T15:16:57.919896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-08-04T15:16:57.919963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-08-04T15:16:57.924799Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-04T15:16:57.926567Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-04T15:16:57.928073Z","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-04T15:16:57.930798Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-04T15:16:57.930965Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-04T15:16:57.931075Z","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-04T15:16:57.931175Z","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-04T15:16:57.9312Z","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-04T15:16:57.932044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-08-04T15:16:57.932186Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-08-04T15:16:57.935743Z","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-04T15:16:57.936011Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-04T15:16:57.936083Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-04T15:16:57.936015Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-08-04T15:16:57.936206Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-08-04T15:16:58.920901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-08-04T15:16:58.920993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-04T15:16:58.92107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-08-04T15:16:58.921099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-08-04T15:16:58.921115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-08-04T15:16:58.921137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-08-04T15:16:58.921157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-08-04T15:16:58.922401Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-08-04T15:16:58.922453Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:16:58.922509Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:16:58.922627Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:16:58.923712Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:16:58.923859Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:16:58.923908Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:16:58.925537Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-04T15:16:58.926117Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-08-04T15:16:58.926437Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-04T15:16:58.926502Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-04T15:17:08.171441Z","caller":"traceutil/trace.go:171","msg":"trace[1607854449] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:338; }","duration":"157.61683ms","start":"2026-08-04T15:17:08.013802Z","end":"2026-08-04T15:17:08.171419Z","steps":["trace[1607854449] 'read index received' (duration: 157.458857ms)","trace[1607854449] 'applied index is now lower than readState.Index' (duration: 156.773µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:17:08.171576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.762343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-04T15:17:08.17163Z","caller":"traceutil/trace.go:171","msg":"trace[1246668848] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:330; }","duration":"157.869495ms","start":"2026-08-04T15:17:08.013751Z","end":"2026-08-04T15:17:08.171621Z","steps":["trace[1246668848] 'agreement among raft nodes before linearized reading' (duration: 157.766953ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:17:08.171818Z","caller":"traceutil/trace.go:171","msg":"trace[10446904] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"188.446067ms","start":"2026-08-04T15:17:07.983345Z","end":"2026-08-04T15:17:08.171791Z","steps":["trace[10446904] 'process raft request' (duration: 187.922905ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:17:18.921856Z","caller":"traceutil/trace.go:171","msg":"trace[2080785234] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"116.326206ms","start":"2026-08-04T15:17:18.805488Z","end":"2026-08-04T15:17:18.921814Z","steps":["trace[2080785234] 'process raft request' (duration: 54.623302ms)","trace[2080785234] 'compare' (duration: 61.52246ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:17:45.86557Z","caller":"traceutil/trace.go:171","msg":"trace[1759027409] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"114.443249ms","start":"2026-08-04T15:17:45.751072Z","end":"2026-08-04T15:17:45.865515Z","steps":["trace[1759027409] 'process raft request' (duration: 112.635826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:17:46.15576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.69789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:17:46.155842Z","caller":"traceutil/trace.go:171","msg":"trace[551877185] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"215.415732ms","start":"2026-08-04T15:17:45.940414Z","end":"2026-08-04T15:17:46.15583Z","steps":["trace[551877185] 'compare' (duration: 204.093147ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:17:47.110215Z","caller":"traceutil/trace.go:171","msg":"trace[661584132] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"224.915986ms","start":"2026-08-04T15:17:46.885274Z","end":"2026-08-04T15:17:47.11019Z","steps":["trace[661584132] 'read index received' (duration: 222.94906ms)","trace[661584132] 'applied index is now lower than readState.Index' (duration: 1.965896ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:17:47.110272Z","caller":"traceutil/trace.go:171","msg":"trace[2141021417] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"562.241583ms","start":"2026-08-04T15:17:46.547998Z","end":"2026-08-04T15:17:47.110239Z","steps":["trace[2141021417] 'process raft request' (duration: 560.634056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:17:47.110577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:17:46.54797Z","time spent":"562.391776ms","remote":"127.0.0.1:57140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:17:47.110705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.414257ms","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-08-04T15:17:47.110774Z","caller":"traceutil/trace.go:171","msg":"trace[464073417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"225.5164ms","start":"2026-08-04T15:17:46.885243Z","end":"2026-08-04T15:17:47.11076Z","steps":["trace[464073417] 'agreement among raft nodes before linearized reading' (duration: 225.12328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:18:02.28052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.861656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-04T15:18:02.280653Z","caller":"traceutil/trace.go:171","msg":"trace[418720684] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:760; }","duration":"266.03508ms","start":"2026-08-04T15:18:02.014591Z","end":"2026-08-04T15:18:02.280626Z","steps":["trace[418720684] 'range keys from in-memory index tree' (duration: 265.684353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:18:02.280573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.33997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-04T15:18:02.280747Z","caller":"traceutil/trace.go:171","msg":"trace[490247923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"294.553605ms","start":"2026-08-04T15:18:01.986175Z","end":"2026-08-04T15:18:02.280729Z","steps":["trace[490247923] 'range keys from in-memory index tree' (duration: 294.056816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:18:02.280801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.173066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-85g75\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-08-04T15:18:02.280925Z","caller":"traceutil/trace.go:171","msg":"trace[400313552] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-85g75; range_end:; response_count:1; response_revision:760; }","duration":"370.38476ms","start":"2026-08-04T15:18:01.910514Z","end":"2026-08-04T15:18:02.280899Z","steps":["trace[400313552] 'range keys from in-memory index tree' (duration: 369.972782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:18:02.280986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:18:01.910498Z","time spent":"370.473962ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-85g75\" "} {"level":"warn","ts":"2026-08-04T15:18:32.184466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.136694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-04T15:18:32.184559Z","caller":"traceutil/trace.go:171","msg":"trace[796186164] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:1167; }","duration":"168.295777ms","start":"2026-08-04T15:18:32.016245Z","end":"2026-08-04T15:18:32.184541Z","steps":["trace[796186164] 'range keys from in-memory index tree' (duration: 167.94239ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:18:48.248211Z","caller":"traceutil/trace.go:171","msg":"trace[1090900465] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"123.091872ms","start":"2026-08-04T15:18:48.125095Z","end":"2026-08-04T15:18:48.248187Z","steps":["trace[1090900465] 'process raft request' (duration: 122.940799ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:19.584399Z","caller":"traceutil/trace.go:171","msg":"trace[1522342810] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1910; }","duration":"313.067129ms","start":"2026-08-04T15:19:19.271302Z","end":"2026-08-04T15:19:19.584369Z","steps":["trace[1522342810] 'read index received' (duration: 312.890285ms)","trace[1522342810] 'applied index is now lower than readState.Index' (duration: 175.264µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:19:19.584527Z","caller":"traceutil/trace.go:171","msg":"trace[1051800986] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"320.796875ms","start":"2026-08-04T15:19:19.263698Z","end":"2026-08-04T15:19:19.584494Z","steps":["trace[1051800986] 'process raft request' (duration: 320.497478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:19.584687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:19.263681Z","time spent":"320.916937ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:19:19.584694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.358166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-826f1f92-1379-4551-85ee-b4e04abb8617\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-04T15:19:19.584748Z","caller":"traceutil/trace.go:171","msg":"trace[501566126] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-826f1f92-1379-4551-85ee-b4e04abb8617; range_end:; response_count:1; response_revision:1841; }","duration":"313.459939ms","start":"2026-08-04T15:19:19.271271Z","end":"2026-08-04T15:19:19.584731Z","steps":["trace[501566126] 'agreement among raft nodes before linearized reading' (duration: 313.257494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:19.584797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:19.271255Z","time spent":"313.52832ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-826f1f92-1379-4551-85ee-b4e04abb8617\" "} {"level":"warn","ts":"2026-08-04T15:19:19.679551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.983788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:19:19.679578Z","caller":"traceutil/trace.go:171","msg":"trace[126062016] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"180.033033ms","start":"2026-08-04T15:19:19.499515Z","end":"2026-08-04T15:19:19.679548Z","steps":["trace[126062016] 'process raft request' (duration: 163.726312ms)","trace[126062016] 'compare' (duration: 16.088955ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:19:19.679628Z","caller":"traceutil/trace.go:171","msg":"trace[1796256396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1842; }","duration":"336.110121ms","start":"2026-08-04T15:19:19.343502Z","end":"2026-08-04T15:19:19.679612Z","steps":["trace[1796256396] 'agreement among raft nodes before linearized reading' (duration: 335.969598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:19.679664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:19.343489Z","time spent":"336.167162ms","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-04T15:19:19.67968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.750015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-04T15:19:19.679777Z","caller":"traceutil/trace.go:171","msg":"trace[2032407706] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1842; }","duration":"166.893777ms","start":"2026-08-04T15:19:19.51286Z","end":"2026-08-04T15:19:19.679754Z","steps":["trace[2032407706] 'agreement among raft nodes before linearized reading' (duration: 166.624971ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:19.757631Z","caller":"traceutil/trace.go:171","msg":"trace[619011695] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"164.767335ms","start":"2026-08-04T15:19:19.592837Z","end":"2026-08-04T15:19:19.757604Z","steps":["trace[619011695] 'process raft request' (duration: 164.567981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:19.757737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.066002ms","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-04T15:19:19.757797Z","caller":"traceutil/trace.go:171","msg":"trace[1847947205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1843; }","duration":"172.140284ms","start":"2026-08-04T15:19:19.585642Z","end":"2026-08-04T15:19:19.757782Z","steps":["trace[1847947205] 'agreement among raft nodes before linearized reading' (duration: 171.932918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:19.757887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.167002ms","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-04T15:19:19.758002Z","caller":"traceutil/trace.go:171","msg":"trace[1796859426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1843; }","duration":"169.328066ms","start":"2026-08-04T15:19:19.588651Z","end":"2026-08-04T15:19:19.757979Z","steps":["trace[1796859426] 'agreement among raft nodes before linearized reading' (duration: 168.991298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:19.758383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.901868ms","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-08-04T15:19:19.758504Z","caller":"traceutil/trace.go:171","msg":"trace[389304411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1843; }","duration":"172.073252ms","start":"2026-08-04T15:19:19.586405Z","end":"2026-08-04T15:19:19.758478Z","steps":["trace[389304411] 'agreement among raft nodes before linearized reading' (duration: 171.616141ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:20.210627Z","caller":"traceutil/trace.go:171","msg":"trace[911154249] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"389.53508ms","start":"2026-08-04T15:19:19.821067Z","end":"2026-08-04T15:19:20.210603Z","steps":["trace[911154249] 'process raft request' (duration: 389.322835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:20.210815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:19.821047Z","time spent":"389.668284ms","remote":"127.0.0.1:57222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:19:20.210975Z","caller":"traceutil/trace.go:171","msg":"trace[2059945357] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"389.788096ms","start":"2026-08-04T15:19:19.821156Z","end":"2026-08-04T15:19:20.210944Z","steps":["trace[2059945357] 'process raft request' (duration: 389.383757ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:20.211021Z","caller":"traceutil/trace.go:171","msg":"trace[523124259] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"388.640568ms","start":"2026-08-04T15:19:19.822347Z","end":"2026-08-04T15:19:20.210988Z","steps":["trace[523124259] 'process raft request' (duration: 388.563546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:20.211146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:19.821136Z","time spent":"389.92021ms","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:19:20.211158Z","caller":"traceutil/trace.go:171","msg":"trace[1317991772] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"389.50072ms","start":"2026-08-04T15:19:19.821645Z","end":"2026-08-04T15:19:20.211146Z","steps":["trace[1317991772] 'process raft request' (duration: 389.149122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:20.211226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:19.821621Z","time spent":"389.571851ms","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:19:20.211222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:19:19.822333Z","time spent":"388.782221ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:19:31.890492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.198619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-04T15:19:31.890583Z","caller":"traceutil/trace.go:171","msg":"trace[1962282918] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1950; }","duration":"125.334562ms","start":"2026-08-04T15:19:31.765227Z","end":"2026-08-04T15:19:31.890562Z","steps":["trace[1962282918] 'range keys from in-memory index tree' (duration: 125.033375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:36.064037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.149057ms","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-04T15:19:36.064085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.625064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-04T15:19:36.064138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.793952ms","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-04T15:19:36.064197Z","caller":"traceutil/trace.go:171","msg":"trace[2117005171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1982; }","duration":"112.892894ms","start":"2026-08-04T15:19:35.95129Z","end":"2026-08-04T15:19:36.064182Z","steps":["trace[2117005171] 'range keys from in-memory index tree' (duration: 112.648858ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:36.064198Z","caller":"traceutil/trace.go:171","msg":"trace[2015164243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1982; }","duration":"149.798148ms","start":"2026-08-04T15:19:35.914381Z","end":"2026-08-04T15:19:36.064179Z","steps":["trace[2015164243] 'range keys from in-memory index tree' (duration: 149.414119ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:19:36.064118Z","caller":"traceutil/trace.go:171","msg":"trace[206504026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1982; }","duration":"112.257409ms","start":"2026-08-04T15:19:35.951842Z","end":"2026-08-04T15:19:36.0641Z","steps":["trace[206504026] 'range keys from in-memory index tree' (duration: 112.051975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:19:36.064334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.797445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-04T15:19:36.064421Z","caller":"traceutil/trace.go:171","msg":"trace[1583965524] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1982; }","duration":"119.186944ms","start":"2026-08-04T15:19:35.94517Z","end":"2026-08-04T15:19:36.064357Z","steps":["trace[1583965524] 'range keys from in-memory index tree' (duration: 118.610741ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:08.32374Z","caller":"traceutil/trace.go:171","msg":"trace[1737876127] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"101.328419ms","start":"2026-08-04T15:20:08.222383Z","end":"2026-08-04T15:20:08.323711Z","steps":["trace[1737876127] 'process raft request' (duration: 100.846298ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:08.578463Z","caller":"traceutil/trace.go:171","msg":"trace[1952299650] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"185.13616ms","start":"2026-08-04T15:20:08.393278Z","end":"2026-08-04T15:20:08.578414Z","steps":["trace[1952299650] 'process raft request' (duration: 148.563559ms)","trace[1952299650] 'compare' (duration: 36.357666ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:20:14.786921Z","caller":"traceutil/trace.go:171","msg":"trace[1431878095] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"176.213304ms","start":"2026-08-04T15:20:14.610672Z","end":"2026-08-04T15:20:14.786886Z","steps":["trace[1431878095] 'process raft request' (duration: 176.006859ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:14.800751Z","caller":"traceutil/trace.go:171","msg":"trace[631190548] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"174.214927ms","start":"2026-08-04T15:20:14.626516Z","end":"2026-08-04T15:20:14.80073Z","steps":["trace[631190548] 'process raft request' (duration: 174.069214ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:14.800926Z","caller":"traceutil/trace.go:171","msg":"trace[1102699042] linearizableReadLoop","detail":"{readStateIndex:2281; appliedIndex:2279; }","duration":"157.118778ms","start":"2026-08-04T15:20:14.643795Z","end":"2026-08-04T15:20:14.800914Z","steps":["trace[1102699042] 'read index received' (duration: 142.892497ms)","trace[1102699042] 'applied index is now lower than readState.Index' (duration: 14.225431ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:20:14.801105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.259262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:20:14.801147Z","caller":"traceutil/trace.go:171","msg":"trace[2076537713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2200; }","duration":"157.383324ms","start":"2026-08-04T15:20:14.643753Z","end":"2026-08-04T15:20:14.801137Z","steps":["trace[2076537713] 'agreement among raft nodes before linearized reading' (duration: 157.20845ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:54.257119Z","caller":"traceutil/trace.go:171","msg":"trace[2087116194] transaction","detail":"{read_only:false; response_revision:2583; number_of_response:1; }","duration":"105.707522ms","start":"2026-08-04T15:20:54.151368Z","end":"2026-08-04T15:20:54.257075Z","steps":["trace[2087116194] 'process raft request' (duration: 105.525618ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:54.921441Z","caller":"traceutil/trace.go:171","msg":"trace[783712817] transaction","detail":"{read_only:false; response_revision:2586; number_of_response:1; }","duration":"226.800681ms","start":"2026-08-04T15:20:54.694607Z","end":"2026-08-04T15:20:54.921407Z","steps":["trace[783712817] 'process raft request' (duration: 226.600806ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:20:54.955024Z","caller":"traceutil/trace.go:171","msg":"trace[1159905489] transaction","detail":"{read_only:false; response_revision:2587; number_of_response:1; }","duration":"206.009348ms","start":"2026-08-04T15:20:54.748977Z","end":"2026-08-04T15:20:54.954987Z","steps":["trace[1159905489] 'process raft request' (duration: 205.840414ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:28.605222Z","caller":"traceutil/trace.go:171","msg":"trace[1451595382] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"135.501058ms","start":"2026-08-04T15:21:28.469669Z","end":"2026-08-04T15:21:28.60517Z","steps":["trace[1451595382] 'process raft request' (duration: 135.14282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:29.159272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.657803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:21:29.159526Z","caller":"traceutil/trace.go:171","msg":"trace[1762914918] transaction","detail":"{read_only:false; response_revision:2794; number_of_response:1; }","duration":"254.936984ms","start":"2026-08-04T15:21:28.904539Z","end":"2026-08-04T15:21:29.159476Z","steps":["trace[1762914918] 'process raft request' (duration: 67.999775ms)","trace[1762914918] 'compare' (duration: 186.486809ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:29.294343Z","caller":"traceutil/trace.go:171","msg":"trace[1246172358] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"128.663609ms","start":"2026-08-04T15:21:29.165653Z","end":"2026-08-04T15:21:29.294317Z","steps":["trace[1246172358] 'process raft request' (duration: 128.499086ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:23.905909Z","caller":"traceutil/trace.go:171","msg":"trace[1116462567] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"102.343739ms","start":"2026-08-04T15:25:23.803536Z","end":"2026-08-04T15:25:23.90588Z","steps":["trace[1116462567] 'process raft request' (duration: 102.200465ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:24.028719Z","caller":"traceutil/trace.go:171","msg":"trace[1442986757] linearizableReadLoop","detail":"{readStateIndex:4922; appliedIndex:4920; }","duration":"130.529315ms","start":"2026-08-04T15:25:23.89816Z","end":"2026-08-04T15:25:24.028689Z","steps":["trace[1442986757] 'read index received' (duration: 7.616167ms)","trace[1442986757] 'applied index is now lower than readState.Index' (duration: 122.911708ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:24.02874Z","caller":"traceutil/trace.go:171","msg":"trace[1317738341] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"202.554947ms","start":"2026-08-04T15:25:23.826157Z","end":"2026-08-04T15:25:24.028712Z","steps":["trace[1317738341] 'process raft request' (duration: 202.358412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:24.028967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.773262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:25:24.02907Z","caller":"traceutil/trace.go:171","msg":"trace[1175093235] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:4740; }","duration":"130.943336ms","start":"2026-08-04T15:25:23.898106Z","end":"2026-08-04T15:25:24.02905Z","steps":["trace[1175093235] 'agreement among raft nodes before linearized reading' (duration: 130.722361ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:26.223036Z","caller":"traceutil/trace.go:171","msg":"trace[1033871654] linearizableReadLoop","detail":"{readStateIndex:5110; appliedIndex:5109; }","duration":"116.383097ms","start":"2026-08-04T15:25:26.106614Z","end":"2026-08-04T15:25:26.222997Z","steps":["trace[1033871654] 'read index received' (duration: 115.26199ms)","trace[1033871654] 'applied index is now lower than readState.Index' (duration: 1.119307ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:26.22309Z","caller":"traceutil/trace.go:171","msg":"trace[441449968] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"121.187357ms","start":"2026-08-04T15:25:26.101878Z","end":"2026-08-04T15:25:26.223065Z","steps":["trace[441449968] 'process raft request' (duration: 119.989689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:26.223372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.372573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-08-04T15:25:26.223447Z","caller":"traceutil/trace.go:171","msg":"trace[1441948048] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4926; }","duration":"115.471035ms","start":"2026-08-04T15:25:26.107951Z","end":"2026-08-04T15:25:26.223422Z","steps":["trace[1441948048] 'agreement among raft nodes before linearized reading' (duration: 115.25949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:26.223802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.176055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-08-04T15:25:26.22387Z","caller":"traceutil/trace.go:171","msg":"trace[608860472] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:4926; }","duration":"117.276297ms","start":"2026-08-04T15:25:26.106578Z","end":"2026-08-04T15:25:26.223854Z","steps":["trace[608860472] 'agreement among raft nodes before linearized reading' (duration: 116.548421ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:28.290938Z","caller":"traceutil/trace.go:171","msg":"trace[1326378062] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"136.87792ms","start":"2026-08-04T15:25:28.154023Z","end":"2026-08-04T15:25:28.290901Z","steps":["trace[1326378062] 'process raft request' (duration: 99.237622ms)","trace[1326378062] 'compare' (duration: 37.448694ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:28.338131Z","caller":"traceutil/trace.go:171","msg":"trace[1386315675] linearizableReadLoop","detail":"{readStateIndex:5157; appliedIndex:5155; }","duration":"168.433784ms","start":"2026-08-04T15:25:28.169672Z","end":"2026-08-04T15:25:28.338106Z","steps":["trace[1386315675] 'read index received' (duration: 83.586262ms)","trace[1386315675] 'applied index is now lower than readState.Index' (duration: 84.846102ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:28.338265Z","caller":"traceutil/trace.go:171","msg":"trace[657229039] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"174.379334ms","start":"2026-08-04T15:25:28.163873Z","end":"2026-08-04T15:25:28.338253Z","steps":["trace[657229039] 'process raft request' (duration: 174.101338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:28.338627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.934196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-29wgw\" ","response":"range_response_count:1 size:4949"} {"level":"info","ts":"2026-08-04T15:25:28.338705Z","caller":"traceutil/trace.go:171","msg":"trace[1477030640] range","detail":"{range_begin:/registry/pods/monitoring/vector-29wgw; range_end:; response_count:1; response_revision:4971; }","duration":"169.039568ms","start":"2026-08-04T15:25:28.169648Z","end":"2026-08-04T15:25:28.338687Z","steps":["trace[1477030640] 'agreement among raft nodes before linearized reading' (duration: 168.803272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:28.339068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.200589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787129"} {"level":"info","ts":"2026-08-04T15:25:28.339186Z","caller":"traceutil/trace.go:171","msg":"trace[2012637611] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4971; }","duration":"129.333522ms","start":"2026-08-04T15:25:28.209833Z","end":"2026-08-04T15:25:28.339167Z","steps":["trace[2012637611] 'agreement among raft nodes before linearized reading' (duration: 128.597345ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:40.627494Z","caller":"traceutil/trace.go:171","msg":"trace[545173791] linearizableReadLoop","detail":"{readStateIndex:5408; appliedIndex:5407; }","duration":"383.792175ms","start":"2026-08-04T15:25:40.243672Z","end":"2026-08-04T15:25:40.627465Z","steps":["trace[545173791] 'read index received' (duration: 383.517428ms)","trace[545173791] 'applied index is now lower than readState.Index' (duration: 273.267µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:40.627635Z","caller":"traceutil/trace.go:171","msg":"trace[504557299] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"388.597899ms","start":"2026-08-04T15:25:40.239025Z","end":"2026-08-04T15:25:40.627623Z","steps":["trace[504557299] 'process raft request' (duration: 388.143658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.627737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:40.239008Z","time spent":"388.655699ms","remote":"127.0.0.1:57114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":129156,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:25:40.628065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.388389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-wc6jn\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-08-04T15:25:40.62811Z","caller":"traceutil/trace.go:171","msg":"trace[838073118] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-wc6jn; range_end:; response_count:1; response_revision:5213; }","duration":"384.45972ms","start":"2026-08-04T15:25:40.243637Z","end":"2026-08-04T15:25:40.628096Z","steps":["trace[838073118] 'agreement among raft nodes before linearized reading' (duration: 384.303336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.62814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:40.24362Z","time spent":"384.513091ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7841,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-wc6jn\" "} {"level":"info","ts":"2026-08-04T15:25:40.680492Z","caller":"traceutil/trace.go:171","msg":"trace[1854615159] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"272.219323ms","start":"2026-08-04T15:25:40.408206Z","end":"2026-08-04T15:25:40.680425Z","steps":["trace[1854615159] 'process raft request' (duration: 271.752142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.680621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.195175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-04T15:25:40.680697Z","caller":"traceutil/trace.go:171","msg":"trace[1235860790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5214; }","duration":"181.324178ms","start":"2026-08-04T15:25:40.499355Z","end":"2026-08-04T15:25:40.680679Z","steps":["trace[1235860790] 'agreement among raft nodes before linearized reading' (duration: 181.096913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.684318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.03689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:25:40.684421Z","caller":"traceutil/trace.go:171","msg":"trace[224366114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5214; }","duration":"342.196473ms","start":"2026-08-04T15:25:40.342199Z","end":"2026-08-04T15:25:40.684395Z","steps":["trace[224366114] 'agreement among raft nodes before linearized reading' (duration: 338.158178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:40.684477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:40.342157Z","time spent":"342.306695ms","remote":"127.0.0.1:57026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-04T15:25:40.881492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.47177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:25:40.881641Z","caller":"traceutil/trace.go:171","msg":"trace[1521280396] linearizableReadLoop","detail":"{readStateIndex:5413; appliedIndex:5409; }","duration":"195.083892ms","start":"2026-08-04T15:25:40.686544Z","end":"2026-08-04T15:25:40.881628Z","steps":["trace[1521280396] 'read index received' (duration: 30.731575ms)","trace[1521280396] 'applied index is now lower than readState.Index' (duration: 164.350877ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:25:40.88177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.224215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:25:40.881799Z","caller":"traceutil/trace.go:171","msg":"trace[2139156968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5218; }","duration":"195.280536ms","start":"2026-08-04T15:25:40.686509Z","end":"2026-08-04T15:25:40.88179Z","steps":["trace[2139156968] 'agreement among raft nodes before linearized reading' (duration: 195.203555ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:40.881645Z","caller":"traceutil/trace.go:171","msg":"trace[756788947] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"196.055305ms","start":"2026-08-04T15:25:40.685514Z","end":"2026-08-04T15:25:40.881569Z","steps":["trace[756788947] 'process raft request' (duration: 42.440722ms)","trace[756788947] 'compare' (duration: 153.382488ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:40.882194Z","caller":"traceutil/trace.go:171","msg":"trace[1610620739] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"153.512602ms","start":"2026-08-04T15:25:40.728667Z","end":"2026-08-04T15:25:40.882179Z","steps":["trace[1610620739] 'process raft request' (duration: 153.365879ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:40.882345Z","caller":"traceutil/trace.go:171","msg":"trace[646916905] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"153.552723ms","start":"2026-08-04T15:25:40.728784Z","end":"2026-08-04T15:25:40.882337Z","steps":["trace[646916905] 'process raft request' (duration: 153.274407ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:40.882431Z","caller":"traceutil/trace.go:171","msg":"trace[1658991280] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"153.516962ms","start":"2026-08-04T15:25:40.728909Z","end":"2026-08-04T15:25:40.882426Z","steps":["trace[1658991280] 'process raft request' (duration: 153.180304ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:40.882497Z","caller":"traceutil/trace.go:171","msg":"trace[846087303] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"158.657133ms","start":"2026-08-04T15:25:40.723827Z","end":"2026-08-04T15:25:40.882484Z","steps":["trace[846087303] 'process raft request' (duration: 157.755212ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:41.424875Z","caller":"traceutil/trace.go:171","msg":"trace[1380015874] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"100.88292ms","start":"2026-08-04T15:25:41.32395Z","end":"2026-08-04T15:25:41.424833Z","steps":["trace[1380015874] 'process raft request' (duration: 100.587263ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:41.425372Z","caller":"traceutil/trace.go:171","msg":"trace[1734543062] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"101.25945ms","start":"2026-08-04T15:25:41.324086Z","end":"2026-08-04T15:25:41.425346Z","steps":["trace[1734543062] 'process raft request' (duration: 100.670405ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:41.425747Z","caller":"traceutil/trace.go:171","msg":"trace[532428162] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"101.396783ms","start":"2026-08-04T15:25:41.324332Z","end":"2026-08-04T15:25:41.425728Z","steps":["trace[532428162] 'process raft request' (duration: 100.789928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:42.064702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.994697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17849"} {"level":"info","ts":"2026-08-04T15:25:42.064811Z","caller":"traceutil/trace.go:171","msg":"trace[925279154] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5230; }","duration":"300.166132ms","start":"2026-08-04T15:25:41.764622Z","end":"2026-08-04T15:25:42.064788Z","steps":["trace[925279154] 'range keys from in-memory index tree' (duration: 299.741601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:42.064856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:41.764579Z","time spent":"300.268144ms","remote":"127.0.0.1:57236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":17873,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-04T15:25:42.065551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.280013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-04T15:25:42.065586Z","caller":"traceutil/trace.go:171","msg":"trace[169637707] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5230; }","duration":"155.366975ms","start":"2026-08-04T15:25:41.910211Z","end":"2026-08-04T15:25:42.065578Z","steps":["trace[169637707] 'range keys from in-memory index tree' (duration: 155.065057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:42.065772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.199727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:25:42.065852Z","caller":"traceutil/trace.go:171","msg":"trace[917507428] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5230; }","duration":"343.33687ms","start":"2026-08-04T15:25:41.722494Z","end":"2026-08-04T15:25:42.065831Z","steps":["trace[917507428] 'count revisions from in-memory index tree' (duration: 343.119374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:42.065892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:41.722471Z","time spent":"343.410021ms","remote":"127.0.0.1:40740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:25:42.065925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.99348ms","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-04T15:25:42.065986Z","caller":"traceutil/trace.go:171","msg":"trace[2141540985] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5230; }","duration":"476.059631ms","start":"2026-08-04T15:25:41.589919Z","end":"2026-08-04T15:25:42.065979Z","steps":["trace[2141540985] 'range keys from in-memory index tree' (duration: 475.98443ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:49.52003Z","caller":"traceutil/trace.go:171","msg":"trace[436149872] transaction","detail":"{read_only:false; response_revision:5386; number_of_response:1; }","duration":"160.070177ms","start":"2026-08-04T15:25:49.359921Z","end":"2026-08-04T15:25:49.519991Z","steps":["trace[436149872] 'process raft request' (duration: 159.611146ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:49.642474Z","caller":"traceutil/trace.go:171","msg":"trace[36265258] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"112.605767ms","start":"2026-08-04T15:25:49.529823Z","end":"2026-08-04T15:25:49.642429Z","steps":["trace[36265258] 'process raft request' (duration: 112.528295ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:49.642532Z","caller":"traceutil/trace.go:171","msg":"trace[1141048766] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"116.003087ms","start":"2026-08-04T15:25:49.526487Z","end":"2026-08-04T15:25:49.64249Z","steps":["trace[1141048766] 'process raft request' (duration: 74.253812ms)","trace[1141048766] 'compare' (duration: 41.392637ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:26:02.342179Z","caller":"traceutil/trace.go:171","msg":"trace[928163161] transaction","detail":"{read_only:false; response_revision:5566; number_of_response:1; }","duration":"167.203645ms","start":"2026-08-04T15:26:02.174949Z","end":"2026-08-04T15:26:02.342152Z","steps":["trace[928163161] 'process raft request' (duration: 166.99124ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:02.6791Z","caller":"traceutil/trace.go:171","msg":"trace[1797769497] transaction","detail":"{read_only:false; response_revision:5567; number_of_response:1; }","duration":"488.075724ms","start":"2026-08-04T15:26:02.19099Z","end":"2026-08-04T15:26:02.679066Z","steps":["trace[1797769497] 'process raft request' (duration: 487.862109ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:02.679277Z","caller":"traceutil/trace.go:171","msg":"trace[1558806] linearizableReadLoop","detail":"{readStateIndex:5770; appliedIndex:5768; }","duration":"460.224158ms","start":"2026-08-04T15:26:02.219028Z","end":"2026-08-04T15:26:02.679253Z","steps":["trace[1558806] 'read index received' (duration: 122.957751ms)","trace[1558806] 'applied index is now lower than readState.Index' (duration: 337.263807ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:26:02.679313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:26:02.190966Z","time spent":"488.245389ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:26:02.6794Z","caller":"traceutil/trace.go:171","msg":"trace[1581406579] transaction","detail":"{read_only:false; response_revision:5568; number_of_response:1; }","duration":"457.695118ms","start":"2026-08-04T15:26:02.221679Z","end":"2026-08-04T15:26:02.679374Z","steps":["trace[1581406579] 'process raft request' (duration: 457.330709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:02.679502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:26:02.221665Z","time spent":"457.776991ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:26:02.679521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.482404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-04T15:26:02.679558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.445235ms","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-08-04T15:26:02.679569Z","caller":"traceutil/trace.go:171","msg":"trace[663392691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5568; }","duration":"460.569917ms","start":"2026-08-04T15:26:02.218985Z","end":"2026-08-04T15:26:02.679555Z","steps":["trace[663392691] 'agreement among raft nodes before linearized reading' (duration: 460.403403ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:02.6796Z","caller":"traceutil/trace.go:171","msg":"trace[1353161306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5568; }","duration":"427.514806ms","start":"2026-08-04T15:26:02.252072Z","end":"2026-08-04T15:26:02.679587Z","steps":["trace[1353161306] 'agreement among raft nodes before linearized reading' (duration: 427.401004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:02.679617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:26:02.218953Z","time spent":"460.651739ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-04T15:26:02.679637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:26:02.252057Z","time spent":"427.569828ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-04T15:26:02.679756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.173152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-04T15:26:02.67978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.966962ms","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-08-04T15:26:02.679818Z","caller":"traceutil/trace.go:171","msg":"trace[562057840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5568; }","duration":"336.284064ms","start":"2026-08-04T15:26:02.343518Z","end":"2026-08-04T15:26:02.679803Z","steps":["trace[562057840] 'agreement among raft nodes before linearized reading' (duration: 336.156431ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:02.679832Z","caller":"traceutil/trace.go:171","msg":"trace[1203387787] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5568; }","duration":"126.059615ms","start":"2026-08-04T15:26:02.553756Z","end":"2026-08-04T15:26:02.679815Z","steps":["trace[1203387787] 'agreement among raft nodes before linearized reading' (duration: 125.977293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:02.679815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.154103ms","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-04T15:26:02.679782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.125424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16547"} {"level":"info","ts":"2026-08-04T15:26:02.679899Z","caller":"traceutil/trace.go:171","msg":"trace[620134931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5568; }","duration":"423.295336ms","start":"2026-08-04T15:26:02.256586Z","end":"2026-08-04T15:26:02.679881Z","steps":["trace[620134931] 'agreement among raft nodes before linearized reading' (duration: 423.092231ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:02.679933Z","caller":"traceutil/trace.go:171","msg":"trace[1245679184] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5568; }","duration":"181.327798ms","start":"2026-08-04T15:26:02.498588Z","end":"2026-08-04T15:26:02.679916Z","steps":["trace[1245679184] 'agreement among raft nodes before linearized reading' (duration: 181.00246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:02.679942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:26:02.256558Z","time spent":"423.375568ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-04T15:26:02.679824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.321355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-04T15:26:02.679994Z","caller":"traceutil/trace.go:171","msg":"trace[434106691] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5568; }","duration":"233.531381ms","start":"2026-08-04T15:26:02.446457Z","end":"2026-08-04T15:26:02.679988Z","steps":["trace[434106691] 'agreement among raft nodes before linearized reading' (duration: 233.241914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:02.679884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:26:02.343498Z","time spent":"336.375496ms","remote":"127.0.0.1:57026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-04T15:26:02.68033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.244545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-04T15:26:02.68039Z","caller":"traceutil/trace.go:171","msg":"trace[512067481] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5568; }","duration":"125.344817ms","start":"2026-08-04T15:26:02.555025Z","end":"2026-08-04T15:26:02.68037Z","steps":["trace[512067481] 'agreement among raft nodes before linearized reading' (duration: 124.616421ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:02.80764Z","caller":"traceutil/trace.go:171","msg":"trace[1365704886] linearizableReadLoop","detail":"{readStateIndex:5775; appliedIndex:5774; }","duration":"112.73501ms","start":"2026-08-04T15:26:02.694879Z","end":"2026-08-04T15:26:02.807614Z","steps":["trace[1365704886] 'read index received' (duration: 92.900402ms)","trace[1365704886] 'applied index is now lower than readState.Index' (duration: 19.833128ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:26:02.807723Z","caller":"traceutil/trace.go:171","msg":"trace[23839859] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"113.299433ms","start":"2026-08-04T15:26:02.694386Z","end":"2026-08-04T15:26:02.807686Z","steps":["trace[23839859] 'process raft request' (duration: 93.490105ms)","trace[23839859] 'compare' (duration: 19.613343ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:26:02.807877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.891588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17849"} {"level":"info","ts":"2026-08-04T15:26:02.807916Z","caller":"traceutil/trace.go:171","msg":"trace[1834775524] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5572; }","duration":"119.96148ms","start":"2026-08-04T15:26:02.687945Z","end":"2026-08-04T15:26:02.807906Z","steps":["trace[1834775524] 'agreement among raft nodes before linearized reading' (duration: 119.800196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:02.807918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.747373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5f20855a-66bd-44a3-9294-4b9c9b5d5639\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-08-04T15:26:02.807996Z","caller":"traceutil/trace.go:171","msg":"trace[1264122917] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5f20855a-66bd-44a3-9294-4b9c9b5d5639; range_end:; response_count:1; response_revision:5572; }","duration":"110.844524ms","start":"2026-08-04T15:26:02.697131Z","end":"2026-08-04T15:26:02.807975Z","steps":["trace[1264122917] 'agreement among raft nodes before linearized reading' (duration: 110.63737ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:40.495414Z","caller":"traceutil/trace.go:171","msg":"trace[1339099839] transaction","detail":"{read_only:false; response_revision:5895; number_of_response:1; }","duration":"123.740239ms","start":"2026-08-04T15:26:40.371634Z","end":"2026-08-04T15:26:40.495375Z","steps":["trace[1339099839] 'process raft request' (duration: 122.930369ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:58.949622Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2957} {"level":"info","ts":"2026-08-04T15:26:59.024631Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2957,"took":"73.321877ms","hash":1013516471,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":33828864,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-04T15:26:59.024749Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1013516471,"revision":2957,"compact-revision":-1} {"level":"info","ts":"2026-08-04T15:31:58.957125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6030} {"level":"info","ts":"2026-08-04T15:31:59.074139Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6030,"took":"114.746625ms","hash":3659170549,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":21516288,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-04T15:31:59.074224Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3659170549,"revision":6030,"compact-revision":2957} {"level":"info","ts":"2026-08-04T15:32:15.089279Z","caller":"traceutil/trace.go:171","msg":"trace[1333968825] linearizableReadLoop","detail":"{readStateIndex:8631; appliedIndex:8630; }","duration":"478.900246ms","start":"2026-08-04T15:32:14.610358Z","end":"2026-08-04T15:32:15.089258Z","steps":["trace[1333968825] 'read index received' (duration: 478.763312ms)","trace[1333968825] 'applied index is now lower than readState.Index' (duration: 136.244µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:32:15.089332Z","caller":"traceutil/trace.go:171","msg":"trace[992127505] transaction","detail":"{read_only:false; response_revision:8338; number_of_response:1; }","duration":"570.672986ms","start":"2026-08-04T15:32:14.518632Z","end":"2026-08-04T15:32:15.089305Z","steps":["trace[992127505] 'process raft request' (duration: 570.488561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:15.089461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:14.518614Z","time spent":"570.774438ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:32:15.089569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.195122ms","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-04T15:32:15.089667Z","caller":"traceutil/trace.go:171","msg":"trace[1766607178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8338; }","duration":"479.386966ms","start":"2026-08-04T15:32:14.610255Z","end":"2026-08-04T15:32:15.089642Z","steps":["trace[1766607178] 'agreement among raft nodes before linearized reading' (duration: 479.09003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:15.089711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:14.610222Z","time spent":"479.480189ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-04T15:32:15.095629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.566949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-04T15:32:15.095706Z","caller":"traceutil/trace.go:171","msg":"trace[185580012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8339; }","duration":"427.689261ms","start":"2026-08-04T15:32:14.668004Z","end":"2026-08-04T15:32:15.095693Z","steps":["trace[185580012] 'agreement among raft nodes before linearized reading' (duration: 427.506877ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:15.095757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:14.667982Z","time spent":"427.766383ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-04T15:32:15.095822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.680721ms","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-08-04T15:32:15.095916Z","caller":"traceutil/trace.go:171","msg":"trace[1202727575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8339; }","duration":"427.828665ms","start":"2026-08-04T15:32:14.668064Z","end":"2026-08-04T15:32:15.095893Z","steps":["trace[1202727575] 'agreement among raft nodes before linearized reading' (duration: 427.547008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:15.095977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:14.668044Z","time spent":"427.917756ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-04T15:32:26.634062Z","caller":"traceutil/trace.go:171","msg":"trace[1070551338] transaction","detail":"{read_only:false; response_revision:8399; number_of_response:1; }","duration":"462.037072ms","start":"2026-08-04T15:32:26.172Z","end":"2026-08-04T15:32:26.634037Z","steps":["trace[1070551338] 'process raft request' (duration: 461.880138ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:26.63426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:26.171973Z","time spent":"462.175465ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:32:26.634454Z","caller":"traceutil/trace.go:171","msg":"trace[207378627] linearizableReadLoop","detail":"{readStateIndex:8694; appliedIndex:8694; }","duration":"290.894163ms","start":"2026-08-04T15:32:26.343523Z","end":"2026-08-04T15:32:26.634417Z","steps":["trace[207378627] 'read index received' (duration: 290.879542ms)","trace[207378627] 'applied index is now lower than readState.Index' (duration: 10.991µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:32:26.634632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.107907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:32:26.634677Z","caller":"traceutil/trace.go:171","msg":"trace[105967195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8399; }","duration":"291.201779ms","start":"2026-08-04T15:32:26.343462Z","end":"2026-08-04T15:32:26.634664Z","steps":["trace[105967195] 'agreement among raft nodes before linearized reading' (duration: 291.112427ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:32:27.53276Z","caller":"traceutil/trace.go:171","msg":"trace[578005904] transaction","detail":"{read_only:false; response_revision:8401; number_of_response:1; }","duration":"355.956429ms","start":"2026-08-04T15:32:27.176781Z","end":"2026-08-04T15:32:27.532738Z","steps":["trace[578005904] 'process raft request' (duration: 342.051374ms)","trace[578005904] 'compare' (duration: 13.811423ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:32:27.532886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:27.176761Z","time spent":"356.057991ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:32:27.559083Z","caller":"traceutil/trace.go:171","msg":"trace[1649320559] transaction","detail":"{read_only:false; response_revision:8402; number_of_response:1; }","duration":"382.19506ms","start":"2026-08-04T15:32:27.176854Z","end":"2026-08-04T15:32:27.559049Z","steps":["trace[1649320559] 'process raft request' (duration: 381.900283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:27.559262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:27.176833Z","time spent":"382.341584ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:32:27.559542Z","caller":"traceutil/trace.go:171","msg":"trace[737327606] transaction","detail":"{read_only:false; response_revision:8404; number_of_response:1; }","duration":"382.354824ms","start":"2026-08-04T15:32:27.177172Z","end":"2026-08-04T15:32:27.559527Z","steps":["trace[737327606] 'process raft request' (duration: 382.17491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:27.559679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:27.177151Z","time spent":"382.454345ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:32:27.55987Z","caller":"traceutil/trace.go:171","msg":"trace[624922804] linearizableReadLoop","detail":"{readStateIndex:8700; appliedIndex:8695; }","duration":"216.038506ms","start":"2026-08-04T15:32:27.343817Z","end":"2026-08-04T15:32:27.559856Z","steps":["trace[624922804] 'read index received' (duration: 175.128693ms)","trace[624922804] 'applied index is now lower than readState.Index' (duration: 40.908613ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:32:27.559982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.16674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:32:27.560018Z","caller":"traceutil/trace.go:171","msg":"trace[900552636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8405; }","duration":"216.253142ms","start":"2026-08-04T15:32:27.343756Z","end":"2026-08-04T15:32:27.560009Z","steps":["trace[900552636] 'agreement among raft nodes before linearized reading' (duration: 216.15645ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:32:27.560067Z","caller":"traceutil/trace.go:171","msg":"trace[1236237401] transaction","detail":"{read_only:false; response_revision:8405; number_of_response:1; }","duration":"382.029046ms","start":"2026-08-04T15:32:27.178023Z","end":"2026-08-04T15:32:27.560052Z","steps":["trace[1236237401] 'process raft request' (duration: 381.475023ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:32:27.560094Z","caller":"traceutil/trace.go:171","msg":"trace[507018600] transaction","detail":"{read_only:false; response_revision:8403; number_of_response:1; }","duration":"382.382084ms","start":"2026-08-04T15:32:27.177049Z","end":"2026-08-04T15:32:27.559431Z","steps":["trace[507018600] 'process raft request' (duration: 381.891533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:27.560152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:27.178009Z","time spent":"382.099228ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:32:27.560167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:27.177039Z","time spent":"383.092921ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:32:28.259242Z","caller":"traceutil/trace.go:171","msg":"trace[196136486] transaction","detail":"{read_only:false; response_revision:8408; number_of_response:1; }","duration":"118.048522ms","start":"2026-08-04T15:32:28.14116Z","end":"2026-08-04T15:32:28.259208Z","steps":["trace[196136486] 'process raft request' (duration: 40.013462ms)","trace[196136486] 'compare' (duration: 77.849255ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:32:56.474069Z","caller":"traceutil/trace.go:171","msg":"trace[1238723699] linearizableReadLoop","detail":"{readStateIndex:8916; appliedIndex:8915; }","duration":"130.185955ms","start":"2026-08-04T15:32:56.343865Z","end":"2026-08-04T15:32:56.474051Z","steps":["trace[1238723699] 'read index received' (duration: 130.033672ms)","trace[1238723699] 'applied index is now lower than readState.Index' (duration: 151.773µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:32:56.474191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.317169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:32:56.47422Z","caller":"traceutil/trace.go:171","msg":"trace[226026992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8613; }","duration":"130.36367ms","start":"2026-08-04T15:32:56.34385Z","end":"2026-08-04T15:32:56.474213Z","steps":["trace[226026992] 'agreement among raft nodes before linearized reading' (duration: 130.282088ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:32:56.474468Z","caller":"traceutil/trace.go:171","msg":"trace[201033342] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"245.596686ms","start":"2026-08-04T15:32:56.228859Z","end":"2026-08-04T15:32:56.474455Z","steps":["trace[201033342] 'process raft request' (duration: 245.095235ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:32:56.808042Z","caller":"traceutil/trace.go:171","msg":"trace[363790649] linearizableReadLoop","detail":"{readStateIndex:8917; appliedIndex:8916; }","duration":"329.102732ms","start":"2026-08-04T15:32:56.478917Z","end":"2026-08-04T15:32:56.80802Z","steps":["trace[363790649] 'read index received' (duration: 295.273594ms)","trace[363790649] 'applied index is now lower than readState.Index' (duration: 33.828418ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:32:56.808156Z","caller":"traceutil/trace.go:171","msg":"trace[1710135464] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"404.443309ms","start":"2026-08-04T15:32:56.403703Z","end":"2026-08-04T15:32:56.808146Z","steps":["trace[1710135464] 'process raft request' (duration: 370.478967ms)","trace[1710135464] 'compare' (duration: 33.43726ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:32:56.808246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:56.403673Z","time spent":"404.50204ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:32:56.808903Z","caller":"traceutil/trace.go:171","msg":"trace[1340033463] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"163.116073ms","start":"2026-08-04T15:32:56.645758Z","end":"2026-08-04T15:32:56.808874Z","steps":["trace[1340033463] 'process raft request' (duration: 162.200882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:56.80903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.108675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:32:56.809068Z","caller":"traceutil/trace.go:171","msg":"trace[1182551766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8615; }","duration":"330.177737ms","start":"2026-08-04T15:32:56.478881Z","end":"2026-08-04T15:32:56.809059Z","steps":["trace[1182551766] 'agreement among raft nodes before linearized reading' (duration: 329.44538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:56.809089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:56.478863Z","time spent":"330.221558ms","remote":"127.0.0.1:57040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-04T15:32:59.874265Z","caller":"traceutil/trace.go:171","msg":"trace[292559085] linearizableReadLoop","detail":"{readStateIndex:8932; appliedIndex:8931; }","duration":"153.268323ms","start":"2026-08-04T15:32:59.720977Z","end":"2026-08-04T15:32:59.874245Z","steps":["trace[292559085] 'read index received' (duration: 143.805162ms)","trace[292559085] 'applied index is now lower than readState.Index' (duration: 9.462321ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:32:59.874515Z","caller":"traceutil/trace.go:171","msg":"trace[1818441553] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"162.154591ms","start":"2026-08-04T15:32:59.712351Z","end":"2026-08-04T15:32:59.874505Z","steps":["trace[1818441553] 'process raft request' (duration: 152.537376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:59.874706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.704884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-04T15:32:59.874769Z","caller":"traceutil/trace.go:171","msg":"trace[46206335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8628; }","duration":"153.814436ms","start":"2026-08-04T15:32:59.720941Z","end":"2026-08-04T15:32:59.874756Z","steps":["trace[46206335] 'agreement among raft nodes before linearized reading' (duration: 153.615701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:59.874756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.632762ms","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-08-04T15:32:59.874844Z","caller":"traceutil/trace.go:171","msg":"trace[2014365059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8628; }","duration":"153.781315ms","start":"2026-08-04T15:32:59.721042Z","end":"2026-08-04T15:32:59.874823Z","steps":["trace[2014365059] 'agreement among raft nodes before linearized reading' (duration: 153.53856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:59.874844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.452035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-04T15:32:59.874979Z","caller":"traceutil/trace.go:171","msg":"trace[542148633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8628; }","duration":"152.66171ms","start":"2026-08-04T15:32:59.722303Z","end":"2026-08-04T15:32:59.874964Z","steps":["trace[542148633] 'agreement among raft nodes before linearized reading' (duration: 152.426404ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:00.829244Z","caller":"traceutil/trace.go:171","msg":"trace[1023832417] linearizableReadLoop","detail":"{readStateIndex:8937; appliedIndex:8936; }","duration":"486.810219ms","start":"2026-08-04T15:33:00.342398Z","end":"2026-08-04T15:33:00.829208Z","steps":["trace[1023832417] 'read index received' (duration: 486.652275ms)","trace[1023832417] 'applied index is now lower than readState.Index' (duration: 156.764µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:33:00.829276Z","caller":"traceutil/trace.go:171","msg":"trace[1515567630] transaction","detail":"{read_only:false; response_revision:8633; number_of_response:1; }","duration":"678.370156ms","start":"2026-08-04T15:33:00.150885Z","end":"2026-08-04T15:33:00.829256Z","steps":["trace[1515567630] 'process raft request' (duration: 678.164911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:00.82937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.971093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-04T15:33:00.829393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:33:00.150866Z","time spent":"678.470649ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:33:00.829407Z","caller":"traceutil/trace.go:171","msg":"trace[511640246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8633; }","duration":"487.050425ms","start":"2026-08-04T15:33:00.342346Z","end":"2026-08-04T15:33:00.829397Z","steps":["trace[511640246] 'agreement among raft nodes before linearized reading' (duration: 486.969763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:00.829431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:33:00.342306Z","time spent":"487.119777ms","remote":"127.0.0.1:57026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-04T15:33:00.835474Z","caller":"traceutil/trace.go:171","msg":"trace[754640886] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"349.777345ms","start":"2026-08-04T15:33:00.485678Z","end":"2026-08-04T15:33:00.835455Z","steps":["trace[754640886] 'process raft request' (duration: 349.53097ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:00.8356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:33:00.485655Z","time spent":"349.879507ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:33:10.100479Z","caller":"traceutil/trace.go:171","msg":"trace[2103628736] linearizableReadLoop","detail":"{readStateIndex:9031; appliedIndex:9030; }","duration":"188.346351ms","start":"2026-08-04T15:33:09.912109Z","end":"2026-08-04T15:33:10.100455Z","steps":["trace[2103628736] 'read index received' (duration: 105.609641ms)","trace[2103628736] 'applied index is now lower than readState.Index' (duration: 82.735409ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:33:10.100704Z","caller":"traceutil/trace.go:171","msg":"trace[969557262] transaction","detail":"{read_only:false; response_revision:8722; number_of_response:1; }","duration":"349.485879ms","start":"2026-08-04T15:33:09.7512Z","end":"2026-08-04T15:33:10.100686Z","steps":["trace[969557262] 'process raft request' (duration: 266.500324ms)","trace[969557262] 'compare' (duration: 82.601336ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:33:10.100793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:33:09.751169Z","time spent":"349.56247ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:33:10.10101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.899744ms","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-04T15:33:10.101039Z","caller":"traceutil/trace.go:171","msg":"trace[353881338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8722; }","duration":"188.964665ms","start":"2026-08-04T15:33:09.912067Z","end":"2026-08-04T15:33:10.101032Z","steps":["trace[353881338] 'agreement among raft nodes before linearized reading' (duration: 188.866573ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:10.131147Z","caller":"traceutil/trace.go:171","msg":"trace[1839966925] transaction","detail":"{read_only:false; response_revision:8723; number_of_response:1; }","duration":"173.162738ms","start":"2026-08-04T15:33:09.95791Z","end":"2026-08-04T15:33:10.131073Z","steps":["trace[1839966925] 'process raft request' (duration: 172.975233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:10.131289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.507719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-04T15:33:10.131381Z","caller":"traceutil/trace.go:171","msg":"trace[1784253753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8725; }","duration":"189.628051ms","start":"2026-08-04T15:33:09.941736Z","end":"2026-08-04T15:33:10.131364Z","steps":["trace[1784253753] 'agreement among raft nodes before linearized reading' (duration: 189.359275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:10.131389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.457764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-04T15:33:10.131356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.396813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-04T15:33:10.131457Z","caller":"traceutil/trace.go:171","msg":"trace[404489532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8725; }","duration":"176.568197ms","start":"2026-08-04T15:33:09.954875Z","end":"2026-08-04T15:33:10.131443Z","steps":["trace[404489532] 'agreement among raft nodes before linearized reading' (duration: 176.346782ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:10.131208Z","caller":"traceutil/trace.go:171","msg":"trace[1253554174] transaction","detail":"{read_only:false; response_revision:8725; number_of_response:1; }","duration":"148.236796ms","start":"2026-08-04T15:33:09.982921Z","end":"2026-08-04T15:33:10.131158Z","steps":["trace[1253554174] 'process raft request' (duration: 148.135504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:10.13151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.246972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qh878\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-08-04T15:33:10.131593Z","caller":"traceutil/trace.go:171","msg":"trace[1531534346] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qh878; range_end:; response_count:1; response_revision:8725; }","duration":"174.363735ms","start":"2026-08-04T15:33:09.957208Z","end":"2026-08-04T15:33:10.131572Z","steps":["trace[1531534346] 'agreement among raft nodes before linearized reading' (duration: 174.093609ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:10.131657Z","caller":"traceutil/trace.go:171","msg":"trace[1374953550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8725; }","duration":"176.543676ms","start":"2026-08-04T15:33:09.954927Z","end":"2026-08-04T15:33:10.131471Z","steps":["trace[1374953550] 'agreement among raft nodes before linearized reading' (duration: 176.29693ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:10.132013Z","caller":"traceutil/trace.go:171","msg":"trace[2133795311] transaction","detail":"{read_only:false; response_revision:8724; number_of_response:1; }","duration":"161.897195ms","start":"2026-08-04T15:33:09.969664Z","end":"2026-08-04T15:33:10.131562Z","steps":["trace[2133795311] 'process raft request' (duration: 161.325352ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:10.858375Z","caller":"traceutil/trace.go:171","msg":"trace[1328538828] linearizableReadLoop","detail":"{readStateIndex:9040; appliedIndex:9039; }","duration":"106.145075ms","start":"2026-08-04T15:33:10.752194Z","end":"2026-08-04T15:33:10.85834Z","steps":["trace[1328538828] 'read index received' (duration: 105.804416ms)","trace[1328538828] 'applied index is now lower than readState.Index' (duration: 335.849µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:33:10.85842Z","caller":"traceutil/trace.go:171","msg":"trace[348572814] transaction","detail":"{read_only:false; response_revision:8731; number_of_response:1; }","duration":"165.058299ms","start":"2026-08-04T15:33:10.693322Z","end":"2026-08-04T15:33:10.85838Z","steps":["trace[348572814] 'process raft request' (duration: 164.675509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:10.85851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.303989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:33:10.858575Z","caller":"traceutil/trace.go:171","msg":"trace[473779039] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:8731; }","duration":"106.423052ms","start":"2026-08-04T15:33:10.752133Z","end":"2026-08-04T15:33:10.858556Z","steps":["trace[473779039] 'agreement among raft nodes before linearized reading' (duration: 106.303989ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:11.452636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.638237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:33:11.452718Z","caller":"traceutil/trace.go:171","msg":"trace[2131306085] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8734; }","duration":"251.777181ms","start":"2026-08-04T15:33:11.200922Z","end":"2026-08-04T15:33:11.452699Z","steps":["trace[2131306085] 'count revisions from in-memory index tree' (duration: 251.536645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:11.4529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.640584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-04T15:33:11.452979Z","caller":"traceutil/trace.go:171","msg":"trace[1667735956] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8734; }","duration":"241.763538ms","start":"2026-08-04T15:33:11.211197Z","end":"2026-08-04T15:33:11.452961Z","steps":["trace[1667735956] 'range keys from in-memory index tree' (duration: 241.46861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:33:11.453111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.908617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-08-04T15:33:11.453222Z","caller":"traceutil/trace.go:171","msg":"trace[1391343078] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8734; }","duration":"226.066131ms","start":"2026-08-04T15:33:11.227137Z","end":"2026-08-04T15:33:11.453203Z","steps":["trace[1391343078] 'range keys from in-memory index tree' (duration: 224.932355ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:11.549854Z","caller":"traceutil/trace.go:171","msg":"trace[327088073] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"217.819728ms","start":"2026-08-04T15:33:11.332012Z","end":"2026-08-04T15:33:11.549832Z","steps":["trace[327088073] 'process raft request' (duration: 217.647994ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:23.206012Z","caller":"traceutil/trace.go:171","msg":"trace[1639023046] transaction","detail":"{read_only:false; response_revision:8831; number_of_response:1; }","duration":"133.023611ms","start":"2026-08-04T15:33:23.07296Z","end":"2026-08-04T15:33:23.205984Z","steps":["trace[1639023046] 'process raft request' (duration: 132.774296ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:27.334838Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f9.wal"} {"level":"warn","ts":"2026-08-04T15:34:27.072711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.994808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:34:27.072969Z","caller":"traceutil/trace.go:171","msg":"trace[288920164] linearizableReadLoop","detail":"{readStateIndex:9809; appliedIndex:9808; }","duration":"415.947975ms","start":"2026-08-04T15:34:26.657Z","end":"2026-08-04T15:34:27.072948Z","steps":["trace[288920164] 'read index received' (duration: 204.618159ms)","trace[288920164] 'applied index is now lower than readState.Index' (duration: 211.328466ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:34:27.073001Z","caller":"traceutil/trace.go:171","msg":"trace[513215122] transaction","detail":"{read_only:false; response_revision:9480; number_of_response:1; }","duration":"422.464027ms","start":"2026-08-04T15:34:26.650501Z","end":"2026-08-04T15:34:27.072965Z","steps":["trace[513215122] 'process raft request' (duration: 211.10257ms)","trace[513215122] 'compare' (duration: 210.525397ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:34:27.073093Z","caller":"traceutil/trace.go:171","msg":"trace[1536269889] transaction","detail":"{read_only:false; response_revision:9481; number_of_response:1; }","duration":"223.103869ms","start":"2026-08-04T15:34:26.849965Z","end":"2026-08-04T15:34:27.073069Z","steps":["trace[1536269889] 'process raft request' (duration: 222.884884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:34:27.073236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.206872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xfcp9\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-08-04T15:34:27.073256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:34:26.650487Z","time spent":"422.594249ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:34:27.073365Z","caller":"traceutil/trace.go:171","msg":"trace[1068901584] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xfcp9; range_end:; response_count:1; response_revision:9481; }","duration":"416.377065ms","start":"2026-08-04T15:34:26.656962Z","end":"2026-08-04T15:34:27.073339Z","steps":["trace[1068901584] 'agreement among raft nodes before linearized reading' (duration: 416.051478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:34:27.07342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:34:26.656947Z","time spent":"416.462508ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-xfcp9\" "} {"level":"info","ts":"2026-08-04T15:34:51.928386Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-04T15:34:51.937298Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-04T15:34:51.937392Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-04T15:36:58.965273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8118} {"level":"info","ts":"2026-08-04T15:36:59.032849Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8118,"took":"64.882785ms","hash":4257933665,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":28344320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-04T15:36:59.032911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4257933665,"revision":8118,"compact-revision":6030}