{"level":"warn","ts":"2026-07-29T19:00:22.057285Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-29T19:00:22.057455Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.12: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.19.213.12:2380","--initial-cluster=instance=https://199.19.213.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.12: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-07-29T19:00:22.057576Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-29T19:00:22.057596Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.12:2380"]} {"level":"info","ts":"2026-07-29T19:00:22.057621Z","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-07-29T19:00:22.058665Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"]} {"level":"info","ts":"2026-07-29T19:00:22.05886Z","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.19.213.12:2380"],"listen-peer-urls":["https://199.19.213.12:2380"],"advertise-client-urls":["https://199.19.213.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.12: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-07-29T19:00:22.063106Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.036749ms"} {"level":"info","ts":"2026-07-29T19:00:22.07032Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"790561176553eafe","cluster-id":"e32c72c573d8545f"} {"level":"info","ts":"2026-07-29T19:00:22.070473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=()"} {"level":"info","ts":"2026-07-29T19:00:22.070509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became follower at term 0"} {"level":"info","ts":"2026-07-29T19:00:22.070519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 790561176553eafe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-29T19:00:22.070527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became follower at term 1"} {"level":"info","ts":"2026-07-29T19:00:22.070571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=(8720483006584974078)"} {"level":"warn","ts":"2026-07-29T19:00:22.080915Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-29T19:00:22.082663Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-29T19:00:22.083817Z","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-07-29T19:00:22.087974Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"790561176553eafe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-29T19:00:22.088148Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"790561176553eafe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-29T19:00:22.0883Z","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-07-29T19:00:22.08838Z","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-07-29T19:00:22.088394Z","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-07-29T19:00:22.092002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe switched to configuration voters=(8720483006584974078)"} {"level":"info","ts":"2026-07-29T19:00:22.092213Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e32c72c573d8545f","local-member-id":"790561176553eafe","added-peer-id":"790561176553eafe","added-peer-peer-urls":["https://199.19.213.12:2380"]} {"level":"info","ts":"2026-07-29T19:00:22.093844Z","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-07-29T19:00:22.093965Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.12:2380"} {"level":"info","ts":"2026-07-29T19:00:22.094022Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.12:2380"} {"level":"info","ts":"2026-07-29T19:00:22.094164Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"790561176553eafe","initial-advertise-peer-urls":["https://199.19.213.12:2380"],"listen-peer-urls":["https://199.19.213.12:2380"],"advertise-client-urls":["https://199.19.213.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-29T19:00:22.094198Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-29T19:00:22.171376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe is starting a new election at term 1"} {"level":"info","ts":"2026-07-29T19:00:22.171463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became pre-candidate at term 1"} {"level":"info","ts":"2026-07-29T19:00:22.171497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe received MsgPreVoteResp from 790561176553eafe at term 1"} {"level":"info","ts":"2026-07-29T19:00:22.171518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became candidate at term 2"} {"level":"info","ts":"2026-07-29T19:00:22.171537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe received MsgVoteResp from 790561176553eafe at term 2"} {"level":"info","ts":"2026-07-29T19:00:22.171554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"790561176553eafe became leader at term 2"} {"level":"info","ts":"2026-07-29T19:00:22.171569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 790561176553eafe elected leader 790561176553eafe at term 2"} {"level":"info","ts":"2026-07-29T19:00:22.172457Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"790561176553eafe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.12:2379]}","request-path":"/0/members/790561176553eafe/attributes","cluster-id":"e32c72c573d8545f","publish-timeout":"7s"} {"level":"info","ts":"2026-07-29T19:00:22.172917Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T19:00:22.1729Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T19:00:22.173258Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-29T19:00:22.173287Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-29T19:00:22.173393Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-29T19:00:22.173903Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e32c72c573d8545f","local-member-id":"790561176553eafe","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T19:00:22.174017Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T19:00:22.174047Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-29T19:00:22.176584Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.12:2379"} {"level":"info","ts":"2026-07-29T19:00:22.176867Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-29T19:00:37.55674Z","caller":"traceutil/trace.go:171","msg":"trace[945378728] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"163.164997ms","start":"2026-07-29T19:00:37.393543Z","end":"2026-07-29T19:00:37.556708Z","steps":["trace[945378728] 'process raft request' (duration: 162.996703ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:00:59.927838Z","caller":"traceutil/trace.go:171","msg":"trace[926696018] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"101.548896ms","start":"2026-07-29T19:00:59.826263Z","end":"2026-07-29T19:00:59.927812Z","steps":["trace[926696018] 'process raft request' (duration: 101.453014ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:00:59.928007Z","caller":"traceutil/trace.go:171","msg":"trace[1389343938] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"118.041154ms","start":"2026-07-29T19:00:59.809935Z","end":"2026-07-29T19:00:59.927976Z","steps":["trace[1389343938] 'process raft request' (duration: 72.227266ms)","trace[1389343938] 'compare' (duration: 45.361628ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:01:00.22883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.855818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-29T19:01:00.229Z","caller":"traceutil/trace.go:171","msg":"trace[563516685] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"321.748971ms","start":"2026-07-29T19:00:59.90723Z","end":"2026-07-29T19:01:00.228979Z","steps":["trace[563516685] 'process raft request' (duration: 186.238039ms)","trace[563516685] 'compare' (duration: 134.645103ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:01:00.229066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:00:59.907206Z","time spent":"321.833944ms","remote":"127.0.0.1:48590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-29T19:01:00.246735Z","caller":"traceutil/trace.go:171","msg":"trace[9361735] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"254.053058ms","start":"2026-07-29T19:00:59.992647Z","end":"2026-07-29T19:01:00.2467Z","steps":["trace[9361735] 'process raft request' (duration: 253.646279ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:01:00.246898Z","caller":"traceutil/trace.go:171","msg":"trace[65525842] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"240.828713ms","start":"2026-07-29T19:01:00.006035Z","end":"2026-07-29T19:01:00.246864Z","steps":["trace[65525842] 'process raft request' (duration: 240.66393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:01:06.417433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.975178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-29T19:01:06.417576Z","caller":"traceutil/trace.go:171","msg":"trace[1553224112] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"280.440264ms","start":"2026-07-29T19:01:06.137115Z","end":"2026-07-29T19:01:06.417555Z","steps":["trace[1553224112] 'read index received' (duration: 110.22757ms)","trace[1553224112] 'applied index is now lower than readState.Index' (duration: 170.210844ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:01:06.417586Z","caller":"traceutil/trace.go:171","msg":"trace[82129211] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"444.205104ms","start":"2026-07-29T19:01:05.973349Z","end":"2026-07-29T19:01:06.417554Z","steps":["trace[82129211] 'process raft request' (duration: 274.011151ms)","trace[82129211] 'compare' (duration: 169.750202ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:01:06.417716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:01:05.973318Z","time spent":"444.334437ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-29T19:01:06.417882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.759351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w64hz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-29T19:01:06.417955Z","caller":"traceutil/trace.go:171","msg":"trace[1630401552] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w64hz; range_end:; response_count:1; response_revision:641; }","duration":"280.872234ms","start":"2026-07-29T19:01:06.137066Z","end":"2026-07-29T19:01:06.417938Z","steps":["trace[1630401552] 'agreement among raft nodes before linearized reading' (duration: 280.603779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:01:06.41788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.815084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-29T19:01:06.418073Z","caller":"traceutil/trace.go:171","msg":"trace[1373013827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"102.062129ms","start":"2026-07-29T19:01:06.315995Z","end":"2026-07-29T19:01:06.418058Z","steps":["trace[1373013827] 'agreement among raft nodes before linearized reading' (duration: 101.697351ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:01:22.605361Z","caller":"traceutil/trace.go:171","msg":"trace[222428057] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"105.621648ms","start":"2026-07-29T19:01:22.499715Z","end":"2026-07-29T19:01:22.605336Z","steps":["trace[222428057] 'process raft request' (duration: 105.326002ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:02:11.807599Z","caller":"traceutil/trace.go:171","msg":"trace[1754829319] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"109.183193ms","start":"2026-07-29T19:02:11.698389Z","end":"2026-07-29T19:02:11.807572Z","steps":["trace[1754829319] 'process raft request' (duration: 109.034869ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:02:11.808916Z","caller":"traceutil/trace.go:171","msg":"trace[137478075] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"110.423321ms","start":"2026-07-29T19:02:11.698476Z","end":"2026-07-29T19:02:11.808899Z","steps":["trace[137478075] 'process raft request' (duration: 110.282047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:02:35.357691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.611604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T19:02:35.357939Z","caller":"traceutil/trace.go:171","msg":"trace[1172085765] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"338.920678ms","start":"2026-07-29T19:02:35.018986Z","end":"2026-07-29T19:02:35.357906Z","steps":["trace[1172085765] 'process raft request' (duration: 227.776832ms)","trace[1172085765] 'compare' (duration: 110.293217ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:02:35.358144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:02:35.018959Z","time spent":"339.065122ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:02:35.839083Z","caller":"traceutil/trace.go:171","msg":"trace[1751420440] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"221.512567ms","start":"2026-07-29T19:02:35.617543Z","end":"2026-07-29T19:02:35.839056Z","steps":["trace[1751420440] 'read index received' (duration: 213.80212ms)","trace[1751420440] 'applied index is now lower than readState.Index' (duration: 7.709357ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:02:35.839187Z","caller":"traceutil/trace.go:171","msg":"trace[2124900022] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"223.423981ms","start":"2026-07-29T19:02:35.615721Z","end":"2026-07-29T19:02:35.839145Z","steps":["trace[2124900022] 'process raft request' (duration: 215.596321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:02:35.83935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.778233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-80ebd29f-42b5-4f24-a7d4-462824090781\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-29T19:02:35.839401Z","caller":"traceutil/trace.go:171","msg":"trace[989129301] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-80ebd29f-42b5-4f24-a7d4-462824090781; range_end:; response_count:1; response_revision:1871; }","duration":"221.891446ms","start":"2026-07-29T19:02:35.617498Z","end":"2026-07-29T19:02:35.83939Z","steps":["trace[989129301] 'agreement among raft nodes before linearized reading' (duration: 221.706592ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:02:47.52075Z","caller":"traceutil/trace.go:171","msg":"trace[632271603] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"108.20259ms","start":"2026-07-29T19:02:47.412525Z","end":"2026-07-29T19:02:47.520727Z","steps":["trace[632271603] 'process raft request' (duration: 108.020046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:03:30.240028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.079932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T19:03:30.240232Z","caller":"traceutil/trace.go:171","msg":"trace[1199369951] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"129.428996ms","start":"2026-07-29T19:03:30.110786Z","end":"2026-07-29T19:03:30.240215Z","steps":["trace[1199369951] 'process raft request' (duration: 129.367175ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:03:30.240269Z","caller":"traceutil/trace.go:171","msg":"trace[1955566351] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"153.531825ms","start":"2026-07-29T19:03:30.086699Z","end":"2026-07-29T19:03:30.240231Z","steps":["trace[1955566351] 'process raft request' (duration: 10.024003ms)","trace[1955566351] 'compare' (duration: 142.55971ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:03:30.701134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.434755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-29T19:03:30.701277Z","caller":"traceutil/trace.go:171","msg":"trace[1254976226] linearizableReadLoop","detail":"{readStateIndex:2301; appliedIndex:2300; }","duration":"372.146566ms","start":"2026-07-29T19:03:30.329105Z","end":"2026-07-29T19:03:30.701252Z","steps":["trace[1254976226] 'read index received' (duration: 78.388514ms)","trace[1254976226] 'applied index is now lower than readState.Index' (duration: 293.756672ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:03:30.70134Z","caller":"traceutil/trace.go:171","msg":"trace[1820383937] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"381.74672ms","start":"2026-07-29T19:03:30.31955Z","end":"2026-07-29T19:03:30.701297Z","steps":["trace[1820383937] 'process raft request' (duration: 88.006518ms)","trace[1820383937] 'compare' (duration: 293.284622ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:03:30.701491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.374221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-29T19:03:30.701574Z","caller":"traceutil/trace.go:171","msg":"trace[1425773576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2218; }","duration":"372.480153ms","start":"2026-07-29T19:03:30.329081Z","end":"2026-07-29T19:03:30.701561Z","steps":["trace[1425773576] 'agreement among raft nodes before linearized reading' (duration: 372.263378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:03:30.70162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:03:30.329071Z","time spent":"372.539604ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-29T19:03:30.701652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:03:30.319532Z","time spent":"381.992285ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:03:30.773769Z","caller":"traceutil/trace.go:171","msg":"trace[969199162] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"237.216097ms","start":"2026-07-29T19:03:30.536527Z","end":"2026-07-29T19:03:30.773743Z","steps":["trace[969199162] 'process raft request' (duration: 237.130525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:03:30.774243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.561372ms","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":"info","ts":"2026-07-29T19:03:30.774332Z","caller":"traceutil/trace.go:171","msg":"trace[1000975591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2219; }","duration":"388.728866ms","start":"2026-07-29T19:03:30.385585Z","end":"2026-07-29T19:03:30.774314Z","steps":["trace[1000975591] 'agreement among raft nodes before linearized reading' (duration: 388.412709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:03:30.774393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:03:30.385554Z","time spent":"388.828838ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-29T19:03:30.77442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.45244ms","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-07-29T19:03:30.774513Z","caller":"traceutil/trace.go:171","msg":"trace[2066858613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2219; }","duration":"405.587423ms","start":"2026-07-29T19:03:30.368906Z","end":"2026-07-29T19:03:30.774494Z","steps":["trace[2066858613] 'agreement among raft nodes before linearized reading' (duration: 405.01667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:03:30.77484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:03:30.368891Z","time spent":"405.933532ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-29T19:03:30.928171Z","caller":"traceutil/trace.go:171","msg":"trace[1189855107] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"127.40631ms","start":"2026-07-29T19:03:30.800746Z","end":"2026-07-29T19:03:30.928152Z","steps":["trace[1189855107] 'process raft request' (duration: 127.362389ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:03:30.928173Z","caller":"traceutil/trace.go:171","msg":"trace[884267573] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"149.240429ms","start":"2026-07-29T19:03:30.778904Z","end":"2026-07-29T19:03:30.928144Z","steps":["trace[884267573] 'process raft request' (duration: 149.171177ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:03:30.928208Z","caller":"traceutil/trace.go:171","msg":"trace[1677784266] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"149.335761ms","start":"2026-07-29T19:03:30.778835Z","end":"2026-07-29T19:03:30.928171Z","steps":["trace[1677784266] 'process raft request' (duration: 131.693197ms)","trace[1677784266] 'compare' (duration: 17.44316ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:04:07.240999Z","caller":"traceutil/trace.go:171","msg":"trace[379498737] linearizableReadLoop","detail":"{readStateIndex:2689; appliedIndex:2688; }","duration":"184.555886ms","start":"2026-07-29T19:04:07.056414Z","end":"2026-07-29T19:04:07.24097Z","steps":["trace[379498737] 'read index received' (duration: 184.348451ms)","trace[379498737] 'applied index is now lower than readState.Index' (duration: 205.925µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:04:07.241069Z","caller":"traceutil/trace.go:171","msg":"trace[1740242285] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"192.636647ms","start":"2026-07-29T19:04:07.048414Z","end":"2026-07-29T19:04:07.241051Z","steps":["trace[1740242285] 'process raft request' (duration: 192.30812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:04:07.241247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.771205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-29T19:04:07.241245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.826612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-29T19:04:07.241272Z","caller":"traceutil/trace.go:171","msg":"trace[832394006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2591; }","duration":"144.829725ms","start":"2026-07-29T19:04:07.096435Z","end":"2026-07-29T19:04:07.241264Z","steps":["trace[832394006] 'agreement among raft nodes before linearized reading' (duration: 144.756494ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:04:07.24128Z","caller":"traceutil/trace.go:171","msg":"trace[672736147] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2591; }","duration":"184.909814ms","start":"2026-07-29T19:04:07.056361Z","end":"2026-07-29T19:04:07.241271Z","steps":["trace[672736147] 'agreement among raft nodes before linearized reading' (duration: 184.75292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:04:07.241245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.383305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-29T19:04:07.241378Z","caller":"traceutil/trace.go:171","msg":"trace[1815949167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2591; }","duration":"156.566679ms","start":"2026-07-29T19:04:07.084792Z","end":"2026-07-29T19:04:07.241359Z","steps":["trace[1815949167] 'agreement among raft nodes before linearized reading' (duration: 156.374834ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:07:16.149606Z","caller":"traceutil/trace.go:171","msg":"trace[525045881] transaction","detail":"{read_only:false; response_revision:3816; number_of_response:1; }","duration":"124.127084ms","start":"2026-07-29T19:07:16.025456Z","end":"2026-07-29T19:07:16.149583Z","steps":["trace[525045881] 'process raft request' (duration: 118.982831ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:08:35.936919Z","caller":"traceutil/trace.go:171","msg":"trace[641430749] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"112.190187ms","start":"2026-07-29T19:08:35.824705Z","end":"2026-07-29T19:08:35.936895Z","steps":["trace[641430749] 'process raft request' (duration: 49.495078ms)","trace[641430749] 'compare' (duration: 62.439314ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:08:42.445322Z","caller":"traceutil/trace.go:171","msg":"trace[1745569298] linearizableReadLoop","detail":"{readStateIndex:5182; appliedIndex:5181; }","duration":"108.897155ms","start":"2026-07-29T19:08:42.336395Z","end":"2026-07-29T19:08:42.445292Z","steps":["trace[1745569298] 'read index received' (duration: 108.65909ms)","trace[1745569298] 'applied index is now lower than readState.Index' (duration: 236.685µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:08:42.445577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.156221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T19:08:42.445659Z","caller":"traceutil/trace.go:171","msg":"trace[967698325] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:4993; }","duration":"109.257843ms","start":"2026-07-29T19:08:42.336384Z","end":"2026-07-29T19:08:42.445642Z","steps":["trace[967698325] 'agreement among raft nodes before linearized reading' (duration: 109.092029ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:08:42.446023Z","caller":"traceutil/trace.go:171","msg":"trace[830213658] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"147.092647ms","start":"2026-07-29T19:08:42.298355Z","end":"2026-07-29T19:08:42.445448Z","steps":["trace[830213658] 'process raft request' (duration: 146.7685ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:08:42.629934Z","caller":"traceutil/trace.go:171","msg":"trace[2013368956] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4993; }","duration":"157.895414ms","start":"2026-07-29T19:08:42.472001Z","end":"2026-07-29T19:08:42.629896Z","steps":["trace[2013368956] 'process raft request' (duration: 141.36455ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:08:42.664029Z","caller":"traceutil/trace.go:171","msg":"trace[1978915549] linearizableReadLoop","detail":"{readStateIndex:5184; appliedIndex:5182; }","duration":"128.605639ms","start":"2026-07-29T19:08:42.535398Z","end":"2026-07-29T19:08:42.664004Z","steps":["trace[1978915549] 'read index received' (duration: 78.134229ms)","trace[1978915549] 'applied index is now lower than readState.Index' (duration: 50.4703ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:08:42.664233Z","caller":"traceutil/trace.go:171","msg":"trace[2072349817] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"190.985321ms","start":"2026-07-29T19:08:42.473234Z","end":"2026-07-29T19:08:42.664219Z","steps":["trace[2072349817] 'process raft request' (duration: 190.623393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:08:42.664504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.088669ms","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-07-29T19:08:42.664585Z","caller":"traceutil/trace.go:171","msg":"trace[1488883920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4994; }","duration":"129.228162ms","start":"2026-07-29T19:08:42.535344Z","end":"2026-07-29T19:08:42.664572Z","steps":["trace[1488883920] 'agreement among raft nodes before linearized reading' (duration: 129.041758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:08:42.664878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.949327ms","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-07-29T19:08:42.664964Z","caller":"traceutil/trace.go:171","msg":"trace[1108006734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4994; }","duration":"125.07127ms","start":"2026-07-29T19:08:42.539874Z","end":"2026-07-29T19:08:42.664945Z","steps":["trace[1108006734] 'agreement among raft nodes before linearized reading' (duration: 124.871186ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:08:42.941296Z","caller":"traceutil/trace.go:171","msg":"trace[1820317920] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"133.047707ms","start":"2026-07-29T19:08:42.808219Z","end":"2026-07-29T19:08:42.941266Z","steps":["trace[1820317920] 'process raft request' (duration: 124.201353ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:08:42.991709Z","caller":"traceutil/trace.go:171","msg":"trace[778760040] linearizableReadLoop","detail":"{readStateIndex:5192; appliedIndex:5190; }","duration":"136.914842ms","start":"2026-07-29T19:08:42.854773Z","end":"2026-07-29T19:08:42.991688Z","steps":["trace[778760040] 'read index received' (duration: 77.909774ms)","trace[778760040] 'applied index is now lower than readState.Index' (duration: 59.004328ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:08:42.99182Z","caller":"traceutil/trace.go:171","msg":"trace[1815466534] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"158.469116ms","start":"2026-07-29T19:08:42.833333Z","end":"2026-07-29T19:08:42.991803Z","steps":["trace[1815466534] 'process raft request' (duration: 158.077297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:08:42.991836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.045715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T19:08:42.991869Z","caller":"traceutil/trace.go:171","msg":"trace[1073730090] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5002; }","duration":"137.132467ms","start":"2026-07-29T19:08:42.854727Z","end":"2026-07-29T19:08:42.99186Z","steps":["trace[1073730090] 'agreement among raft nodes before linearized reading' (duration: 137.050505ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:08:43.132648Z","caller":"traceutil/trace.go:171","msg":"trace[1375634792] linearizableReadLoop","detail":"{readStateIndex:5194; appliedIndex:5193; }","duration":"118.061217ms","start":"2026-07-29T19:08:43.014559Z","end":"2026-07-29T19:08:43.13262Z","steps":["trace[1375634792] 'read index received' (duration: 39.943348ms)","trace[1375634792] 'applied index is now lower than readState.Index' (duration: 78.116449ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:08:43.132899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.324372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-07-29T19:08:43.132948Z","caller":"traceutil/trace.go:171","msg":"trace[806468237] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5004; }","duration":"118.411664ms","start":"2026-07-29T19:08:43.014522Z","end":"2026-07-29T19:08:43.132933Z","steps":["trace[806468237] 'agreement among raft nodes before linearized reading' (duration: 118.19628ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:08:43.132905Z","caller":"traceutil/trace.go:171","msg":"trace[1321761803] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"128.63568ms","start":"2026-07-29T19:08:43.004122Z","end":"2026-07-29T19:08:43.132758Z","steps":["trace[1321761803] 'process raft request' (duration: 50.385809ms)","trace[1321761803] 'compare' (duration: 77.952704ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:08:55.418248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.576558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-29T19:08:55.418337Z","caller":"traceutil/trace.go:171","msg":"trace[896782431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5251; }","duration":"143.70382ms","start":"2026-07-29T19:08:55.274616Z","end":"2026-07-29T19:08:55.41832Z","steps":["trace[896782431] 'range keys from in-memory index tree' (duration: 143.401024ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:09:03.668672Z","caller":"traceutil/trace.go:171","msg":"trace[316995655] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"138.263341ms","start":"2026-07-29T19:09:03.530385Z","end":"2026-07-29T19:09:03.668649Z","steps":["trace[316995655] 'process raft request' (duration: 138.054536ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:09:03.88765Z","caller":"traceutil/trace.go:171","msg":"trace[820655669] linearizableReadLoop","detail":"{readStateIndex:5635; appliedIndex:5634; }","duration":"201.544823ms","start":"2026-07-29T19:09:03.686083Z","end":"2026-07-29T19:09:03.887628Z","steps":["trace[820655669] 'read index received' (duration: 201.38608ms)","trace[820655669] 'applied index is now lower than readState.Index' (duration: 158.023µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:09:03.887736Z","caller":"traceutil/trace.go:171","msg":"trace[977656224] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"205.810847ms","start":"2026-07-29T19:09:03.681899Z","end":"2026-07-29T19:09:03.88771Z","steps":["trace[977656224] 'process raft request' (duration: 205.576242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:09:03.887872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.772268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"warn","ts":"2026-07-29T19:09:03.887945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.367394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-07-29T19:09:03.887972Z","caller":"traceutil/trace.go:171","msg":"trace[2083854201] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5433; }","duration":"198.427635ms","start":"2026-07-29T19:09:03.689538Z","end":"2026-07-29T19:09:03.887965Z","steps":["trace[2083854201] 'agreement among raft nodes before linearized reading' (duration: 198.313142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:09:03.888113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.822788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9f2sg\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-07-29T19:09:03.888129Z","caller":"traceutil/trace.go:171","msg":"trace[1798978218] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9f2sg; range_end:; response_count:1; response_revision:5433; }","duration":"195.867808ms","start":"2026-07-29T19:09:03.692256Z","end":"2026-07-29T19:09:03.888123Z","steps":["trace[1798978218] 'agreement among raft nodes before linearized reading' (duration: 195.818267ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:09:03.887909Z","caller":"traceutil/trace.go:171","msg":"trace[694604707] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5433; }","duration":"201.84901ms","start":"2026-07-29T19:09:03.686048Z","end":"2026-07-29T19:09:03.887897Z","steps":["trace[694604707] 'agreement among raft nodes before linearized reading' (duration: 201.699177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:09:03.888347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.740967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-29T19:09:03.888394Z","caller":"traceutil/trace.go:171","msg":"trace[1032088555] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5433; }","duration":"146.82126ms","start":"2026-07-29T19:09:03.74156Z","end":"2026-07-29T19:09:03.888381Z","steps":["trace[1032088555] 'agreement among raft nodes before linearized reading' (duration: 146.35596ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:09:04.023923Z","caller":"traceutil/trace.go:171","msg":"trace[404573529] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"129.614651ms","start":"2026-07-29T19:09:03.89428Z","end":"2026-07-29T19:09:04.023895Z","steps":["trace[404573529] 'process raft request' (duration: 118.587958ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:09:04.02617Z","caller":"traceutil/trace.go:171","msg":"trace[374633808] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"131.82808ms","start":"2026-07-29T19:09:03.894323Z","end":"2026-07-29T19:09:04.026151Z","steps":["trace[374633808] 'process raft request' (duration: 131.681397ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:09:04.02642Z","caller":"traceutil/trace.go:171","msg":"trace[1150457014] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"130.93064ms","start":"2026-07-29T19:09:03.895464Z","end":"2026-07-29T19:09:04.026395Z","steps":["trace[1150457014] 'process raft request' (duration: 130.654754ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:09:04.026607Z","caller":"traceutil/trace.go:171","msg":"trace[535340060] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"130.805498ms","start":"2026-07-29T19:09:03.895789Z","end":"2026-07-29T19:09:04.026595Z","steps":["trace[535340060] 'process raft request' (duration: 130.504731ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:09:04.026802Z","caller":"traceutil/trace.go:171","msg":"trace[1923559484] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"130.174864ms","start":"2026-07-29T19:09:03.896604Z","end":"2026-07-29T19:09:04.026779Z","steps":["trace[1923559484] 'process raft request' (duration: 129.886937ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:09:04.026956Z","caller":"traceutil/trace.go:171","msg":"trace[1686567404] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"129.9868ms","start":"2026-07-29T19:09:03.89696Z","end":"2026-07-29T19:09:04.026947Z","steps":["trace[1686567404] 'process raft request' (duration: 129.763555ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:09:04.02706Z","caller":"traceutil/trace.go:171","msg":"trace[445881628] linearizableReadLoop","detail":"{readStateIndex:5637; appliedIndex:5635; }","duration":"132.153276ms","start":"2026-07-29T19:09:03.8949Z","end":"2026-07-29T19:09:04.027053Z","steps":["trace[445881628] 'read index received' (duration: 118.027716ms)","trace[445881628] 'applied index is now lower than readState.Index' (duration: 14.12479ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:09:04.027202Z","caller":"traceutil/trace.go:171","msg":"trace[1723957453] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"127.543576ms","start":"2026-07-29T19:09:03.899642Z","end":"2026-07-29T19:09:04.027186Z","steps":["trace[1723957453] 'process raft request' (duration: 127.209819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:09:04.027236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.321841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80046"} {"level":"info","ts":"2026-07-29T19:09:04.027259Z","caller":"traceutil/trace.go:171","msg":"trace[58937392] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5440; }","duration":"132.385802ms","start":"2026-07-29T19:09:03.894867Z","end":"2026-07-29T19:09:04.027253Z","steps":["trace[58937392] 'agreement among raft nodes before linearized reading' (duration: 132.209448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:09:05.90431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.455993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T19:09:05.904377Z","caller":"traceutil/trace.go:171","msg":"trace[1861308162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5468; }","duration":"116.579605ms","start":"2026-07-29T19:09:05.787783Z","end":"2026-07-29T19:09:05.904363Z","steps":["trace[1861308162] 'range keys from in-memory index tree' (duration: 116.33299ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:10:22.380744Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3023} {"level":"info","ts":"2026-07-29T19:10:22.466749Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3023,"took":"84.487519ms","hash":4277363028,"current-db-size-bytes":58916864,"current-db-size":"59 MB","current-db-size-in-use-bytes":32321536,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-29T19:10:22.46683Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4277363028,"revision":3023,"compact-revision":-1} {"level":"info","ts":"2026-07-29T19:15:22.398829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6154} {"level":"info","ts":"2026-07-29T19:15:22.498033Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6154,"took":"97.298871ms","hash":35645792,"current-db-size-bytes":58916864,"current-db-size":"59 MB","current-db-size-in-use-bytes":23597056,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-29T19:15:22.498147Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":35645792,"revision":6154,"compact-revision":3023} {"level":"info","ts":"2026-07-29T19:15:28.808772Z","caller":"traceutil/trace.go:171","msg":"trace[370271069] linearizableReadLoop","detail":"{readStateIndex:8650; appliedIndex:8649; }","duration":"118.236141ms","start":"2026-07-29T19:15:28.69051Z","end":"2026-07-29T19:15:28.808746Z","steps":["trace[370271069] 'read index received' (duration: 118.082548ms)","trace[370271069] 'applied index is now lower than readState.Index' (duration: 149.813µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:15:28.808847Z","caller":"traceutil/trace.go:171","msg":"trace[1312745088] transaction","detail":"{read_only:false; response_revision:8355; number_of_response:1; }","duration":"148.724927ms","start":"2026-07-29T19:15:28.660085Z","end":"2026-07-29T19:15:28.80881Z","steps":["trace[1312745088] 'process raft request' (duration: 148.496352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:15:28.809022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.491017ms","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-07-29T19:15:28.809086Z","caller":"traceutil/trace.go:171","msg":"trace[1959642225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8355; }","duration":"118.603948ms","start":"2026-07-29T19:15:28.690467Z","end":"2026-07-29T19:15:28.809071Z","steps":["trace[1959642225] 'agreement among raft nodes before linearized reading' (duration: 118.410224ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:15:28.830904Z","caller":"traceutil/trace.go:171","msg":"trace[419447898] transaction","detail":"{read_only:false; response_revision:8356; number_of_response:1; }","duration":"124.18505ms","start":"2026-07-29T19:15:28.706689Z","end":"2026-07-29T19:15:28.830874Z","steps":["trace[419447898] 'process raft request' (duration: 124.013547ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:15:56.496762Z","caller":"traceutil/trace.go:171","msg":"trace[486278948] linearizableReadLoop","detail":"{readStateIndex:8860; appliedIndex:8859; }","duration":"138.801735ms","start":"2026-07-29T19:15:56.357932Z","end":"2026-07-29T19:15:56.496733Z","steps":["trace[486278948] 'read index received' (duration: 138.512779ms)","trace[486278948] 'applied index is now lower than readState.Index' (duration: 287.566µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:15:56.496947Z","caller":"traceutil/trace.go:171","msg":"trace[1332352680] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"142.552056ms","start":"2026-07-29T19:15:56.354353Z","end":"2026-07-29T19:15:56.496905Z","steps":["trace[1332352680] 'process raft request' (duration: 142.161256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:15:56.497176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.200034ms","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-07-29T19:15:56.497297Z","caller":"traceutil/trace.go:171","msg":"trace[2033840782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8557; }","duration":"139.381258ms","start":"2026-07-29T19:15:56.35789Z","end":"2026-07-29T19:15:56.497272Z","steps":["trace[2033840782] 'agreement among raft nodes before linearized reading' (duration: 139.017691ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:15:56.875488Z","caller":"traceutil/trace.go:171","msg":"trace[1754522597] transaction","detail":"{read_only:false; response_revision:8558; number_of_response:1; }","duration":"372.117091ms","start":"2026-07-29T19:15:56.503347Z","end":"2026-07-29T19:15:56.875464Z","steps":["trace[1754522597] 'process raft request' (duration: 371.991958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:15:56.875639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:15:56.503334Z","time spent":"372.232293ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:15:56.876019Z","caller":"traceutil/trace.go:171","msg":"trace[760431595] linearizableReadLoop","detail":"{readStateIndex:8861; appliedIndex:8861; }","duration":"105.644667ms","start":"2026-07-29T19:15:56.770293Z","end":"2026-07-29T19:15:56.875937Z","steps":["trace[760431595] 'read index received' (duration: 105.633407ms)","trace[760431595] 'applied index is now lower than readState.Index' (duration: 9.07µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:15:56.876203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.913783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-29T19:15:56.876233Z","caller":"traceutil/trace.go:171","msg":"trace[60432768] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8558; }","duration":"105.974914ms","start":"2026-07-29T19:15:56.770251Z","end":"2026-07-29T19:15:56.876226Z","steps":["trace[60432768] 'agreement among raft nodes before linearized reading' (duration: 105.820361ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:16:05.64987Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022de.wal"} {"level":"info","ts":"2026-07-29T19:16:09.501449Z","caller":"traceutil/trace.go:171","msg":"trace[1009410473] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"250.567345ms","start":"2026-07-29T19:16:09.250855Z","end":"2026-07-29T19:16:09.501422Z","steps":["trace[1009410473] 'process raft request' (duration: 250.37221ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:16:09.666518Z","caller":"traceutil/trace.go:171","msg":"trace[369618149] linearizableReadLoop","detail":"{readStateIndex:8978; appliedIndex:8976; }","duration":"407.217172ms","start":"2026-07-29T19:16:09.259274Z","end":"2026-07-29T19:16:09.666491Z","steps":["trace[369618149] 'read index received' (duration: 242.072913ms)","trace[369618149] 'applied index is now lower than readState.Index' (duration: 165.143409ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:16:09.666642Z","caller":"traceutil/trace.go:171","msg":"trace[299119888] transaction","detail":"{read_only:false; response_revision:8669; number_of_response:1; }","duration":"407.428416ms","start":"2026-07-29T19:16:09.259176Z","end":"2026-07-29T19:16:09.666604Z","steps":["trace[299119888] 'process raft request' (duration: 407.14973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:16:09.666761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.468237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6lg2p\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-07-29T19:16:09.666791Z","caller":"traceutil/trace.go:171","msg":"trace[762761143] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6lg2p; range_end:; response_count:1; response_revision:8669; }","duration":"407.550799ms","start":"2026-07-29T19:16:09.259232Z","end":"2026-07-29T19:16:09.666783Z","steps":["trace[762761143] 'agreement among raft nodes before linearized reading' (duration: 407.386845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:16:09.666796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:16:09.25915Z","time spent":"407.5872ms","remote":"127.0.0.1:48622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-29T19:16:09.666819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:16:09.259213Z","time spent":"407.599259ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4670,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6lg2p\" "} {"level":"warn","ts":"2026-07-29T19:16:09.673963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.533852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-29T19:16:09.674026Z","caller":"traceutil/trace.go:171","msg":"trace[874907113] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:8670; }","duration":"132.608133ms","start":"2026-07-29T19:16:09.541402Z","end":"2026-07-29T19:16:09.674011Z","steps":["trace[874907113] 'agreement among raft nodes before linearized reading' (duration: 132.46789ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:16:10.157189Z","caller":"traceutil/trace.go:171","msg":"trace[451040013] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"118.051452ms","start":"2026-07-29T19:16:10.03912Z","end":"2026-07-29T19:16:10.157171Z","steps":["trace[451040013] 'process raft request' (duration: 117.920739ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:16:10.264321Z","caller":"traceutil/trace.go:171","msg":"trace[859327071] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"150.949035ms","start":"2026-07-29T19:16:10.113341Z","end":"2026-07-29T19:16:10.264291Z","steps":["trace[859327071] 'process raft request' (duration: 141.521885ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:18:00.44448Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"790561176553eafe","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-29T19:18:00.451784Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-29T19:18:00.451903Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-29T19:20:22.406589Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8320} {"level":"info","ts":"2026-07-29T19:20:22.490961Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8320,"took":"80.643274ms","hash":3006656481,"current-db-size-bytes":58916864,"current-db-size":"59 MB","current-db-size-in-use-bytes":26513408,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-29T19:20:22.491053Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3006656481,"revision":8320,"compact-revision":6154} {"level":"info","ts":"2026-07-29T19:25:22.413348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10813} {"level":"info","ts":"2026-07-29T19:25:22.501232Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10813,"took":"85.347092ms","hash":970057249,"current-db-size-bytes":58916864,"current-db-size":"59 MB","current-db-size-in-use-bytes":26537984,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-29T19:25:22.501321Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":970057249,"revision":10813,"compact-revision":8320} {"level":"info","ts":"2026-07-29T19:27:12.485607Z","caller":"traceutil/trace.go:171","msg":"trace[851228084] transaction","detail":"{read_only:false; response_revision:13724; number_of_response:1; }","duration":"135.772064ms","start":"2026-07-29T19:27:12.34981Z","end":"2026-07-29T19:27:12.485582Z","steps":["trace[851228084] 'process raft request' (duration: 135.60487ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:27:12.48742Z","caller":"traceutil/trace.go:171","msg":"trace[480663834] transaction","detail":"{read_only:false; response_revision:13725; number_of_response:1; }","duration":"136.297276ms","start":"2026-07-29T19:27:12.351096Z","end":"2026-07-29T19:27:12.487393Z","steps":["trace[480663834] 'process raft request' (duration: 136.144063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:27:47.173334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.579464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-07-29T19:27:47.173417Z","caller":"traceutil/trace.go:171","msg":"trace[690731839] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:13927; }","duration":"107.700527ms","start":"2026-07-29T19:27:47.065698Z","end":"2026-07-29T19:27:47.173399Z","steps":["trace[690731839] 'range keys from in-memory index tree' (duration: 107.435171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:27:47.173431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.949428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-2vmg4\" ","response":"range_response_count:1 size:11624"} {"level":"info","ts":"2026-07-29T19:27:47.173545Z","caller":"traceutil/trace.go:171","msg":"trace[672792895] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-2vmg4; range_end:; response_count:1; response_revision:13927; }","duration":"120.078601ms","start":"2026-07-29T19:27:47.053429Z","end":"2026-07-29T19:27:47.173508Z","steps":["trace[672792895] 'range keys from in-memory index tree' (duration: 119.823175ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:28:10.472171Z","caller":"traceutil/trace.go:171","msg":"trace[1402143241] linearizableReadLoop","detail":"{readStateIndex:14726; appliedIndex:14725; }","duration":"223.34609ms","start":"2026-07-29T19:28:10.248794Z","end":"2026-07-29T19:28:10.47214Z","steps":["trace[1402143241] 'read index received' (duration: 223.137036ms)","trace[1402143241] 'applied index is now lower than readState.Index' (duration: 207.754µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:28:10.472344Z","caller":"traceutil/trace.go:171","msg":"trace[1158117266] transaction","detail":"{read_only:false; response_revision:14239; number_of_response:1; }","duration":"263.115323ms","start":"2026-07-29T19:28:10.209214Z","end":"2026-07-29T19:28:10.472329Z","steps":["trace[1158117266] 'process raft request' (duration: 262.751275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:28:10.472659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.860502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9f2sg\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-07-29T19:28:10.472727Z","caller":"traceutil/trace.go:171","msg":"trace[788086312] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9f2sg; range_end:; response_count:1; response_revision:14239; }","duration":"223.970395ms","start":"2026-07-29T19:28:10.248742Z","end":"2026-07-29T19:28:10.472712Z","steps":["trace[788086312] 'agreement among raft nodes before linearized reading' (duration: 223.80967ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:28:10.478187Z","caller":"traceutil/trace.go:171","msg":"trace[1699277087] transaction","detail":"{read_only:false; response_revision:14240; number_of_response:1; }","duration":"224.385814ms","start":"2026-07-29T19:28:10.253776Z","end":"2026-07-29T19:28:10.478162Z","steps":["trace[1699277087] 'process raft request' (duration: 224.23049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:28:10.478329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.300045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7862bbc-62a0-4cfe-ac5e-768d32e12266\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-07-29T19:28:10.478411Z","caller":"traceutil/trace.go:171","msg":"trace[690416712] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b7862bbc-62a0-4cfe-ac5e-768d32e12266; range_end:; response_count:1; response_revision:14240; }","duration":"133.418978ms","start":"2026-07-29T19:28:10.344974Z","end":"2026-07-29T19:28:10.478393Z","steps":["trace[690416712] 'agreement among raft nodes before linearized reading' (duration: 133.189942ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:30:22.420048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12885} {"level":"info","ts":"2026-07-29T19:30:22.506291Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12885,"took":"83.904876ms","hash":4002685241,"current-db-size-bytes":58916864,"current-db-size":"59 MB","current-db-size-in-use-bytes":30371840,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-29T19:30:22.506357Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4002685241,"revision":12885,"compact-revision":10813} {"level":"info","ts":"2026-07-29T19:35:22.430302Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15351} {"level":"info","ts":"2026-07-29T19:35:22.548743Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15351,"took":"114.482082ms","hash":3337938827,"current-db-size-bytes":58916864,"current-db-size":"59 MB","current-db-size-in-use-bytes":31891456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-29T19:35:22.548829Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3337938827,"revision":15351,"compact-revision":12885} {"level":"info","ts":"2026-07-29T19:35:28.350867Z","caller":"traceutil/trace.go:171","msg":"trace[1856567798] transaction","detail":"{read_only:false; response_revision:17658; number_of_response:1; }","duration":"220.376404ms","start":"2026-07-29T19:35:28.130465Z","end":"2026-07-29T19:35:28.350841Z","steps":["trace[1856567798] 'process raft request' (duration: 220.20196ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:35:28.360273Z","caller":"traceutil/trace.go:171","msg":"trace[797094279] transaction","detail":"{read_only:false; response_revision:17659; number_of_response:1; }","duration":"192.366686ms","start":"2026-07-29T19:35:28.167879Z","end":"2026-07-29T19:35:28.360246Z","steps":["trace[797094279] 'process raft request' (duration: 192.206393ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:35:28.721311Z","caller":"traceutil/trace.go:171","msg":"trace[310220888] linearizableReadLoop","detail":"{readStateIndex:18257; appliedIndex:18256; }","duration":"220.378953ms","start":"2026-07-29T19:35:28.500892Z","end":"2026-07-29T19:35:28.721271Z","steps":["trace[310220888] 'read index received' (duration: 220.046276ms)","trace[310220888] 'applied index is now lower than readState.Index' (duration: 331.297µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:35:28.721391Z","caller":"traceutil/trace.go:171","msg":"trace[549620604] transaction","detail":"{read_only:false; response_revision:17660; number_of_response:1; }","duration":"252.189374ms","start":"2026-07-29T19:35:28.469157Z","end":"2026-07-29T19:35:28.721346Z","steps":["trace[549620604] 'process raft request' (duration: 251.914828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:35:28.721642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.71669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-rhmkx\" ","response":"range_response_count:1 size:22017"} {"level":"info","ts":"2026-07-29T19:35:28.721699Z","caller":"traceutil/trace.go:171","msg":"trace[1671734278] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-rhmkx; range_end:; response_count:1; response_revision:17660; }","duration":"220.848983ms","start":"2026-07-29T19:35:28.500834Z","end":"2026-07-29T19:35:28.721683Z","steps":["trace[1671734278] 'agreement among raft nodes before linearized reading' (duration: 220.617688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:35:28.724337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.595979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-29T19:35:28.724592Z","caller":"traceutil/trace.go:171","msg":"trace[117049627] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:17660; }","duration":"181.816543ms","start":"2026-07-29T19:35:28.542667Z","end":"2026-07-29T19:35:28.724484Z","steps":["trace[117049627] 'agreement among raft nodes before linearized reading' (duration: 178.884459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:35:28.932368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.423529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:35:28.932457Z","caller":"traceutil/trace.go:171","msg":"trace[702559593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17660; }","duration":"143.567493ms","start":"2026-07-29T19:35:28.788868Z","end":"2026-07-29T19:35:28.932436Z","steps":["trace[702559593] 'range keys from in-memory index tree' (duration: 143.282117ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:38:56.444016Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"790561176553eafe","local-member-applied-index":20003,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-29T19:38:56.449334Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-07-29T19:38:56.44943Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-07-29T19:40:22.439424Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17621} {"level":"info","ts":"2026-07-29T19:40:22.537435Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17621,"took":"92.758623ms","hash":1323355125,"current-db-size-bytes":58916864,"current-db-size":"59 MB","current-db-size-in-use-bytes":34086912,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-29T19:40:22.537512Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1323355125,"revision":17621,"compact-revision":15351} {"level":"info","ts":"2026-07-29T19:41:06.842782Z","caller":"traceutil/trace.go:171","msg":"trace[279653168] transaction","detail":"{read_only:false; response_revision:20301; number_of_response:1; }","duration":"107.222491ms","start":"2026-07-29T19:41:06.735526Z","end":"2026-07-29T19:41:06.842748Z","steps":["trace[279653168] 'process raft request' (duration: 103.240983ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:41:12.668139Z","caller":"traceutil/trace.go:171","msg":"trace[299291432] transaction","detail":"{read_only:false; response_revision:20328; number_of_response:1; }","duration":"376.412009ms","start":"2026-07-29T19:41:12.291691Z","end":"2026-07-29T19:41:12.668103Z","steps":["trace[299291432] 'process raft request' (duration: 376.178734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:41:12.668383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:41:12.291666Z","time spent":"376.595352ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T19:41:13.043311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.677118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:41:13.043414Z","caller":"traceutil/trace.go:171","msg":"trace[1469750244] linearizableReadLoop","detail":"{readStateIndex:21012; appliedIndex:21011; }","duration":"254.268318ms","start":"2026-07-29T19:41:12.78913Z","end":"2026-07-29T19:41:13.043399Z","steps":["trace[1469750244] 'read index received' (duration: 73.423636ms)","trace[1469750244] 'applied index is now lower than readState.Index' (duration: 180.843412ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:41:13.04349Z","caller":"traceutil/trace.go:171","msg":"trace[1345235244] transaction","detail":"{read_only:false; response_revision:20329; number_of_response:1; }","duration":"331.801416ms","start":"2026-07-29T19:41:12.711679Z","end":"2026-07-29T19:41:13.04348Z","steps":["trace[1345235244] 'process raft request' (duration: 150.892053ms)","trace[1345235244] 'compare' (duration: 180.581366ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:41:13.043539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:41:12.711631Z","time spent":"331.880968ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T19:41:13.04368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.152065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-29T19:41:13.04377Z","caller":"traceutil/trace.go:171","msg":"trace[976830012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20329; }","duration":"174.276817ms","start":"2026-07-29T19:41:12.869476Z","end":"2026-07-29T19:41:13.043752Z","steps":["trace[976830012] 'agreement among raft nodes before linearized reading' (duration: 174.063922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:41:13.043894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.822381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-29T19:41:13.044101Z","caller":"traceutil/trace.go:171","msg":"trace[1754046286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20329; }","duration":"161.068417ms","start":"2026-07-29T19:41:12.883022Z","end":"2026-07-29T19:41:13.044091Z","steps":["trace[1754046286] 'agreement among raft nodes before linearized reading' (duration: 160.78211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:41:13.044231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.108948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-29T19:41:13.044237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.553904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-29T19:41:13.044269Z","caller":"traceutil/trace.go:171","msg":"trace[1179206122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20329; }","duration":"255.182809ms","start":"2026-07-29T19:41:12.789079Z","end":"2026-07-29T19:41:13.044261Z","steps":["trace[1179206122] 'agreement among raft nodes before linearized reading' (duration: 255.127088ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:41:13.044287Z","caller":"traceutil/trace.go:171","msg":"trace[660324671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20329; }","duration":"174.662517ms","start":"2026-07-29T19:41:12.869611Z","end":"2026-07-29T19:41:13.044273Z","steps":["trace[660324671] 'agreement among raft nodes before linearized reading' (duration: 174.536314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:41:13.366908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.362951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:41:13.367082Z","caller":"traceutil/trace.go:171","msg":"trace[1169363550] linearizableReadLoop","detail":"{readStateIndex:21016; appliedIndex:21012; }","duration":"277.214284ms","start":"2026-07-29T19:41:13.089853Z","end":"2026-07-29T19:41:13.367067Z","steps":["trace[1169363550] 'read index received' (duration: 8.448086ms)","trace[1169363550] 'applied index is now lower than readState.Index' (duration: 268.765478ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:41:13.367196Z","caller":"traceutil/trace.go:171","msg":"trace[1122044351] transaction","detail":"{read_only:false; response_revision:20332; number_of_response:1; }","duration":"316.871058ms","start":"2026-07-29T19:41:13.05023Z","end":"2026-07-29T19:41:13.367101Z","steps":["trace[1122044351] 'process raft request' (duration: 316.760526ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:41:13.367326Z","caller":"traceutil/trace.go:171","msg":"trace[1359458583] transaction","detail":"{read_only:false; response_revision:20333; number_of_response:1; }","duration":"277.957221ms","start":"2026-07-29T19:41:13.089354Z","end":"2026-07-29T19:41:13.367311Z","steps":["trace[1359458583] 'process raft request' (duration: 277.676825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:41:13.367495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:41:13.050211Z","time spent":"317.120363ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T19:41:13.367272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.418359ms","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-07-29T19:41:13.367574Z","caller":"traceutil/trace.go:171","msg":"trace[940274805] transaction","detail":"{read_only:false; response_revision:20331; number_of_response:1; }","duration":"317.274287ms","start":"2026-07-29T19:41:13.050231Z","end":"2026-07-29T19:41:13.367505Z","steps":["trace[940274805] 'process raft request' (duration: 171.240761ms)","trace[940274805] 'compare' (duration: 145.258309ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:41:13.367625Z","caller":"traceutil/trace.go:171","msg":"trace[1351747248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20333; }","duration":"277.794387ms","start":"2026-07-29T19:41:13.089804Z","end":"2026-07-29T19:41:13.367598Z","steps":["trace[1351747248] 'agreement among raft nodes before linearized reading' (duration: 277.308516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:41:13.367976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:41:13.050212Z","time spent":"317.617074ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:41:13.529196Z","caller":"traceutil/trace.go:171","msg":"trace[594230837] transaction","detail":"{read_only:false; response_revision:20334; number_of_response:1; }","duration":"156.786433ms","start":"2026-07-29T19:41:13.372375Z","end":"2026-07-29T19:41:13.529161Z","steps":["trace[594230837] 'process raft request' (duration: 151.707041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:41:13.878788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.836935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:41:13.878909Z","caller":"traceutil/trace.go:171","msg":"trace[690522865] transaction","detail":"{read_only:false; response_revision:20335; number_of_response:1; }","duration":"352.916741ms","start":"2026-07-29T19:41:13.525967Z","end":"2026-07-29T19:41:13.878883Z","steps":["trace[690522865] 'process raft request' (duration: 184.83632ms)","trace[690522865] 'compare' (duration: 167.501668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:41:13.879182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:41:13.525942Z","time spent":"353.191678ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:41:13.888868Z","caller":"traceutil/trace.go:171","msg":"trace[104170684] transaction","detail":"{read_only:false; response_revision:20336; number_of_response:1; }","duration":"316.210013ms","start":"2026-07-29T19:41:13.572633Z","end":"2026-07-29T19:41:13.888843Z","steps":["trace[104170684] 'process raft request' (duration: 316.05773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:41:13.889014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:41:13.572611Z","time spent":"316.336386ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:41:13.889009Z","caller":"traceutil/trace.go:171","msg":"trace[709200692] linearizableReadLoop","detail":"{readStateIndex:21020; appliedIndex:21018; }","duration":"137.810515ms","start":"2026-07-29T19:41:13.751164Z","end":"2026-07-29T19:41:13.888975Z","steps":["trace[709200692] 'read index received' (duration: 137.451227ms)","trace[709200692] 'applied index is now lower than readState.Index' (duration: 356.758µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:41:13.889042Z","caller":"traceutil/trace.go:171","msg":"trace[1795402491] transaction","detail":"{read_only:false; response_revision:20337; number_of_response:1; }","duration":"314.668799ms","start":"2026-07-29T19:41:13.574342Z","end":"2026-07-29T19:41:13.889011Z","steps":["trace[1795402491] 'process raft request' (duration: 314.449824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:41:13.889155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:41:13.574311Z","time spent":"314.783661ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T19:41:13.889195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.007039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-29T19:41:13.889238Z","caller":"traceutil/trace.go:171","msg":"trace[1787603376] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:20337; }","duration":"138.104191ms","start":"2026-07-29T19:41:13.75112Z","end":"2026-07-29T19:41:13.889224Z","steps":["trace[1787603376] 'agreement among raft nodes before linearized reading' (duration: 137.994419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:41:13.889349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.223665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:41:13.889409Z","caller":"traceutil/trace.go:171","msg":"trace[896323042] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:20337; }","duration":"109.332088ms","start":"2026-07-29T19:41:13.78006Z","end":"2026-07-29T19:41:13.889393Z","steps":["trace[896323042] 'agreement among raft nodes before linearized reading' (duration: 109.236796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:41:14.160272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.948104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:41:14.160379Z","caller":"traceutil/trace.go:171","msg":"trace[819672316] linearizableReadLoop","detail":"{readStateIndex:21021; appliedIndex:21020; }","duration":"166.867375ms","start":"2026-07-29T19:41:13.993495Z","end":"2026-07-29T19:41:14.160362Z","steps":["trace[819672316] 'read index received' (duration: 10.750627ms)","trace[819672316] 'applied index is now lower than readState.Index' (duration: 156.115738ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:41:14.160466Z","caller":"traceutil/trace.go:171","msg":"trace[1678764689] transaction","detail":"{read_only:false; response_revision:20338; number_of_response:1; }","duration":"221.808124ms","start":"2026-07-29T19:41:13.938617Z","end":"2026-07-29T19:41:14.160425Z","steps":["trace[1678764689] 'process raft request' (duration: 65.621745ms)","trace[1678764689] 'compare' (duration: 155.840182ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:41:14.160506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.997177ms","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:8"} {"level":"info","ts":"2026-07-29T19:41:14.160615Z","caller":"traceutil/trace.go:171","msg":"trace[802518768] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20338; }","duration":"167.142391ms","start":"2026-07-29T19:41:13.993442Z","end":"2026-07-29T19:41:14.160585Z","steps":["trace[802518768] 'agreement among raft nodes before linearized reading' (duration: 166.980987ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:41:14.318501Z","caller":"traceutil/trace.go:171","msg":"trace[1197166020] transaction","detail":"{read_only:false; response_revision:20339; number_of_response:1; }","duration":"108.210333ms","start":"2026-07-29T19:41:14.210257Z","end":"2026-07-29T19:41:14.318468Z","steps":["trace[1197166020] 'process raft request' (duration: 108.025979ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:45:08.696209Z","caller":"traceutil/trace.go:171","msg":"trace[1058346348] transaction","detail":"{read_only:false; response_revision:22713; number_of_response:1; }","duration":"149.828798ms","start":"2026-07-29T19:45:08.546346Z","end":"2026-07-29T19:45:08.696175Z","steps":["trace[1058346348] 'process raft request' (duration: 149.651244ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:45:08.696415Z","caller":"traceutil/trace.go:171","msg":"trace[132794337] linearizableReadLoop","detail":"{readStateIndex:23470; appliedIndex:23470; }","duration":"129.355858ms","start":"2026-07-29T19:45:08.567Z","end":"2026-07-29T19:45:08.696356Z","steps":["trace[132794337] 'read index received' (duration: 129.339048ms)","trace[132794337] 'applied index is now lower than readState.Index' (duration: 14µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:45:08.696678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.643994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-29T19:45:08.696794Z","caller":"traceutil/trace.go:171","msg":"trace[1927311753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22713; }","duration":"129.844789ms","start":"2026-07-29T19:45:08.56692Z","end":"2026-07-29T19:45:08.696765Z","steps":["trace[1927311753] 'agreement among raft nodes before linearized reading' (duration: 129.543512ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:45:08.766453Z","caller":"traceutil/trace.go:171","msg":"trace[164586764] transaction","detail":"{read_only:false; response_revision:22714; number_of_response:1; }","duration":"187.592446ms","start":"2026-07-29T19:45:08.578831Z","end":"2026-07-29T19:45:08.766424Z","steps":["trace[164586764] 'process raft request' (duration: 187.257569ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:45:22.453432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20066} {"level":"info","ts":"2026-07-29T19:45:22.567384Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20066,"took":"110.038063ms","hash":1895703396,"current-db-size-bytes":58916864,"current-db-size":"59 MB","current-db-size-in-use-bytes":42221568,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-29T19:45:22.567473Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1895703396,"revision":20066,"compact-revision":17621} {"level":"info","ts":"2026-07-29T19:48:51.488801Z","caller":"traceutil/trace.go:171","msg":"trace[758849280] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"142.195695ms","start":"2026-07-29T19:48:51.346581Z","end":"2026-07-29T19:48:51.488776Z","steps":["trace[758849280] 'process raft request' (duration: 142.050552ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:48:51.498935Z","caller":"traceutil/trace.go:171","msg":"trace[203651313] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"152.205867ms","start":"2026-07-29T19:48:51.346701Z","end":"2026-07-29T19:48:51.498907Z","steps":["trace[203651313] 'process raft request' (duration: 152.072964ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:48:57.465508Z","caller":"traceutil/trace.go:171","msg":"trace[636047518] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"295.791372ms","start":"2026-07-29T19:48:57.169699Z","end":"2026-07-29T19:48:57.465491Z","steps":["trace[636047518] 'process raft request' (duration: 295.525286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:48:57.465712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.102389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:48:57.465809Z","caller":"traceutil/trace.go:171","msg":"trace[1148753434] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:25019; }","duration":"163.266812ms","start":"2026-07-29T19:48:57.302521Z","end":"2026-07-29T19:48:57.465788Z","steps":["trace[1148753434] 'agreement among raft nodes before linearized reading' (duration: 163.057808ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:48:57.465436Z","caller":"traceutil/trace.go:171","msg":"trace[318829469] linearizableReadLoop","detail":"{readStateIndex:25833; appliedIndex:25832; }","duration":"162.853703ms","start":"2026-07-29T19:48:57.302558Z","end":"2026-07-29T19:48:57.465412Z","steps":["trace[318829469] 'read index received' (duration: 162.661729ms)","trace[318829469] 'applied index is now lower than readState.Index' (duration: 190.934µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:48:57.466015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.317589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:48:57.466052Z","caller":"traceutil/trace.go:171","msg":"trace[1731810249] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25019; }","duration":"138.396501ms","start":"2026-07-29T19:48:57.327641Z","end":"2026-07-29T19:48:57.466037Z","steps":["trace[1731810249] 'agreement among raft nodes before linearized reading' (duration: 138.312179ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:01.728261Z","caller":"traceutil/trace.go:171","msg":"trace[1300280225] transaction","detail":"{read_only:false; response_revision:25050; number_of_response:1; }","duration":"127.970429ms","start":"2026-07-29T19:49:01.60026Z","end":"2026-07-29T19:49:01.72823Z","steps":["trace[1300280225] 'process raft request' (duration: 127.755685ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:01.89523Z","caller":"traceutil/trace.go:171","msg":"trace[915396246] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"292.03559ms","start":"2026-07-29T19:49:01.603169Z","end":"2026-07-29T19:49:01.895205Z","steps":["trace[915396246] 'process raft request' (duration: 291.843156ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:01.895541Z","caller":"traceutil/trace.go:171","msg":"trace[582750634] linearizableReadLoop","detail":"{readStateIndex:25868; appliedIndex:25865; }","duration":"106.551845ms","start":"2026-07-29T19:49:01.788965Z","end":"2026-07-29T19:49:01.895517Z","steps":["trace[582750634] 'read index received' (duration: 106.026552ms)","trace[582750634] 'applied index is now lower than readState.Index' (duration: 524.263µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:01.895524Z","caller":"traceutil/trace.go:171","msg":"trace[415223095] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"290.916595ms","start":"2026-07-29T19:49:01.604569Z","end":"2026-07-29T19:49:01.895486Z","steps":["trace[415223095] 'process raft request' (duration: 290.593357ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:01.895646Z","caller":"traceutil/trace.go:171","msg":"trace[192677914] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"276.350122ms","start":"2026-07-29T19:49:01.619289Z","end":"2026-07-29T19:49:01.895639Z","steps":["trace[192677914] 'process raft request' (duration: 276.102326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:01.895835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.860242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:01.89589Z","caller":"traceutil/trace.go:171","msg":"trace[1614995381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25053; }","duration":"106.959433ms","start":"2026-07-29T19:49:01.788915Z","end":"2026-07-29T19:49:01.895875Z","steps":["trace[1614995381] 'agreement among raft nodes before linearized reading' (duration: 106.868871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:02.169806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.996412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:49:02.169936Z","caller":"traceutil/trace.go:171","msg":"trace[345445919] linearizableReadLoop","detail":"{readStateIndex:25869; appliedIndex:25868; }","duration":"223.11447ms","start":"2026-07-29T19:49:01.946799Z","end":"2026-07-29T19:49:02.169913Z","steps":["trace[345445919] 'read index received' (duration: 79.883422ms)","trace[345445919] 'applied index is now lower than readState.Index' (duration: 143.229478ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:02.17007Z","caller":"traceutil/trace.go:171","msg":"trace[877626843] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"269.506281ms","start":"2026-07-29T19:49:01.900515Z","end":"2026-07-29T19:49:02.170022Z","steps":["trace[877626843] 'process raft request' (duration: 126.18518ms)","trace[877626843] 'compare' (duration: 142.814079ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:02.170179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.374017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-29T19:49:02.170232Z","caller":"traceutil/trace.go:171","msg":"trace[1699291065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25054; }","duration":"223.458269ms","start":"2026-07-29T19:49:01.946755Z","end":"2026-07-29T19:49:02.170213Z","steps":["trace[1699291065] 'agreement among raft nodes before linearized reading' (duration: 223.255574ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:02.181426Z","caller":"traceutil/trace.go:171","msg":"trace[1346736100] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"212.70784ms","start":"2026-07-29T19:49:01.968694Z","end":"2026-07-29T19:49:02.181402Z","steps":["trace[1346736100] 'process raft request' (duration: 212.466974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:02.181436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.186188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-29T19:49:02.181574Z","caller":"traceutil/trace.go:171","msg":"trace[1636196966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25055; }","duration":"230.344902ms","start":"2026-07-29T19:49:01.951199Z","end":"2026-07-29T19:49:02.181544Z","steps":["trace[1636196966] 'agreement among raft nodes before linearized reading' (duration: 230.094647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:02.464157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.243224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:49:02.464263Z","caller":"traceutil/trace.go:171","msg":"trace[409484272] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"289.371692ms","start":"2026-07-29T19:49:02.174872Z","end":"2026-07-29T19:49:02.464244Z","steps":["trace[409484272] 'process raft request' (duration: 133.744288ms)","trace[409484272] 'compare' (duration: 154.703063ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:02.494616Z","caller":"traceutil/trace.go:171","msg":"trace[1960793315] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"310.004158ms","start":"2026-07-29T19:49:02.18459Z","end":"2026-07-29T19:49:02.494594Z","steps":["trace[1960793315] 'process raft request' (duration: 309.695352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:02.494838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:02.184576Z","time spent":"310.191232ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T19:49:02.808168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.460209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:49:02.80836Z","caller":"traceutil/trace.go:171","msg":"trace[37845766] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"469.706181ms","start":"2026-07-29T19:49:02.33863Z","end":"2026-07-29T19:49:02.808336Z","steps":["trace[37845766] 'process raft request' (duration: 313.996326ms)","trace[37845766] 'compare' (duration: 155.355037ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:02.808422Z","caller":"traceutil/trace.go:171","msg":"trace[2057125881] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"211.284087ms","start":"2026-07-29T19:49:02.597124Z","end":"2026-07-29T19:49:02.808408Z","steps":["trace[2057125881] 'process raft request' (duration: 211.236356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:02.808463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:02.338606Z","time spent":"469.808644ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:02.808446Z","caller":"traceutil/trace.go:171","msg":"trace[1032847876] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"392.953408ms","start":"2026-07-29T19:49:02.415455Z","end":"2026-07-29T19:49:02.808408Z","steps":["trace[1032847876] 'process raft request' (duration: 392.814435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:02.808629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:02.415434Z","time spent":"393.088612ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:02.808664Z","caller":"traceutil/trace.go:171","msg":"trace[1336430119] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"373.080158ms","start":"2026-07-29T19:49:02.435572Z","end":"2026-07-29T19:49:02.808652Z","steps":["trace[1336430119] 'process raft request' (duration: 372.74643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:02.80873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:02.435548Z","time spent":"373.141951ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:02.808822Z","caller":"traceutil/trace.go:171","msg":"trace[877453497] linearizableReadLoop","detail":"{readStateIndex:25873; appliedIndex:25871; }","duration":"417.266418ms","start":"2026-07-29T19:49:02.391545Z","end":"2026-07-29T19:49:02.808811Z","steps":["trace[877453497] 'read index received' (duration: 102.79491ms)","trace[877453497] 'applied index is now lower than readState.Index' (duration: 314.470408ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:02.808907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.370261ms","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:6"} {"level":"info","ts":"2026-07-29T19:49:02.808944Z","caller":"traceutil/trace.go:171","msg":"trace[1047306593] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25061; }","duration":"417.444053ms","start":"2026-07-29T19:49:02.391489Z","end":"2026-07-29T19:49:02.808933Z","steps":["trace[1047306593] 'agreement among raft nodes before linearized reading' (duration: 417.381621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:02.808976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:02.391464Z","time spent":"417.503344ms","remote":"127.0.0.1:47492","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":"info","ts":"2026-07-29T19:49:03.007038Z","caller":"traceutil/trace.go:171","msg":"trace[780317584] linearizableReadLoop","detail":"{readStateIndex:25877; appliedIndex:25876; }","duration":"198.173366ms","start":"2026-07-29T19:49:02.808847Z","end":"2026-07-29T19:49:03.00702Z","steps":["trace[780317584] 'read index received' (duration: 198.013573ms)","trace[780317584] 'applied index is now lower than readState.Index' (duration: 159.163µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:03.007186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.645051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-29T19:49:03.007176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.310401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:03.007217Z","caller":"traceutil/trace.go:171","msg":"trace[624917985] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:25062; }","duration":"547.720222ms","start":"2026-07-29T19:49:02.459484Z","end":"2026-07-29T19:49:03.007204Z","steps":["trace[624917985] 'agreement among raft nodes before linearized reading' (duration: 547.6295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:03.007192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.196792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:03.007235Z","caller":"traceutil/trace.go:171","msg":"trace[974502050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25062; }","duration":"217.387933ms","start":"2026-07-29T19:49:02.789832Z","end":"2026-07-29T19:49:03.00722Z","steps":["trace[974502050] 'agreement among raft nodes before linearized reading' (duration: 217.24979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:03.007256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:02.459461Z","time spent":"547.780974ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-07-29T19:49:03.007282Z","caller":"traceutil/trace.go:171","msg":"trace[164023788] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:25062; }","duration":"392.339246ms","start":"2026-07-29T19:49:02.61492Z","end":"2026-07-29T19:49:03.007259Z","steps":["trace[164023788] 'agreement among raft nodes before linearized reading' (duration: 392.192532ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:03.007307Z","caller":"traceutil/trace.go:171","msg":"trace[768177507] transaction","detail":"{read_only:false; response_revision:25062; number_of_response:1; }","duration":"302.178254ms","start":"2026-07-29T19:49:02.705103Z","end":"2026-07-29T19:49:03.007281Z","steps":["trace[768177507] 'process raft request' (duration: 301.800536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:03.007339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:02.614898Z","time spent":"392.422527ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-07-29T19:49:03.007386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.796414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-29T19:49:03.00744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:02.705073Z","time spent":"302.285387ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T19:49:03.007211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.004766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:03.007478Z","caller":"traceutil/trace.go:171","msg":"trace[281540647] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25062; }","duration":"218.932148ms","start":"2026-07-29T19:49:02.788522Z","end":"2026-07-29T19:49:03.007454Z","steps":["trace[281540647] 'agreement among raft nodes before linearized reading' (duration: 218.746294ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:03.007528Z","caller":"traceutil/trace.go:171","msg":"trace[6991041] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25062; }","duration":"195.335083ms","start":"2026-07-29T19:49:02.812178Z","end":"2026-07-29T19:49:03.007513Z","steps":["trace[6991041] 'agreement among raft nodes before linearized reading' (duration: 194.970875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:03.394391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.833932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-29T19:49:03.394433Z","caller":"traceutil/trace.go:171","msg":"trace[1724228451] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"253.057815ms","start":"2026-07-29T19:49:03.141344Z","end":"2026-07-29T19:49:03.394401Z","steps":["trace[1724228451] 'process raft request' (duration: 252.491722ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:03.39448Z","caller":"traceutil/trace.go:171","msg":"trace[2003367837] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25062; }","duration":"262.942225ms","start":"2026-07-29T19:49:03.131519Z","end":"2026-07-29T19:49:03.394461Z","steps":["trace[2003367837] 'count revisions from in-memory index tree' (duration: 262.687809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:03.759657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.270248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:49:03.759747Z","caller":"traceutil/trace.go:171","msg":"trace[695441628] linearizableReadLoop","detail":"{readStateIndex:25879; appliedIndex:25878; }","duration":"192.785648ms","start":"2026-07-29T19:49:03.566949Z","end":"2026-07-29T19:49:03.759735Z","steps":["trace[695441628] 'read index received' (duration: 13.311126ms)","trace[695441628] 'applied index is now lower than readState.Index' (duration: 179.473692ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:03.759748Z","caller":"traceutil/trace.go:171","msg":"trace[768440021] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"267.157287ms","start":"2026-07-29T19:49:03.492576Z","end":"2026-07-29T19:49:03.759733Z","steps":["trace[768440021] 'process raft request' (duration: 87.743916ms)","trace[768440021] 'compare' (duration: 179.160056ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:03.759882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.916871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:03.759964Z","caller":"traceutil/trace.go:171","msg":"trace[1533236724] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25064; }","duration":"193.047104ms","start":"2026-07-29T19:49:03.566897Z","end":"2026-07-29T19:49:03.759944Z","steps":["trace[1533236724] 'agreement among raft nodes before linearized reading' (duration: 192.89662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:04.098875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.47341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:49:04.099006Z","caller":"traceutil/trace.go:171","msg":"trace[712920572] linearizableReadLoop","detail":"{readStateIndex:25880; appliedIndex:25879; }","duration":"199.080618ms","start":"2026-07-29T19:49:03.899903Z","end":"2026-07-29T19:49:04.098984Z","steps":["trace[712920572] 'read index received' (duration: 34.381863ms)","trace[712920572] 'applied index is now lower than readState.Index' (duration: 164.696744ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:04.099085Z","caller":"traceutil/trace.go:171","msg":"trace[1065577543] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"335.216068ms","start":"2026-07-29T19:49:03.763834Z","end":"2026-07-29T19:49:04.09905Z","steps":["trace[1065577543] 'process raft request' (duration: 170.466222ms)","trace[1065577543] 'compare' (duration: 164.287336ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:04.099158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.247851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-29T19:49:04.099234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:03.763809Z","time spent":"335.345821ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:04.099191Z","caller":"traceutil/trace.go:171","msg":"trace[2122946928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25065; }","duration":"199.306202ms","start":"2026-07-29T19:49:03.899874Z","end":"2026-07-29T19:49:04.099181Z","steps":["trace[2122946928] 'agreement among raft nodes before linearized reading' (duration: 199.164279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:04.409641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.240419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:49:04.409871Z","caller":"traceutil/trace.go:171","msg":"trace[601816977] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"509.501425ms","start":"2026-07-29T19:49:03.900338Z","end":"2026-07-29T19:49:04.409839Z","steps":["trace[601816977] 'process raft request' (duration: 337.967189ms)","trace[601816977] 'compare' (duration: 170.848731ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:04.409908Z","caller":"traceutil/trace.go:171","msg":"trace[1366018994] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"508.201186ms","start":"2026-07-29T19:49:03.901689Z","end":"2026-07-29T19:49:04.40989Z","steps":["trace[1366018994] 'process raft request' (duration: 508.059313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:04.41001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:03.901679Z","time spent":"508.269857ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T19:49:04.410022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:03.900303Z","time spent":"509.640628ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:04.410278Z","caller":"traceutil/trace.go:171","msg":"trace[532706110] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"306.926139ms","start":"2026-07-29T19:49:04.103341Z","end":"2026-07-29T19:49:04.410267Z","steps":["trace[532706110] 'process raft request' (duration: 306.5083ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:04.410308Z","caller":"traceutil/trace.go:171","msg":"trace[77472338] linearizableReadLoop","detail":"{readStateIndex:25883; appliedIndex:25880; }","duration":"234.80042ms","start":"2026-07-29T19:49:04.175492Z","end":"2026-07-29T19:49:04.410292Z","steps":["trace[77472338] 'read index received' (duration: 62.830915ms)","trace[77472338] 'applied index is now lower than readState.Index' (duration: 171.968725ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:04.410358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:04.103318Z","time spent":"307.004012ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:04.410379Z","caller":"traceutil/trace.go:171","msg":"trace[759492348] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"224.019331ms","start":"2026-07-29T19:49:04.186346Z","end":"2026-07-29T19:49:04.410365Z","steps":["trace[759492348] 'process raft request' (duration: 223.703014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:04.410449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.949303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-29T19:49:04.410491Z","caller":"traceutil/trace.go:171","msg":"trace[644059772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25069; }","duration":"234.995554ms","start":"2026-07-29T19:49:04.175485Z","end":"2026-07-29T19:49:04.410481Z","steps":["trace[644059772] 'agreement among raft nodes before linearized reading' (duration: 234.860981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:04.712187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.815599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:49:04.712324Z","caller":"traceutil/trace.go:171","msg":"trace[1821203173] linearizableReadLoop","detail":"{readStateIndex:25886; appliedIndex:25884; }","duration":"243.633425ms","start":"2026-07-29T19:49:04.468676Z","end":"2026-07-29T19:49:04.712309Z","steps":["trace[1821203173] 'read index received' (duration: 77.573141ms)","trace[1821203173] 'applied index is now lower than readState.Index' (duration: 166.059304ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:04.712414Z","caller":"traceutil/trace.go:171","msg":"trace[103813563] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"305.386636ms","start":"2026-07-29T19:49:04.407016Z","end":"2026-07-29T19:49:04.712403Z","steps":["trace[103813563] 'process raft request' (duration: 139.293291ms)","trace[103813563] 'compare' (duration: 165.713867ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:04.712468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:04.406991Z","time spent":"305.446527ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:04.712626Z","caller":"traceutil/trace.go:171","msg":"trace[1430495929] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"298.423631ms","start":"2026-07-29T19:49:04.414168Z","end":"2026-07-29T19:49:04.712592Z","steps":["trace[1430495929] 'process raft request' (duration: 298.096724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:04.712669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.971603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-29T19:49:04.712748Z","caller":"traceutil/trace.go:171","msg":"trace[2080685860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25071; }","duration":"244.090465ms","start":"2026-07-29T19:49:04.468635Z","end":"2026-07-29T19:49:04.712725Z","steps":["trace[2080685860] 'agreement among raft nodes before linearized reading' (duration: 243.822889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:04.712707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.370326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-29T19:49:04.712814Z","caller":"traceutil/trace.go:171","msg":"trace[194900385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25071; }","duration":"214.514559ms","start":"2026-07-29T19:49:04.498275Z","end":"2026-07-29T19:49:04.71279Z","steps":["trace[194900385] 'agreement among raft nodes before linearized reading' (duration: 214.292924ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:04.942722Z","caller":"traceutil/trace.go:171","msg":"trace[1278210196] transaction","detail":"{read_only:false; response_revision:25073; number_of_response:1; }","duration":"225.384611ms","start":"2026-07-29T19:49:04.717306Z","end":"2026-07-29T19:49:04.942691Z","steps":["trace[1278210196] 'process raft request' (duration: 195.321804ms)","trace[1278210196] 'compare' (duration: 29.719249ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:04.942757Z","caller":"traceutil/trace.go:171","msg":"trace[809964361] transaction","detail":"{read_only:false; response_revision:25074; number_of_response:1; }","duration":"130.061225ms","start":"2026-07-29T19:49:04.812676Z","end":"2026-07-29T19:49:04.942737Z","steps":["trace[809964361] 'process raft request' (duration: 129.961873ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:04.942858Z","caller":"traceutil/trace.go:171","msg":"trace[894068246] transaction","detail":"{read_only:false; response_revision:25076; number_of_response:1; }","duration":"127.778034ms","start":"2026-07-29T19:49:04.815063Z","end":"2026-07-29T19:49:04.942841Z","steps":["trace[894068246] 'process raft request' (duration: 127.718623ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:04.942891Z","caller":"traceutil/trace.go:171","msg":"trace[2056162443] linearizableReadLoop","detail":"{readStateIndex:25888; appliedIndex:25887; }","duration":"153.868504ms","start":"2026-07-29T19:49:04.789014Z","end":"2026-07-29T19:49:04.942882Z","steps":["trace[2056162443] 'read index received' (duration: 123.748446ms)","trace[2056162443] 'applied index is now lower than readState.Index' (duration: 30.119308ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:04.942975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.946295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:04.943003Z","caller":"traceutil/trace.go:171","msg":"trace[1490348469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25076; }","duration":"154.029137ms","start":"2026-07-29T19:49:04.788968Z","end":"2026-07-29T19:49:04.942997Z","steps":["trace[1490348469] 'agreement among raft nodes before linearized reading' (duration: 153.940375ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:04.943012Z","caller":"traceutil/trace.go:171","msg":"trace[1562842114] transaction","detail":"{read_only:false; response_revision:25075; number_of_response:1; }","duration":"128.329797ms","start":"2026-07-29T19:49:04.814674Z","end":"2026-07-29T19:49:04.943004Z","steps":["trace[1562842114] 'process raft request' (duration: 128.03237ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:06.096098Z","caller":"traceutil/trace.go:171","msg":"trace[501796719] transaction","detail":"{read_only:false; response_revision:25078; number_of_response:1; }","duration":"331.111117ms","start":"2026-07-29T19:49:05.764962Z","end":"2026-07-29T19:49:06.096073Z","steps":["trace[501796719] 'process raft request' (duration: 330.906542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:06.096252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:05.764937Z","time spent":"331.237499ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:06.096967Z","caller":"traceutil/trace.go:171","msg":"trace[602223341] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"298.284468ms","start":"2026-07-29T19:49:05.798666Z","end":"2026-07-29T19:49:06.09695Z","steps":["trace[602223341] 'process raft request' (duration: 297.896389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:06.41555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.81193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-29T19:49:06.415652Z","caller":"traceutil/trace.go:171","msg":"trace[236747986] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:25080; }","duration":"184.984354ms","start":"2026-07-29T19:49:06.230647Z","end":"2026-07-29T19:49:06.415632Z","steps":["trace[236747986] 'count revisions from in-memory index tree' (duration: 184.696148ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:06.721976Z","caller":"traceutil/trace.go:171","msg":"trace[468533426] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"304.927216ms","start":"2026-07-29T19:49:06.417025Z","end":"2026-07-29T19:49:06.721952Z","steps":["trace[468533426] 'process raft request' (duration: 304.823263ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:06.721982Z","caller":"traceutil/trace.go:171","msg":"trace[1502211195] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"305.855996ms","start":"2026-07-29T19:49:06.416086Z","end":"2026-07-29T19:49:06.721942Z","steps":["trace[1502211195] 'process raft request' (duration: 287.712824ms)","trace[1502211195] 'compare' (duration: 17.526399ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:06.722029Z","caller":"traceutil/trace.go:171","msg":"trace[1462026517] linearizableReadLoop","detail":"{readStateIndex:25900; appliedIndex:25897; }","duration":"146.440789ms","start":"2026-07-29T19:49:06.575564Z","end":"2026-07-29T19:49:06.722005Z","steps":["trace[1462026517] 'read index received' (duration: 128.406699ms)","trace[1462026517] 'applied index is now lower than readState.Index' (duration: 18.03311ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:06.722162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:06.417001Z","time spent":"305.048508ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T19:49:06.722181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:06.416053Z","time spent":"306.00912ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T19:49:06.722465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.888919ms","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-07-29T19:49:06.722407Z","caller":"traceutil/trace.go:171","msg":"trace[2124044891] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"302.692557ms","start":"2026-07-29T19:49:06.419678Z","end":"2026-07-29T19:49:06.72237Z","steps":["trace[2124044891] 'process raft request' (duration: 302.231326ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:06.7225Z","caller":"traceutil/trace.go:171","msg":"trace[729789159] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25084; }","duration":"146.973921ms","start":"2026-07-29T19:49:06.575518Z","end":"2026-07-29T19:49:06.722492Z","steps":["trace[729789159] 'agreement among raft nodes before linearized reading' (duration: 146.835748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:06.722615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:06.419655Z","time spent":"302.86802ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:06.890676Z","caller":"traceutil/trace.go:171","msg":"trace[567466071] linearizableReadLoop","detail":"{readStateIndex:25902; appliedIndex:25901; }","duration":"103.112628ms","start":"2026-07-29T19:49:06.787514Z","end":"2026-07-29T19:49:06.890627Z","steps":["trace[567466071] 'read index received' (duration: 96.841278ms)","trace[567466071] 'applied index is now lower than readState.Index' (duration: 6.27054ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:06.890749Z","caller":"traceutil/trace.go:171","msg":"trace[2084284170] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"132.0772ms","start":"2026-07-29T19:49:06.758651Z","end":"2026-07-29T19:49:06.890728Z","steps":["trace[2084284170] 'process raft request' (duration: 125.675038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:06.890829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.295082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:06.890864Z","caller":"traceutil/trace.go:171","msg":"trace[870869243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25086; }","duration":"103.345403ms","start":"2026-07-29T19:49:06.787507Z","end":"2026-07-29T19:49:06.890853Z","steps":["trace[870869243] 'agreement among raft nodes before linearized reading' (duration: 103.267241ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:07.083955Z","caller":"traceutil/trace.go:171","msg":"trace[523922118] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"136.455498ms","start":"2026-07-29T19:49:06.947438Z","end":"2026-07-29T19:49:07.083894Z","steps":["trace[523922118] 'process raft request' (duration: 134.627958ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:07.172248Z","caller":"traceutil/trace.go:171","msg":"trace[2107737791] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"224.770297ms","start":"2026-07-29T19:49:06.947452Z","end":"2026-07-29T19:49:07.172222Z","steps":["trace[2107737791] 'process raft request' (duration: 224.508881ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:07.17234Z","caller":"traceutil/trace.go:171","msg":"trace[746607495] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"224.656015ms","start":"2026-07-29T19:49:06.947665Z","end":"2026-07-29T19:49:07.172321Z","steps":["trace[746607495] 'process raft request' (duration: 224.406399ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:07.172419Z","caller":"traceutil/trace.go:171","msg":"trace[604412426] transaction","detail":"{read_only:false; response_revision:25090; number_of_response:1; }","duration":"223.13014ms","start":"2026-07-29T19:49:06.949249Z","end":"2026-07-29T19:49:07.17238Z","steps":["trace[604412426] 'process raft request' (duration: 223.007927ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:07.172517Z","caller":"traceutil/trace.go:171","msg":"trace[1051595236] linearizableReadLoop","detail":"{readStateIndex:25906; appliedIndex:25902; }","duration":"220.83807ms","start":"2026-07-29T19:49:06.951669Z","end":"2026-07-29T19:49:07.172507Z","steps":["trace[1051595236] 'read index received' (duration: 130.565687ms)","trace[1051595236] 'applied index is now lower than readState.Index' (duration: 90.271453ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:07.172596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.906321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:07.172623Z","caller":"traceutil/trace.go:171","msg":"trace[1819725208] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25090; }","duration":"220.960413ms","start":"2026-07-29T19:49:06.951655Z","end":"2026-07-29T19:49:07.172616Z","steps":["trace[1819725208] 'agreement among raft nodes before linearized reading' (duration: 220.897212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:07.434433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.152103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:07.434517Z","caller":"traceutil/trace.go:171","msg":"trace[971115806] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25090; }","duration":"201.294816ms","start":"2026-07-29T19:49:07.233203Z","end":"2026-07-29T19:49:07.434498Z","steps":["trace[971115806] 'count revisions from in-memory index tree' (duration: 201.051471ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:07.581375Z","caller":"traceutil/trace.go:171","msg":"trace[1069194002] transaction","detail":"{read_only:false; response_revision:25091; number_of_response:1; }","duration":"120.908602ms","start":"2026-07-29T19:49:07.460441Z","end":"2026-07-29T19:49:07.58135Z","steps":["trace[1069194002] 'process raft request' (duration: 120.556645ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:08.184208Z","caller":"traceutil/trace.go:171","msg":"trace[1628238320] transaction","detail":"{read_only:false; response_revision:25092; number_of_response:1; }","duration":"171.127267ms","start":"2026-07-29T19:49:08.013057Z","end":"2026-07-29T19:49:08.184185Z","steps":["trace[1628238320] 'process raft request' (duration: 170.979174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:08.485395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.071557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:49:08.485496Z","caller":"traceutil/trace.go:171","msg":"trace[271461763] linearizableReadLoop","detail":"{readStateIndex:25909; appliedIndex:25907; }","duration":"366.774238ms","start":"2026-07-29T19:49:08.118706Z","end":"2026-07-29T19:49:08.48548Z","steps":["trace[271461763] 'read index received' (duration: 65.348589ms)","trace[271461763] 'applied index is now lower than readState.Index' (duration: 301.424579ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:08.485633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.936502ms","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-07-29T19:49:08.485668Z","caller":"traceutil/trace.go:171","msg":"trace[1536517401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25093; }","duration":"367.008802ms","start":"2026-07-29T19:49:08.118647Z","end":"2026-07-29T19:49:08.485656Z","steps":["trace[1536517401] 'agreement among raft nodes before linearized reading' (duration: 366.88205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:08.485705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:08.118625Z","time spent":"367.071744ms","remote":"127.0.0.1:48830","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":"info","ts":"2026-07-29T19:49:08.485962Z","caller":"traceutil/trace.go:171","msg":"trace[312385166] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"384.795458ms","start":"2026-07-29T19:49:08.101148Z","end":"2026-07-29T19:49:08.485943Z","steps":["trace[312385166] 'process raft request' (duration: 208.092327ms)","trace[312385166] 'compare' (duration: 175.933014ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:08.486045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:08.101125Z","time spent":"384.875489ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:08.648094Z","caller":"traceutil/trace.go:171","msg":"trace[388065177] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"216.975884ms","start":"2026-07-29T19:49:08.431089Z","end":"2026-07-29T19:49:08.648065Z","steps":["trace[388065177] 'process raft request' (duration: 216.589716ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:08.648943Z","caller":"traceutil/trace.go:171","msg":"trace[1021062399] transaction","detail":"{read_only:false; response_revision:25095; number_of_response:1; }","duration":"158.320272ms","start":"2026-07-29T19:49:08.490598Z","end":"2026-07-29T19:49:08.648918Z","steps":["trace[1021062399] 'process raft request' (duration: 158.079737ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:15.869682Z","caller":"traceutil/trace.go:171","msg":"trace[1751288504] linearizableReadLoop","detail":"{readStateIndex:25972; appliedIndex:25971; }","duration":"253.226519ms","start":"2026-07-29T19:49:15.616425Z","end":"2026-07-29T19:49:15.869652Z","steps":["trace[1751288504] 'read index received' (duration: 252.924743ms)","trace[1751288504] 'applied index is now lower than readState.Index' (duration: 300.496µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:15.869798Z","caller":"traceutil/trace.go:171","msg":"trace[273920742] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"254.590819ms","start":"2026-07-29T19:49:15.615172Z","end":"2026-07-29T19:49:15.869763Z","steps":["trace[273920742] 'process raft request' (duration: 254.266552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:15.869975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.520356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.12\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-29T19:49:15.870065Z","caller":"traceutil/trace.go:171","msg":"trace[1055209029] range","detail":"{range_begin:/registry/masterleases/199.19.213.12; range_end:; response_count:1; response_revision:25154; }","duration":"253.656509ms","start":"2026-07-29T19:49:15.616388Z","end":"2026-07-29T19:49:15.870045Z","steps":["trace[1055209029] 'agreement among raft nodes before linearized reading' (duration: 253.401193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:16.208119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.741168ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-29T19:49:16.208242Z","caller":"traceutil/trace.go:171","msg":"trace[1521140954] linearizableReadLoop","detail":"{readStateIndex:25973; appliedIndex:25972; }","duration":"178.678865ms","start":"2026-07-29T19:49:16.029543Z","end":"2026-07-29T19:49:16.208222Z","steps":["trace[1521140954] 'read index received' (duration: 34.841µs)","trace[1521140954] 'applied index is now lower than readState.Index' (duration: 178.641894ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:16.208345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.793217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:16.208401Z","caller":"traceutil/trace.go:171","msg":"trace[1982179707] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:25154; }","duration":"178.88759ms","start":"2026-07-29T19:49:16.0295Z","end":"2026-07-29T19:49:16.208387Z","steps":["trace[1982179707] 'agreement among raft nodes before linearized reading' (duration: 178.781357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:16.208439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:15.871151Z","time spent":"337.279504ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-29T19:49:16.811171Z","caller":"traceutil/trace.go:171","msg":"trace[171450889] linearizableReadLoop","detail":"{readStateIndex:25975; appliedIndex:25974; }","duration":"228.232505ms","start":"2026-07-29T19:49:16.582914Z","end":"2026-07-29T19:49:16.811147Z","steps":["trace[171450889] 'read index received' (duration: 224.438751ms)","trace[171450889] 'applied index is now lower than readState.Index' (duration: 3.792424ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:16.810293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.336224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-29T19:49:16.811257Z","caller":"traceutil/trace.go:171","msg":"trace[1290019446] transaction","detail":"{read_only:false; response_revision:25156; number_of_response:1; }","duration":"284.451222ms","start":"2026-07-29T19:49:16.52679Z","end":"2026-07-29T19:49:16.811241Z","steps":["trace[1290019446] 'process raft request' (duration: 280.551066ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:16.811278Z","caller":"traceutil/trace.go:171","msg":"trace[1029686883] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:25155; }","duration":"287.372987ms","start":"2026-07-29T19:49:16.523881Z","end":"2026-07-29T19:49:16.811254Z","steps":["trace[1029686883] 'count revisions from in-memory index tree' (duration: 286.257043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:16.811385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.47447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-29T19:49:16.811419Z","caller":"traceutil/trace.go:171","msg":"trace[2078221073] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25156; }","duration":"228.561192ms","start":"2026-07-29T19:49:16.582846Z","end":"2026-07-29T19:49:16.811407Z","steps":["trace[2078221073] 'agreement among raft nodes before linearized reading' (duration: 228.409229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:17.311734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16933147223998252011,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-29T19:49:17.385997Z","caller":"traceutil/trace.go:171","msg":"trace[1528947828] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"700.665917ms","start":"2026-07-29T19:49:16.685305Z","end":"2026-07-29T19:49:17.385971Z","steps":["trace[1528947828] 'process raft request' (duration: 700.379071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:17.386184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:16.685279Z","time spent":"700.78521ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:17.386234Z","caller":"traceutil/trace.go:171","msg":"trace[644945150] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"634.874766ms","start":"2026-07-29T19:49:16.751344Z","end":"2026-07-29T19:49:17.386219Z","steps":["trace[644945150] 'process raft request' (duration: 634.559769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:17.386311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:16.751322Z","time spent":"634.952837ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:17.386363Z","caller":"traceutil/trace.go:171","msg":"trace[718597905] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"630.013839ms","start":"2026-07-29T19:49:16.75632Z","end":"2026-07-29T19:49:17.386334Z","steps":["trace[718597905] 'process raft request' (duration: 629.847735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:17.386521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:16.756292Z","time spent":"630.139992ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T19:49:17.734842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.465062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:49:17.735002Z","caller":"traceutil/trace.go:171","msg":"trace[748870256] linearizableReadLoop","detail":"{readStateIndex:25981; appliedIndex:25975; }","duration":"923.759607ms","start":"2026-07-29T19:49:16.811231Z","end":"2026-07-29T19:49:17.734991Z","steps":["trace[748870256] 'read index received' (duration: 574.489497ms)","trace[748870256] 'applied index is now lower than readState.Index' (duration: 349.26947ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:17.735059Z","caller":"traceutil/trace.go:171","msg":"trace[855033950] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"528.456766ms","start":"2026-07-29T19:49:17.206544Z","end":"2026-07-29T19:49:17.735001Z","steps":["trace[855033950] 'process raft request' (duration: 528.368404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:17.735114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.308051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:17.735196Z","caller":"traceutil/trace.go:171","msg":"trace[817535424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25162; }","duration":"948.426834ms","start":"2026-07-29T19:49:16.786752Z","end":"2026-07-29T19:49:17.735178Z","steps":["trace[817535424] 'agreement among raft nodes before linearized reading' (duration: 948.281271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:17.735241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:16.786712Z","time spent":"948.520767ms","remote":"127.0.0.1:48552","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-07-29T19:49:17.735336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049549438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-29T19:49:17.735478Z","caller":"traceutil/trace.go:171","msg":"trace[732097565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25162; }","duration":"1.049732022s","start":"2026-07-29T19:49:16.685724Z","end":"2026-07-29T19:49:17.735456Z","steps":["trace[732097565] 'agreement among raft nodes before linearized reading' (duration: 1.049423226s)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:17.735511Z","caller":"traceutil/trace.go:171","msg":"trace[1922836377] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"510.126178ms","start":"2026-07-29T19:49:17.225373Z","end":"2026-07-29T19:49:17.735499Z","steps":["trace[1922836377] 'process raft request' (duration: 509.586577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:17.735536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:16.685707Z","time spent":"1.049815774s","remote":"127.0.0.1:48830","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":"warn","ts":"2026-07-29T19:49:17.73542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:17.206521Z","time spent":"528.62613ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:17.735047Z","caller":"traceutil/trace.go:171","msg":"trace[1152354289] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"618.554835ms","start":"2026-07-29T19:49:17.116459Z","end":"2026-07-29T19:49:17.735014Z","steps":["trace[1152354289] 'process raft request' (duration: 338.851498ms)","trace[1152354289] 'compare' (duration: 279.226846ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:17.735788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.043297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-29T19:49:17.735836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.991185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"warn","ts":"2026-07-29T19:49:17.735834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:17.116442Z","time spent":"619.303302ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:17.735846Z","caller":"traceutil/trace.go:171","msg":"trace[773094319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25162; }","duration":"798.100679ms","start":"2026-07-29T19:49:16.937731Z","end":"2026-07-29T19:49:17.735832Z","steps":["trace[773094319] 'agreement among raft nodes before linearized reading' (duration: 797.955716ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:17.73589Z","caller":"traceutil/trace.go:171","msg":"trace[1004202916] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25162; }","duration":"683.078737ms","start":"2026-07-29T19:49:17.052798Z","end":"2026-07-29T19:49:17.735877Z","steps":["trace[1004202916] 'agreement among raft nodes before linearized reading' (duration: 682.930943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:17.7359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:16.937672Z","time spent":"798.216831ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-29T19:49:17.735926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:17.052769Z","time spent":"683.150609ms","remote":"127.0.0.1:48720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1873,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"warn","ts":"2026-07-29T19:49:17.73592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.563203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-07-29T19:49:17.735611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:17.225352Z","time spent":"510.19502ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:17.735977Z","caller":"traceutil/trace.go:171","msg":"trace[860122380] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25162; }","duration":"718.658026ms","start":"2026-07-29T19:49:17.017305Z","end":"2026-07-29T19:49:17.735963Z","steps":["trace[860122380] 'agreement among raft nodes before linearized reading' (duration: 718.472722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:17.736009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:17.017289Z","time spent":"718.714866ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-29T19:49:17.736261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.763785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-29T19:49:17.736352Z","caller":"traceutil/trace.go:171","msg":"trace[2076405490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25162; }","duration":"954.877467ms","start":"2026-07-29T19:49:16.78146Z","end":"2026-07-29T19:49:17.736337Z","steps":["trace[2076405490] 'agreement among raft nodes before linearized reading' (duration: 953.601989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:17.736565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:16.781451Z","time spent":"955.099442ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-29T19:49:17.736635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.186907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-29T19:49:17.736591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.122363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-29T19:49:17.736694Z","caller":"traceutil/trace.go:171","msg":"trace[58261173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25162; }","duration":"961.292759ms","start":"2026-07-29T19:49:16.775384Z","end":"2026-07-29T19:49:17.736677Z","steps":["trace[58261173] 'agreement among raft nodes before linearized reading' (duration: 961.100975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:17.736748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:16.775365Z","time spent":"961.36833ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-29T19:49:17.736764Z","caller":"traceutil/trace.go:171","msg":"trace[147059799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25162; }","duration":"503.305877ms","start":"2026-07-29T19:49:17.233412Z","end":"2026-07-29T19:49:17.736718Z","steps":["trace[147059799] 'agreement among raft nodes before linearized reading' (duration: 502.405577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:17.736884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:17.233394Z","time spent":"503.47091ms","remote":"127.0.0.1:48830","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-07-29T19:49:22.816477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.49395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5180706d-0aaf-43aa-a8e9-da4c21afa629\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-07-29T19:49:22.816551Z","caller":"traceutil/trace.go:171","msg":"trace[1110828454] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5180706d-0aaf-43aa-a8e9-da4c21afa629; range_end:; response_count:1; response_revision:25257; }","duration":"164.614723ms","start":"2026-07-29T19:49:22.651918Z","end":"2026-07-29T19:49:22.816533Z","steps":["trace[1110828454] 'range keys from in-memory index tree' (duration: 164.396038ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:22.938542Z","caller":"traceutil/trace.go:171","msg":"trace[2015282449] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"111.587317ms","start":"2026-07-29T19:49:22.826925Z","end":"2026-07-29T19:49:22.938513Z","steps":["trace[2015282449] 'process raft request' (duration: 111.354832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:23.197666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.382271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:23.197749Z","caller":"traceutil/trace.go:171","msg":"trace[1247306940] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25258; }","duration":"175.512715ms","start":"2026-07-29T19:49:23.022218Z","end":"2026-07-29T19:49:23.19773Z","steps":["trace[1247306940] 'count revisions from in-memory index tree' (duration: 175.32092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:23.197841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.400278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-07-29T19:49:23.197646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.823603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:23.197931Z","caller":"traceutil/trace.go:171","msg":"trace[1803602078] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25258; }","duration":"114.546092ms","start":"2026-07-29T19:49:23.083365Z","end":"2026-07-29T19:49:23.197911Z","steps":["trace[1803602078] 'range keys from in-memory index tree' (duration: 114.197994ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:23.197985Z","caller":"traceutil/trace.go:171","msg":"trace[602748048] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25258; }","duration":"172.186021ms","start":"2026-07-29T19:49:23.02578Z","end":"2026-07-29T19:49:23.197966Z","steps":["trace[602748048] 'range keys from in-memory index tree' (duration: 171.711331ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:23.513383Z","caller":"traceutil/trace.go:171","msg":"trace[1125030531] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"290.472934ms","start":"2026-07-29T19:49:23.222882Z","end":"2026-07-29T19:49:23.513355Z","steps":["trace[1125030531] 'process raft request' (duration: 290.29281ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:23.522018Z","caller":"traceutil/trace.go:171","msg":"trace[817832633] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"115.939842ms","start":"2026-07-29T19:49:23.406055Z","end":"2026-07-29T19:49:23.521994Z","steps":["trace[817832633] 'process raft request' (duration: 115.890931ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:23.522062Z","caller":"traceutil/trace.go:171","msg":"trace[2069315015] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"117.685202ms","start":"2026-07-29T19:49:23.404345Z","end":"2026-07-29T19:49:23.52203Z","steps":["trace[2069315015] 'process raft request' (duration: 117.419716ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:23.522142Z","caller":"traceutil/trace.go:171","msg":"trace[1327382886] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"116.25536ms","start":"2026-07-29T19:49:23.405812Z","end":"2026-07-29T19:49:23.522067Z","steps":["trace[1327382886] 'process raft request' (duration: 116.083526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:23.780971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.370544ms","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:8"} {"level":"info","ts":"2026-07-29T19:49:23.781039Z","caller":"traceutil/trace.go:171","msg":"trace[1088134741] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25262; }","duration":"126.448246ms","start":"2026-07-29T19:49:23.654572Z","end":"2026-07-29T19:49:23.78102Z","steps":["trace[1088134741] 'count revisions from in-memory index tree' (duration: 126.158509ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:25.998588Z","caller":"traceutil/trace.go:171","msg":"trace[1870051513] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"157.362222ms","start":"2026-07-29T19:49:25.84119Z","end":"2026-07-29T19:49:25.998552Z","steps":["trace[1870051513] 'process raft request' (duration: 157.139006ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:26.048078Z","caller":"traceutil/trace.go:171","msg":"trace[1927842388] linearizableReadLoop","detail":"{readStateIndex:26103; appliedIndex:26100; }","duration":"198.75437ms","start":"2026-07-29T19:49:25.849302Z","end":"2026-07-29T19:49:26.048057Z","steps":["trace[1927842388] 'read index received' (duration: 149.052007ms)","trace[1927842388] 'applied index is now lower than readState.Index' (duration: 49.701123ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:26.048124Z","caller":"traceutil/trace.go:171","msg":"trace[1099272334] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"201.093582ms","start":"2026-07-29T19:49:25.846989Z","end":"2026-07-29T19:49:26.048083Z","steps":["trace[1099272334] 'process raft request' (duration: 200.984629ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:26.048209Z","caller":"traceutil/trace.go:171","msg":"trace[2040383268] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"147.966903ms","start":"2026-07-29T19:49:25.900231Z","end":"2026-07-29T19:49:26.048198Z","steps":["trace[2040383268] 'process raft request' (duration: 147.784319ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:26.048378Z","caller":"traceutil/trace.go:171","msg":"trace[841062241] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"202.37609ms","start":"2026-07-29T19:49:25.845986Z","end":"2026-07-29T19:49:26.048362Z","steps":["trace[841062241] 'process raft request' (duration: 201.834328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:26.048487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.080414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-29T19:49:26.048521Z","caller":"traceutil/trace.go:171","msg":"trace[880052151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25283; }","duration":"193.131215ms","start":"2026-07-29T19:49:25.85538Z","end":"2026-07-29T19:49:26.048511Z","steps":["trace[880052151] 'agreement among raft nodes before linearized reading' (duration: 192.994862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:26.048549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.639978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-29T19:49:26.048589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.35195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-29T19:49:26.048641Z","caller":"traceutil/trace.go:171","msg":"trace[1305900142] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25283; }","duration":"193.440063ms","start":"2026-07-29T19:49:25.855188Z","end":"2026-07-29T19:49:26.048628Z","steps":["trace[1305900142] 'agreement among raft nodes before linearized reading' (duration: 193.286329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:26.048653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.379144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5180706d-0aaf-43aa-a8e9-da4c21afa629\" ","response":"range_response_count:1 size:3946"} {"level":"info","ts":"2026-07-29T19:49:26.048638Z","caller":"traceutil/trace.go:171","msg":"trace[1812621202] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25283; }","duration":"198.76417ms","start":"2026-07-29T19:49:25.849855Z","end":"2026-07-29T19:49:26.048619Z","steps":["trace[1812621202] 'agreement among raft nodes before linearized reading' (duration: 198.483724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:26.048661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.33502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-29T19:49:26.048685Z","caller":"traceutil/trace.go:171","msg":"trace[1963782216] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5180706d-0aaf-43aa-a8e9-da4c21afa629; range_end:; response_count:1; response_revision:25283; }","duration":"199.438435ms","start":"2026-07-29T19:49:25.849236Z","end":"2026-07-29T19:49:26.048675Z","steps":["trace[1963782216] 'agreement among raft nodes before linearized reading' (duration: 199.353493ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:26.048699Z","caller":"traceutil/trace.go:171","msg":"trace[481312284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25283; }","duration":"193.377971ms","start":"2026-07-29T19:49:25.85531Z","end":"2026-07-29T19:49:26.048688Z","steps":["trace[481312284] 'agreement among raft nodes before linearized reading' (duration: 193.299329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:26.048587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.301619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-29T19:49:26.048763Z","caller":"traceutil/trace.go:171","msg":"trace[31113096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25283; }","duration":"193.497253ms","start":"2026-07-29T19:49:25.855249Z","end":"2026-07-29T19:49:26.048746Z","steps":["trace[31113096] 'agreement among raft nodes before linearized reading' (duration: 193.219117ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:30.451588Z","caller":"traceutil/trace.go:171","msg":"trace[1500656920] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"129.641186ms","start":"2026-07-29T19:49:30.321915Z","end":"2026-07-29T19:49:30.451557Z","steps":["trace[1500656920] 'process raft request' (duration: 129.331699ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:33.163026Z","caller":"traceutil/trace.go:171","msg":"trace[1376736155] transaction","detail":"{read_only:false; response_revision:25360; number_of_response:1; }","duration":"127.191411ms","start":"2026-07-29T19:49:33.035808Z","end":"2026-07-29T19:49:33.162999Z","steps":["trace[1376736155] 'process raft request' (duration: 127.014267ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:34.093454Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000664d.wal"} {"level":"info","ts":"2026-07-29T19:49:34.321775Z","caller":"traceutil/trace.go:171","msg":"trace[1573970910] transaction","detail":"{read_only:false; response_revision:25367; number_of_response:1; }","duration":"163.586691ms","start":"2026-07-29T19:49:34.15816Z","end":"2026-07-29T19:49:34.321746Z","steps":["trace[1573970910] 'process raft request' (duration: 163.374136ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:34.328764Z","caller":"traceutil/trace.go:171","msg":"trace[968559600] linearizableReadLoop","detail":"{readStateIndex:26190; appliedIndex:26188; }","duration":"165.927181ms","start":"2026-07-29T19:49:34.162811Z","end":"2026-07-29T19:49:34.328738Z","steps":["trace[968559600] 'read index received' (duration: 158.712712ms)","trace[968559600] 'applied index is now lower than readState.Index' (duration: 7.213539ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:34.328848Z","caller":"traceutil/trace.go:171","msg":"trace[463086613] transaction","detail":"{read_only:false; response_revision:25368; number_of_response:1; }","duration":"170.100855ms","start":"2026-07-29T19:49:34.158706Z","end":"2026-07-29T19:49:34.328807Z","steps":["trace[463086613] 'process raft request' (duration: 169.89905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:34.329067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.165566ms","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-07-29T19:49:34.329081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.552433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-29T19:49:34.329078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.033846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-29T19:49:34.329128Z","caller":"traceutil/trace.go:171","msg":"trace[1118624514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25368; }","duration":"166.277179ms","start":"2026-07-29T19:49:34.162835Z","end":"2026-07-29T19:49:34.329112Z","steps":["trace[1118624514] 'agreement among raft nodes before linearized reading' (duration: 166.087245ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:34.329149Z","caller":"traceutil/trace.go:171","msg":"trace[163559749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25368; }","duration":"162.130908ms","start":"2026-07-29T19:49:34.167001Z","end":"2026-07-29T19:49:34.329132Z","steps":["trace[163559749] 'agreement among raft nodes before linearized reading' (duration: 161.938514ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:34.329131Z","caller":"traceutil/trace.go:171","msg":"trace[1221978146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25368; }","duration":"165.652755ms","start":"2026-07-29T19:49:34.163467Z","end":"2026-07-29T19:49:34.329119Z","steps":["trace[1221978146] 'agreement among raft nodes before linearized reading' (duration: 165.515882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:34.329192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.376951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-29T19:49:34.329329Z","caller":"traceutil/trace.go:171","msg":"trace[826876923] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25368; }","duration":"166.530865ms","start":"2026-07-29T19:49:34.162783Z","end":"2026-07-29T19:49:34.329314Z","steps":["trace[826876923] 'agreement among raft nodes before linearized reading' (duration: 166.078725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:34.329082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.090505ms","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-07-29T19:49:34.329469Z","caller":"traceutil/trace.go:171","msg":"trace[753061693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25368; }","duration":"166.509555ms","start":"2026-07-29T19:49:34.162939Z","end":"2026-07-29T19:49:34.329449Z","steps":["trace[753061693] 'agreement among raft nodes before linearized reading' (duration: 166.061675ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:36.513283Z","caller":"traceutil/trace.go:171","msg":"trace[1477401205] linearizableReadLoop","detail":"{readStateIndex:26206; appliedIndex:26204; }","duration":"173.923799ms","start":"2026-07-29T19:49:36.339336Z","end":"2026-07-29T19:49:36.513259Z","steps":["trace[1477401205] 'read index received' (duration: 5.337689ms)","trace[1477401205] 'applied index is now lower than readState.Index' (duration: 168.58516ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:36.513674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.03737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-29T19:49:36.513707Z","caller":"traceutil/trace.go:171","msg":"trace[452543312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25383; }","duration":"173.094941ms","start":"2026-07-29T19:49:36.340604Z","end":"2026-07-29T19:49:36.513699Z","steps":["trace[452543312] 'agreement among raft nodes before linearized reading' (duration: 173.002639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:36.513674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.325978ms","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-07-29T19:49:36.513799Z","caller":"traceutil/trace.go:171","msg":"trace[1934960314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25383; }","duration":"174.482821ms","start":"2026-07-29T19:49:36.339304Z","end":"2026-07-29T19:49:36.513787Z","steps":["trace[1934960314] 'agreement among raft nodes before linearized reading' (duration: 174.259206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:36.513818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.465686ms","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-07-29T19:49:36.514841Z","caller":"traceutil/trace.go:171","msg":"trace[395983257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25383; }","duration":"173.497629ms","start":"2026-07-29T19:49:36.341321Z","end":"2026-07-29T19:49:36.514818Z","steps":["trace[395983257] 'agreement among raft nodes before linearized reading' (duration: 172.451216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:36.513821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.355234ms","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-07-29T19:49:36.514993Z","caller":"traceutil/trace.go:171","msg":"trace[408891556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25383; }","duration":"173.573352ms","start":"2026-07-29T19:49:36.341394Z","end":"2026-07-29T19:49:36.514967Z","steps":["trace[408891556] 'agreement among raft nodes before linearized reading' (duration: 172.256592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:36.513869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.606839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-29T19:49:36.515227Z","caller":"traceutil/trace.go:171","msg":"trace[284954967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25383; }","duration":"173.94519ms","start":"2026-07-29T19:49:36.34125Z","end":"2026-07-29T19:49:36.515195Z","steps":["trace[284954967] 'agreement among raft nodes before linearized reading' (duration: 172.553388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:36.513899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.808873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:36.51533Z","caller":"traceutil/trace.go:171","msg":"trace[1158860118] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:25383; }","duration":"105.311957ms","start":"2026-07-29T19:49:36.409998Z","end":"2026-07-29T19:49:36.51531Z","steps":["trace[1158860118] 'agreement among raft nodes before linearized reading' (duration: 103.788263ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:36.513571Z","caller":"traceutil/trace.go:171","msg":"trace[1480861619] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"177.699982ms","start":"2026-07-29T19:49:36.33583Z","end":"2026-07-29T19:49:36.51353Z","steps":["trace[1480861619] 'process raft request' (duration: 177.271523ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:42.207214Z","caller":"traceutil/trace.go:171","msg":"trace[958082959] transaction","detail":"{read_only:false; response_revision:25425; number_of_response:1; }","duration":"107.649109ms","start":"2026-07-29T19:49:42.099544Z","end":"2026-07-29T19:49:42.207194Z","steps":["trace[958082959] 'process raft request' (duration: 107.478314ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:42.234074Z","caller":"traceutil/trace.go:171","msg":"trace[587516391] transaction","detail":"{read_only:false; response_revision:25426; number_of_response:1; }","duration":"119.266636ms","start":"2026-07-29T19:49:42.114777Z","end":"2026-07-29T19:49:42.234044Z","steps":["trace[587516391] 'process raft request' (duration: 119.086902ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:44.698478Z","caller":"traceutil/trace.go:171","msg":"trace[649012378] linearizableReadLoop","detail":"{readStateIndex:26268; appliedIndex:26267; }","duration":"135.439955ms","start":"2026-07-29T19:49:44.563012Z","end":"2026-07-29T19:49:44.698452Z","steps":["trace[649012378] 'read index received' (duration: 40.964009ms)","trace[649012378] 'applied index is now lower than readState.Index' (duration: 94.474846ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:44.698493Z","caller":"traceutil/trace.go:171","msg":"trace[788373728] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"137.819648ms","start":"2026-07-29T19:49:44.560643Z","end":"2026-07-29T19:49:44.698463Z","steps":["trace[788373728] 'process raft request' (duration: 43.202738ms)","trace[788373728] 'compare' (duration: 94.423175ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:44.6988Z","caller":"traceutil/trace.go:171","msg":"trace[189689604] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"111.822971ms","start":"2026-07-29T19:49:44.586953Z","end":"2026-07-29T19:49:44.698776Z","steps":["trace[189689604] 'process raft request' (duration: 111.460243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:44.698917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.888625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-29T19:49:44.698952Z","caller":"traceutil/trace.go:171","msg":"trace[970621384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25445; }","duration":"135.954687ms","start":"2026-07-29T19:49:44.562988Z","end":"2026-07-29T19:49:44.698943Z","steps":["trace[970621384] 'agreement among raft nodes before linearized reading' (duration: 135.61539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:44.699022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.413441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-29T19:49:44.699079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.088794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-29T19:49:44.699124Z","caller":"traceutil/trace.go:171","msg":"trace[692690964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25445; }","duration":"129.537854ms","start":"2026-07-29T19:49:44.569561Z","end":"2026-07-29T19:49:44.699098Z","steps":["trace[692690964] 'agreement among raft nodes before linearized reading' (duration: 129.241367ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:44.699144Z","caller":"traceutil/trace.go:171","msg":"trace[1965586273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25445; }","duration":"129.171726ms","start":"2026-07-29T19:49:44.569952Z","end":"2026-07-29T19:49:44.699124Z","steps":["trace[1965586273] 'agreement among raft nodes before linearized reading' (duration: 129.008973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:44.699079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.103279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-29T19:49:44.699301Z","caller":"traceutil/trace.go:171","msg":"trace[465457979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25445; }","duration":"131.325834ms","start":"2026-07-29T19:49:44.567942Z","end":"2026-07-29T19:49:44.699268Z","steps":["trace[465457979] 'agreement among raft nodes before linearized reading' (duration: 130.828103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:44.699623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.252428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-29T19:49:44.699752Z","caller":"traceutil/trace.go:171","msg":"trace[207225989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25445; }","duration":"130.123746ms","start":"2026-07-29T19:49:44.569607Z","end":"2026-07-29T19:49:44.69973Z","steps":["trace[207225989] 'agreement among raft nodes before linearized reading' (duration: 129.180666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:52.470798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.920126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:49:52.470895Z","caller":"traceutil/trace.go:171","msg":"trace[1721815410] linearizableReadLoop","detail":"{readStateIndex:26340; appliedIndex:26339; }","duration":"179.583385ms","start":"2026-07-29T19:49:52.291296Z","end":"2026-07-29T19:49:52.470879Z","steps":["trace[1721815410] 'read index received' (duration: 43.436854ms)","trace[1721815410] 'applied index is now lower than readState.Index' (duration: 136.145491ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:52.471077Z","caller":"traceutil/trace.go:171","msg":"trace[1737428407] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"203.237579ms","start":"2026-07-29T19:49:52.267804Z","end":"2026-07-29T19:49:52.471042Z","steps":["trace[1737428407] 'process raft request' (duration: 66.989977ms)","trace[1737428407] 'compare' (duration: 135.810443ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:49:52.471109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.81592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-29T19:49:52.4713Z","caller":"traceutil/trace.go:171","msg":"trace[1826936878] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25514; }","duration":"180.018334ms","start":"2026-07-29T19:49:52.291244Z","end":"2026-07-29T19:49:52.471262Z","steps":["trace[1826936878] 'agreement among raft nodes before linearized reading' (duration: 179.670146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:52.746464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.522695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-29T19:49:52.746531Z","caller":"traceutil/trace.go:171","msg":"trace[191628319] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:25515; }","duration":"175.645878ms","start":"2026-07-29T19:49:52.570869Z","end":"2026-07-29T19:49:52.746515Z","steps":["trace[191628319] 'count revisions from in-memory index tree' (duration: 175.443363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:52.747001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.084692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-29T19:49:52.747068Z","caller":"traceutil/trace.go:171","msg":"trace[1594815338] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25515; }","duration":"151.203105ms","start":"2026-07-29T19:49:52.595851Z","end":"2026-07-29T19:49:52.747054Z","steps":["trace[1594815338] 'count revisions from in-memory index tree' (duration: 150.98512ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:52.92911Z","caller":"traceutil/trace.go:171","msg":"trace[1398442099] linearizableReadLoop","detail":"{readStateIndex:26342; appliedIndex:26341; }","duration":"156.575844ms","start":"2026-07-29T19:49:52.772513Z","end":"2026-07-29T19:49:52.929089Z","steps":["trace[1398442099] 'read index received' (duration: 156.348419ms)","trace[1398442099] 'applied index is now lower than readState.Index' (duration: 226.365µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:52.929222Z","caller":"traceutil/trace.go:171","msg":"trace[762730029] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"207.385092ms","start":"2026-07-29T19:49:52.721801Z","end":"2026-07-29T19:49:52.929186Z","steps":["trace[762730029] 'process raft request' (duration: 207.150547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:52.929339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.351735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-29T19:49:52.929374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.031155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-29T19:49:52.929384Z","caller":"traceutil/trace.go:171","msg":"trace[1265773135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25516; }","duration":"154.459817ms","start":"2026-07-29T19:49:52.774912Z","end":"2026-07-29T19:49:52.929372Z","steps":["trace[1265773135] 'agreement among raft nodes before linearized reading' (duration: 154.293003ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:52.929417Z","caller":"traceutil/trace.go:171","msg":"trace[1786033433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25516; }","duration":"148.112597ms","start":"2026-07-29T19:49:52.781293Z","end":"2026-07-29T19:49:52.929405Z","steps":["trace[1786033433] 'agreement among raft nodes before linearized reading' (duration: 147.920823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:52.9294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.930464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-29T19:49:52.929474Z","caller":"traceutil/trace.go:171","msg":"trace[745473219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25516; }","duration":"153.060997ms","start":"2026-07-29T19:49:52.776393Z","end":"2026-07-29T19:49:52.929454Z","steps":["trace[745473219] 'agreement among raft nodes before linearized reading' (duration: 152.881983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:52.929462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.466427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-29T19:49:52.929472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.296728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-29T19:49:52.929399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.862461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-29T19:49:52.929537Z","caller":"traceutil/trace.go:171","msg":"trace[1687117772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25516; }","duration":"154.570039ms","start":"2026-07-29T19:49:52.774952Z","end":"2026-07-29T19:49:52.929522Z","steps":["trace[1687117772] 'agreement among raft nodes before linearized reading' (duration: 154.356085ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:52.929544Z","caller":"traceutil/trace.go:171","msg":"trace[937991166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25516; }","duration":"142.425521ms","start":"2026-07-29T19:49:52.787105Z","end":"2026-07-29T19:49:52.92953Z","steps":["trace[937991166] 'agreement among raft nodes before linearized reading' (duration: 142.270917ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:52.929566Z","caller":"traceutil/trace.go:171","msg":"trace[873511823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25516; }","duration":"157.074255ms","start":"2026-07-29T19:49:52.772481Z","end":"2026-07-29T19:49:52.929555Z","steps":["trace[873511823] 'agreement among raft nodes before linearized reading' (duration: 156.719197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:53.168345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.815852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:49:53.168574Z","caller":"traceutil/trace.go:171","msg":"trace[1387966553] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"235.782882ms","start":"2026-07-29T19:49:52.93276Z","end":"2026-07-29T19:49:53.168543Z","steps":["trace[1387966553] 'process raft request' (duration: 86.670213ms)","trace[1387966553] 'compare' (duration: 148.663269ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:53.1687Z","caller":"traceutil/trace.go:171","msg":"trace[360609562] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"235.669059ms","start":"2026-07-29T19:49:52.933005Z","end":"2026-07-29T19:49:53.168674Z","steps":["trace[360609562] 'process raft request' (duration: 235.460225ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:53.169256Z","caller":"traceutil/trace.go:171","msg":"trace[932064145] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"235.920156ms","start":"2026-07-29T19:49:52.933316Z","end":"2026-07-29T19:49:53.169236Z","steps":["trace[932064145] 'process raft request' (duration: 235.298771ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:53.169542Z","caller":"traceutil/trace.go:171","msg":"trace[1439325253] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"235.097406ms","start":"2026-07-29T19:49:52.934427Z","end":"2026-07-29T19:49:53.169524Z","steps":["trace[1439325253] 'process raft request' (duration: 234.718648ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:53.169666Z","caller":"traceutil/trace.go:171","msg":"trace[1324411260] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"234.951033ms","start":"2026-07-29T19:49:52.93458Z","end":"2026-07-29T19:49:53.169531Z","steps":["trace[1324411260] 'process raft request' (duration: 234.80296ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:59.323073Z","caller":"traceutil/trace.go:171","msg":"trace[52530788] transaction","detail":"{read_only:false; response_revision:25563; number_of_response:1; }","duration":"125.988286ms","start":"2026-07-29T19:49:59.197052Z","end":"2026-07-29T19:49:59.32304Z","steps":["trace[52530788] 'process raft request' (duration: 125.792302ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:59.550824Z","caller":"traceutil/trace.go:171","msg":"trace[26080912] transaction","detail":"{read_only:false; response_revision:25564; number_of_response:1; }","duration":"352.45911ms","start":"2026-07-29T19:49:59.198337Z","end":"2026-07-29T19:49:59.550796Z","steps":["trace[26080912] 'process raft request' (duration: 352.259076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:59.550981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:59.198313Z","time spent":"352.588203ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:59.551124Z","caller":"traceutil/trace.go:171","msg":"trace[902614755] transaction","detail":"{read_only:false; response_revision:25565; number_of_response:1; }","duration":"352.477941ms","start":"2026-07-29T19:49:59.198603Z","end":"2026-07-29T19:49:59.551081Z","steps":["trace[902614755] 'process raft request' (duration: 352.151394ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:59.551195Z","caller":"traceutil/trace.go:171","msg":"trace[764453655] linearizableReadLoop","detail":"{readStateIndex:26394; appliedIndex:26389; }","duration":"310.06308ms","start":"2026-07-29T19:49:59.241103Z","end":"2026-07-29T19:49:59.551166Z","steps":["trace[764453655] 'read index received' (duration: 81.802355ms)","trace[764453655] 'applied index is now lower than readState.Index' (duration: 228.259635ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:59.551299Z","caller":"traceutil/trace.go:171","msg":"trace[2057581520] transaction","detail":"{read_only:false; response_revision:25566; number_of_response:1; }","duration":"352.630044ms","start":"2026-07-29T19:49:59.198658Z","end":"2026-07-29T19:49:59.551288Z","steps":["trace[2057581520] 'process raft request' (duration: 352.327217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:59.551311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:59.198578Z","time spent":"352.642324ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T19:49:59.551366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:59.198647Z","time spent":"352.686026ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-29T19:49:59.551358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.231304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:49:59.551461Z","caller":"traceutil/trace.go:171","msg":"trace[451132959] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25567; }","duration":"310.369407ms","start":"2026-07-29T19:49:59.241053Z","end":"2026-07-29T19:49:59.551422Z","steps":["trace[451132959] 'agreement among raft nodes before linearized reading' (duration: 310.231534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:59.551532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:59.24104Z","time spent":"310.472369ms","remote":"127.0.0.1:43184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"info","ts":"2026-07-29T19:49:59.551886Z","caller":"traceutil/trace.go:171","msg":"trace[78638404] transaction","detail":"{read_only:false; response_revision:25567; number_of_response:1; }","duration":"352.603313ms","start":"2026-07-29T19:49:59.198791Z","end":"2026-07-29T19:49:59.551395Z","steps":["trace[78638404] 'process raft request' (duration: 352.308477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:49:59.552092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-29T19:49:59.198767Z","time spent":"353.210237ms","remote":"127.0.0.1:48830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-29T19:49:59.778003Z","caller":"traceutil/trace.go:171","msg":"trace[442870681] transaction","detail":"{read_only:false; response_revision:25569; number_of_response:1; }","duration":"116.947445ms","start":"2026-07-29T19:49:59.661026Z","end":"2026-07-29T19:49:59.777973Z","steps":["trace[442870681] 'process raft request' (duration: 56.79381ms)","trace[442870681] 'compare' (duration: 59.994591ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:49:59.79382Z","caller":"traceutil/trace.go:171","msg":"trace[153318186] transaction","detail":"{read_only:false; response_revision:25570; number_of_response:1; }","duration":"103.155058ms","start":"2026-07-29T19:49:59.690601Z","end":"2026-07-29T19:49:59.793756Z","steps":["trace[153318186] 'process raft request' (duration: 102.886152ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:49:59.794529Z","caller":"traceutil/trace.go:171","msg":"trace[1298349485] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"103.290802ms","start":"2026-07-29T19:49:59.691182Z","end":"2026-07-29T19:49:59.794473Z","steps":["trace[1298349485] 'process raft request' (duration: 102.453722ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:00.781692Z","caller":"traceutil/trace.go:171","msg":"trace[997419213] linearizableReadLoop","detail":"{readStateIndex:26412; appliedIndex:26411; }","duration":"150.201764ms","start":"2026-07-29T19:50:00.631461Z","end":"2026-07-29T19:50:00.781662Z","steps":["trace[997419213] 'read index received' (duration: 106.192337ms)","trace[997419213] 'applied index is now lower than readState.Index' (duration: 44.008307ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:50:00.782015Z","caller":"traceutil/trace.go:171","msg":"trace[760068185] transaction","detail":"{read_only:false; response_revision:25585; number_of_response:1; }","duration":"168.858077ms","start":"2026-07-29T19:50:00.613122Z","end":"2026-07-29T19:50:00.78198Z","steps":["trace[760068185] 'process raft request' (duration: 124.601374ms)","trace[760068185] 'compare' (duration: 43.212ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:50:00.782232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.751666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:50:00.782761Z","caller":"traceutil/trace.go:171","msg":"trace[949402223] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:25585; }","duration":"151.327348ms","start":"2026-07-29T19:50:00.631417Z","end":"2026-07-29T19:50:00.782744Z","steps":["trace[949402223] 'agreement among raft nodes before linearized reading' (duration: 150.346737ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:00.951844Z","caller":"traceutil/trace.go:171","msg":"trace[680488049] linearizableReadLoop","detail":"{readStateIndex:26413; appliedIndex:26412; }","duration":"162.332682ms","start":"2026-07-29T19:50:00.789483Z","end":"2026-07-29T19:50:00.951816Z","steps":["trace[680488049] 'read index received' (duration: 74.983484ms)","trace[680488049] 'applied index is now lower than readState.Index' (duration: 87.347728ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:50:00.951975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.469705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:50:00.952005Z","caller":"traceutil/trace.go:171","msg":"trace[1493388834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25585; }","duration":"162.556007ms","start":"2026-07-29T19:50:00.78944Z","end":"2026-07-29T19:50:00.951996Z","steps":["trace[1493388834] 'agreement among raft nodes before linearized reading' (duration: 162.475995ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:01.051027Z","caller":"traceutil/trace.go:171","msg":"trace[448764794] transaction","detail":"{read_only:false; response_revision:25586; number_of_response:1; }","duration":"252.301438ms","start":"2026-07-29T19:50:00.798709Z","end":"2026-07-29T19:50:01.051011Z","steps":["trace[448764794] 'process raft request' (duration: 252.178145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:50:01.19936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.138862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-29T19:50:01.199494Z","caller":"traceutil/trace.go:171","msg":"trace[847368574] transaction","detail":"{read_only:false; response_revision:25587; number_of_response:1; }","duration":"246.713264ms","start":"2026-07-29T19:50:00.952757Z","end":"2026-07-29T19:50:01.19947Z","steps":["trace[847368574] 'process raft request' (duration: 146.126522ms)","trace[847368574] 'compare' (duration: 100.009669ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:50:01.199622Z","caller":"traceutil/trace.go:171","msg":"trace[180748159] linearizableReadLoop","detail":"{readStateIndex:26415; appliedIndex:26414; }","duration":"245.807354ms","start":"2026-07-29T19:50:00.953789Z","end":"2026-07-29T19:50:01.199596Z","steps":["trace[180748159] 'read index received' (duration: 97.415391ms)","trace[180748159] 'applied index is now lower than readState.Index' (duration: 148.388543ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:50:01.199789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.979828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:50:01.199829Z","caller":"traceutil/trace.go:171","msg":"trace[1283470790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25587; }","duration":"246.066339ms","start":"2026-07-29T19:50:00.953753Z","end":"2026-07-29T19:50:01.199819Z","steps":["trace[1283470790] 'agreement among raft nodes before linearized reading' (duration: 245.968857ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:01.210756Z","caller":"traceutil/trace.go:171","msg":"trace[631457718] transaction","detail":"{read_only:false; response_revision:25588; number_of_response:1; }","duration":"129.575935ms","start":"2026-07-29T19:50:01.081153Z","end":"2026-07-29T19:50:01.210729Z","steps":["trace[631457718] 'process raft request' (duration: 129.207816ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:03.532334Z","caller":"traceutil/trace.go:171","msg":"trace[150836493] transaction","detail":"{read_only:false; response_revision:25607; number_of_response:1; }","duration":"106.847601ms","start":"2026-07-29T19:50:03.425456Z","end":"2026-07-29T19:50:03.532303Z","steps":["trace[150836493] 'process raft request' (duration: 106.687367ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:03.683735Z","caller":"traceutil/trace.go:171","msg":"trace[826370431] linearizableReadLoop","detail":"{readStateIndex:26436; appliedIndex:26435; }","duration":"112.523226ms","start":"2026-07-29T19:50:03.571179Z","end":"2026-07-29T19:50:03.683702Z","steps":["trace[826370431] 'read index received' (duration: 49.199901ms)","trace[826370431] 'applied index is now lower than readState.Index' (duration: 63.321015ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:50:03.683882Z","caller":"traceutil/trace.go:171","msg":"trace[650144819] transaction","detail":"{read_only:false; response_revision:25608; number_of_response:1; }","duration":"112.726781ms","start":"2026-07-29T19:50:03.571114Z","end":"2026-07-29T19:50:03.683841Z","steps":["trace[650144819] 'process raft request' (duration: 49.248652ms)","trace[650144819] 'compare' (duration: 63.07317ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:50:03.684108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.890114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-29T19:50:03.684213Z","caller":"traceutil/trace.go:171","msg":"trace[1247494942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25608; }","duration":"113.050408ms","start":"2026-07-29T19:50:03.571134Z","end":"2026-07-29T19:50:03.684184Z","steps":["trace[1247494942] 'agreement among raft nodes before linearized reading' (duration: 112.779482ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:03.684529Z","caller":"traceutil/trace.go:171","msg":"trace[361987506] transaction","detail":"{read_only:false; response_revision:25610; number_of_response:1; }","duration":"106.760099ms","start":"2026-07-29T19:50:03.577755Z","end":"2026-07-29T19:50:03.684515Z","steps":["trace[361987506] 'process raft request' (duration: 106.632756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:50:03.684605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.15475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-29T19:50:03.684633Z","caller":"traceutil/trace.go:171","msg":"trace[270011788] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"109.148801ms","start":"2026-07-29T19:50:03.575476Z","end":"2026-07-29T19:50:03.684625Z","steps":["trace[270011788] 'process raft request' (duration: 108.789474ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:03.684668Z","caller":"traceutil/trace.go:171","msg":"trace[442369842] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25611; }","duration":"113.242012ms","start":"2026-07-29T19:50:03.571407Z","end":"2026-07-29T19:50:03.684649Z","steps":["trace[442369842] 'agreement among raft nodes before linearized reading' (duration: 113.062558ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:03.68476Z","caller":"traceutil/trace.go:171","msg":"trace[1007374468] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"106.984203ms","start":"2026-07-29T19:50:03.577767Z","end":"2026-07-29T19:50:03.684752Z","steps":["trace[1007374468] 'process raft request' (duration: 106.663836ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:05.877808Z","caller":"traceutil/trace.go:171","msg":"trace[1969491445] linearizableReadLoop","detail":"{readStateIndex:26460; appliedIndex:26459; }","duration":"157.349151ms","start":"2026-07-29T19:50:05.720429Z","end":"2026-07-29T19:50:05.877778Z","steps":["trace[1969491445] 'read index received' (duration: 157.237449ms)","trace[1969491445] 'applied index is now lower than readState.Index' (duration: 110.462µs)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:50:05.877978Z","caller":"traceutil/trace.go:171","msg":"trace[496486428] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"162.865284ms","start":"2026-07-29T19:50:05.715029Z","end":"2026-07-29T19:50:05.877894Z","steps":["trace[496486428] 'process raft request' (duration: 162.593398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-29T19:50:05.878187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.661949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-29T19:50:05.878269Z","caller":"traceutil/trace.go:171","msg":"trace[801527842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25631; }","duration":"157.863352ms","start":"2026-07-29T19:50:05.720386Z","end":"2026-07-29T19:50:05.87825Z","steps":["trace[801527842] 'agreement among raft nodes before linearized reading' (duration: 157.520155ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:06.128447Z","caller":"traceutil/trace.go:171","msg":"trace[779129022] linearizableReadLoop","detail":"{readStateIndex:26465; appliedIndex:26464; }","duration":"168.233793ms","start":"2026-07-29T19:50:05.960188Z","end":"2026-07-29T19:50:06.128422Z","steps":["trace[779129022] 'read index received' (duration: 112.890575ms)","trace[779129022] 'applied index is now lower than readState.Index' (duration: 55.342218ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:50:06.128512Z","caller":"traceutil/trace.go:171","msg":"trace[2092600561] transaction","detail":"{read_only:false; response_revision:25636; number_of_response:1; }","duration":"245.648411ms","start":"2026-07-29T19:50:05.88282Z","end":"2026-07-29T19:50:06.128469Z","steps":["trace[2092600561] 'process raft request' (duration: 190.314303ms)","trace[2092600561] 'compare' (duration: 55.112753ms)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:50:06.128677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.432587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:50:06.128802Z","caller":"traceutil/trace.go:171","msg":"trace[436435101] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:25636; }","duration":"168.58403ms","start":"2026-07-29T19:50:05.960171Z","end":"2026-07-29T19:50:06.128755Z","steps":["trace[436435101] 'agreement among raft nodes before linearized reading' (duration: 168.327095ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:08.280983Z","caller":"traceutil/trace.go:171","msg":"trace[1790155188] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"144.108488ms","start":"2026-07-29T19:50:08.136847Z","end":"2026-07-29T19:50:08.280955Z","steps":["trace[1790155188] 'process raft request' (duration: 143.980125ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:08.644115Z","caller":"traceutil/trace.go:171","msg":"trace[1707901699] linearizableReadLoop","detail":"{readStateIndex:26480; appliedIndex:26479; }","duration":"180.601667ms","start":"2026-07-29T19:50:08.463489Z","end":"2026-07-29T19:50:08.644091Z","steps":["trace[1707901699] 'read index received' (duration: 180.519306ms)","trace[1707901699] 'applied index is now lower than readState.Index' (duration: 81.491µs)"],"step_count":2} {"level":"warn","ts":"2026-07-29T19:50:08.644251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.73635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-29T19:50:08.644294Z","caller":"traceutil/trace.go:171","msg":"trace[1064029302] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25651; }","duration":"180.825193ms","start":"2026-07-29T19:50:08.463458Z","end":"2026-07-29T19:50:08.644283Z","steps":["trace[1064029302] 'agreement among raft nodes before linearized reading' (duration: 180.7244ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:08.644287Z","caller":"traceutil/trace.go:171","msg":"trace[210846676] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"276.406783ms","start":"2026-07-29T19:50:08.367853Z","end":"2026-07-29T19:50:08.64426Z","steps":["trace[210846676] 'process raft request' (duration: 276.106847ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:08.651145Z","caller":"traceutil/trace.go:171","msg":"trace[1884914714] transaction","detail":"{read_only:false; response_revision:25652; number_of_response:1; }","duration":"103.69046ms","start":"2026-07-29T19:50:08.547439Z","end":"2026-07-29T19:50:08.651129Z","steps":["trace[1884914714] 'process raft request' (duration: 103.358103ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:50:22.45952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22894} {"level":"info","ts":"2026-07-29T19:50:22.611636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22894,"took":"147.948323ms","hash":4095303284,"current-db-size-bytes":58916864,"current-db-size":"59 MB","current-db-size-in-use-bytes":36392960,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-29T19:50:22.611717Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4095303284,"revision":22894,"compact-revision":20066} {"level":"info","ts":"2026-07-29T19:51:20.178818Z","caller":"traceutil/trace.go:171","msg":"trace[873243138] transaction","detail":"{read_only:false; response_revision:26375; number_of_response:1; }","duration":"125.536046ms","start":"2026-07-29T19:51:20.05326Z","end":"2026-07-29T19:51:20.178797Z","steps":["trace[873243138] 'process raft request' (duration: 105.666525ms)","trace[873243138] 'compare' (duration: 19.773348ms)"],"step_count":2} {"level":"info","ts":"2026-07-29T19:51:20.188148Z","caller":"traceutil/trace.go:171","msg":"trace[322022627] transaction","detail":"{read_only:false; response_revision:26376; number_of_response:1; }","duration":"134.849072ms","start":"2026-07-29T19:51:20.05328Z","end":"2026-07-29T19:51:20.188129Z","steps":["trace[322022627] 'process raft request' (duration: 134.439772ms)"],"step_count":1} {"level":"info","ts":"2026-07-29T19:55:22.467134Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25775} {"level":"info","ts":"2026-07-29T19:55:22.606885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25775,"took":"133.402927ms","hash":3636355633,"current-db-size-bytes":59760640,"current-db-size":"60 MB","current-db-size-in-use-bytes":37953536,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-29T19:55:22.606952Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3636355633,"revision":25775,"compact-revision":22894} {"level":"info","ts":"2026-07-29T19:56:21.673682Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"790561176553eafe","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-29T19:56:21.682526Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-07-29T19:56:21.682675Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-07-29T20:00:22.476657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28649} {"level":"info","ts":"2026-07-29T20:00:22.63786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28649,"took":"156.747001ms","hash":1656700901,"current-db-size-bytes":59760640,"current-db-size":"60 MB","current-db-size-in-use-bytes":30867456,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-29T20:00:22.637933Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1656700901,"revision":28649,"compact-revision":25775} {"level":"info","ts":"2026-07-29T20:05:22.484255Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30923} {"level":"info","ts":"2026-07-29T20:05:22.59869Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30923,"took":"110.707364ms","hash":575236670,"current-db-size-bytes":59760640,"current-db-size":"60 MB","current-db-size-in-use-bytes":31961088,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-29T20:05:22.598744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":575236670,"revision":30923,"compact-revision":28649} {"level":"info","ts":"2026-07-29T20:10:22.490427Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33231} {"level":"info","ts":"2026-07-29T20:10:22.668299Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33231,"took":"173.362247ms","hash":725437355,"current-db-size-bytes":59760640,"current-db-size":"60 MB","current-db-size-in-use-bytes":31997952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-29T20:10:22.668389Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":725437355,"revision":33231,"compact-revision":30923} {"level":"info","ts":"2026-07-29T20:15:22.497519Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35526} {"level":"info","ts":"2026-07-29T20:15:22.662094Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35526,"took":"160.245123ms","hash":1890479130,"current-db-size-bytes":59760640,"current-db-size":"60 MB","current-db-size-in-use-bytes":31432704,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-29T20:15:22.662161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1890479130,"revision":35526,"compact-revision":33231} {"level":"info","ts":"2026-07-29T20:17:40.749143Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"790561176553eafe","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-29T20:17:40.754825Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-07-29T20:17:40.755003Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-07-29T20:20:22.504267Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37753} {"level":"info","ts":"2026-07-29T20:20:22.624541Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37753,"took":"116.605295ms","hash":2274875996,"current-db-size-bytes":60604416,"current-db-size":"61 MB","current-db-size-in-use-bytes":32301056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-29T20:20:22.624592Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2274875996,"revision":37753,"compact-revision":35526}