{"level":"warn","ts":"2026-07-01T07:19:08.702838Z","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-01T07:19:08.702925Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-01T07:19:08.703017Z","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-01T07:19:08.703026Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-01T07:19:08.703054Z","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-01T07:19:08.703703Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-07-01T07:19:08.70378Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-01T07:19:08.70845Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.459385ms"} {"level":"info","ts":"2026-07-01T07:19:08.714466Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-07-01T07:19:08.714534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T07:19:08.714553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-07-01T07:19:08.714567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T07:19:08.714584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-07-01T07:19:08.714615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-07-01T07:19:08.722676Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T07:19:08.724354Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T07:19:08.726837Z","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-01T07:19:08.728587Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T07:19:08.728724Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T07:19:08.728847Z","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-01T07:19:08.728916Z","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-01T07:19:08.728928Z","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-01T07:19:08.730363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-07-01T07:19:08.730495Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-01T07:19:08.736386Z","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-01T07:19:08.736654Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T07:19:08.736683Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T07:19:08.73651Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-01T07:19:08.737218Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-01T07:19:08.915027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T07:19:08.915085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T07:19:08.915102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-07-01T07:19:08.915116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-07-01T07:19:08.915125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-01T07:19:08.915136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-07-01T07:19:08.915151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-01T07:19:08.917104Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T07:19:08.917234Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:19:08.917402Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:19:08.91756Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:19:08.91786Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T07:19:08.917921Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T07:19:08.919518Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-07-01T07:19:08.920736Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:19:08.920834Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:19:08.92087Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:19:08.921463Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T07:19:34.954222Z","caller":"traceutil/trace.go:171","msg":"trace[642319632] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"105.256708ms","start":"2026-07-01T07:19:34.848938Z","end":"2026-07-01T07:19:34.954195Z","steps":["trace[642319632] 'process raft request' (duration: 48.040368ms)","trace[642319632] 'compare' (duration: 57.108599ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:19:35.626503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.944416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:19:35.626999Z","caller":"traceutil/trace.go:171","msg":"trace[1631139230] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"660.963929ms","start":"2026-07-01T07:19:34.966Z","end":"2026-07-01T07:19:35.626964Z","steps":["trace[1631139230] 'process raft request' (duration: 448.048657ms)","trace[1631139230] 'compare' (duration: 209.780414ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:19:35.627103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:19:34.965977Z","time spent":"661.07199ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:19:52.743464Z","caller":"traceutil/trace.go:171","msg":"trace[132799949] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"118.742832ms","start":"2026-07-01T07:19:52.624697Z","end":"2026-07-01T07:19:52.74344Z","steps":["trace[132799949] 'process raft request' (duration: 118.54665ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:19:52.751455Z","caller":"traceutil/trace.go:171","msg":"trace[1124436856] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"123.466672ms","start":"2026-07-01T07:19:52.627958Z","end":"2026-07-01T07:19:52.751425Z","steps":["trace[1124436856] 'process raft request' (duration: 123.079908ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:19:52.753362Z","caller":"traceutil/trace.go:171","msg":"trace[923335389] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"119.654093ms","start":"2026-07-01T07:19:52.633679Z","end":"2026-07-01T07:19:52.753333Z","steps":["trace[923335389] 'process raft request' (duration: 117.611811ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:19:52.761059Z","caller":"traceutil/trace.go:171","msg":"trace[1187817677] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"104.393191ms","start":"2026-07-01T07:19:52.656645Z","end":"2026-07-01T07:19:52.761038Z","steps":["trace[1187817677] 'process raft request' (duration: 101.205987ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:19:53.013059Z","caller":"traceutil/trace.go:171","msg":"trace[1589698877] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"120.380969ms","start":"2026-07-01T07:19:52.892659Z","end":"2026-07-01T07:19:53.01304Z","steps":["trace[1589698877] 'process raft request' (duration: 115.966292ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:19:53.013357Z","caller":"traceutil/trace.go:171","msg":"trace[72820790] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"109.231851ms","start":"2026-07-01T07:19:52.904113Z","end":"2026-07-01T07:19:53.013345Z","steps":["trace[72820790] 'process raft request' (duration: 108.990649ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:19:58.474792Z","caller":"traceutil/trace.go:171","msg":"trace[1674494255] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"113.683488ms","start":"2026-07-01T07:19:58.361073Z","end":"2026-07-01T07:19:58.474757Z","steps":["trace[1674494255] 'process raft request' (duration: 113.460896ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:19:58.600195Z","caller":"traceutil/trace.go:171","msg":"trace[335791627] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"113.098842ms","start":"2026-07-01T07:19:58.487069Z","end":"2026-07-01T07:19:58.600168Z","steps":["trace[335791627] 'process raft request' (duration: 112.557526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:00.130382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151820435s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:20:00.130521Z","caller":"traceutil/trace.go:171","msg":"trace[1248224852] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:675; }","duration":"1.079413714s","start":"2026-07-01T07:19:59.051095Z","end":"2026-07-01T07:20:00.130509Z","steps":["trace[1248224852] 'read index received' (duration: 55.541µs)","trace[1248224852] 'applied index is now lower than readState.Index' (duration: 1.079357513s)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:20:00.130592Z","caller":"traceutil/trace.go:171","msg":"trace[796917076] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.49781725s","start":"2026-07-01T07:19:58.632768Z","end":"2026-07-01T07:20:00.130585Z","steps":["trace[796917076] 'process raft request' (duration: 345.731752ms)","trace[796917076] 'compare' (duration: 1.151696964s)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:20:00.13063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:19:58.632691Z","time spent":"1.497918901s","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:20:00.130776Z","caller":"traceutil/trace.go:171","msg":"trace[1404671051] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.47239542s","start":"2026-07-01T07:19:58.658352Z","end":"2026-07-01T07:20:00.130747Z","steps":["trace[1404671051] 'process raft request' (duration: 1.472109027s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:00.130789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064061127s","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-01T07:20:00.130844Z","caller":"traceutil/trace.go:171","msg":"trace[829038859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"1.064151518s","start":"2026-07-01T07:19:59.066677Z","end":"2026-07-01T07:20:00.130828Z","steps":["trace[829038859] 'agreement among raft nodes before linearized reading' (duration: 1.064040797s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:00.130867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:19:59.06666Z","time spent":"1.064202929s","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T07:20:00.130865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.954557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-01T07:20:00.130853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.201168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:20:00.130864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:19:58.658328Z","time spent":"1.47249091s","remote":"127.0.0.1:57514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:20:00.130899Z","caller":"traceutil/trace.go:171","msg":"trace[455523289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"383.020347ms","start":"2026-07-01T07:19:59.747866Z","end":"2026-07-01T07:20:00.130886Z","steps":["trace[455523289] 'agreement among raft nodes before linearized reading' (duration: 382.908246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:00.130918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:19:59.747845Z","time spent":"383.068217ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T07:20:00.130912Z","caller":"traceutil/trace.go:171","msg":"trace[1902577166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"701.32135ms","start":"2026-07-01T07:19:59.429571Z","end":"2026-07-01T07:20:00.130892Z","steps":["trace[1902577166] 'agreement among raft nodes before linearized reading' (duration: 701.237789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:00.130861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079726497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-01T07:20:00.130946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:19:59.429548Z","time spent":"701.391291ms","remote":"127.0.0.1:57048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T07:20:00.130971Z","caller":"traceutil/trace.go:171","msg":"trace[1316888604] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"1.079899279s","start":"2026-07-01T07:19:59.051049Z","end":"2026-07-01T07:20:00.130948Z","steps":["trace[1316888604] 'agreement among raft nodes before linearized reading' (duration: 1.079641516s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:00.131046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:19:59.051006Z","time spent":"1.080028721s","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T07:20:01.979654Z","caller":"traceutil/trace.go:171","msg":"trace[587775256] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"202.896603ms","start":"2026-07-01T07:20:01.776737Z","end":"2026-07-01T07:20:01.979634Z","steps":["trace[587775256] 'process raft request' (duration: 202.780882ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:01.980593Z","caller":"traceutil/trace.go:171","msg":"trace[19414798] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"159.823228ms","start":"2026-07-01T07:20:01.82075Z","end":"2026-07-01T07:20:01.980573Z","steps":["trace[19414798] 'process raft request' (duration: 159.719496ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:04.823545Z","caller":"traceutil/trace.go:171","msg":"trace[981204833] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"163.494261ms","start":"2026-07-01T07:20:04.660028Z","end":"2026-07-01T07:20:04.823522Z","steps":["trace[981204833] 'process raft request' (duration: 163.340919ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:04.853667Z","caller":"traceutil/trace.go:171","msg":"trace[1326449911] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"181.992876ms","start":"2026-07-01T07:20:04.671661Z","end":"2026-07-01T07:20:04.853654Z","steps":["trace[1326449911] 'process raft request' (duration: 181.896505ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:05.175022Z","caller":"traceutil/trace.go:171","msg":"trace[1927990073] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"118.054052ms","start":"2026-07-01T07:20:05.056948Z","end":"2026-07-01T07:20:05.175002Z","steps":["trace[1927990073] 'process raft request' (duration: 117.8991ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:05.190978Z","caller":"traceutil/trace.go:171","msg":"trace[677658352] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"121.694158ms","start":"2026-07-01T07:20:05.069246Z","end":"2026-07-01T07:20:05.19094Z","steps":["trace[677658352] 'process raft request' (duration: 121.175423ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:05.45132Z","caller":"traceutil/trace.go:171","msg":"trace[745189881] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"100.064044ms","start":"2026-07-01T07:20:05.351225Z","end":"2026-07-01T07:20:05.451289Z","steps":["trace[745189881] 'process raft request' (duration: 99.583549ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:05.753667Z","caller":"traceutil/trace.go:171","msg":"trace[1336863692] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"133.358134ms","start":"2026-07-01T07:20:05.620279Z","end":"2026-07-01T07:20:05.753638Z","steps":["trace[1336863692] 'read index received' (duration: 35.602913ms)","trace[1336863692] 'applied index is now lower than readState.Index' (duration: 97.753751ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:20:05.753768Z","caller":"traceutil/trace.go:171","msg":"trace[1931154914] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"271.448506ms","start":"2026-07-01T07:20:05.482266Z","end":"2026-07-01T07:20:05.753715Z","steps":["trace[1931154914] 'process raft request' (duration: 173.610554ms)","trace[1931154914] 'compare' (duration: 97.531149ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:20:05.753966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.672127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-07-01T07:20:05.754018Z","caller":"traceutil/trace.go:171","msg":"trace[505306126] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:736; }","duration":"133.790209ms","start":"2026-07-01T07:20:05.620214Z","end":"2026-07-01T07:20:05.754004Z","steps":["trace[505306126] 'agreement among raft nodes before linearized reading' (duration: 133.582487ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:06.093772Z","caller":"traceutil/trace.go:171","msg":"trace[752744299] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"273.752395ms","start":"2026-07-01T07:20:05.819991Z","end":"2026-07-01T07:20:06.093743Z","steps":["trace[752744299] 'read index received' (duration: 244.252113ms)","trace[752744299] 'applied index is now lower than readState.Index' (duration: 29.499392ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:20:06.093836Z","caller":"traceutil/trace.go:171","msg":"trace[1340741252] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"327.445727ms","start":"2026-07-01T07:20:05.766359Z","end":"2026-07-01T07:20:06.093804Z","steps":["trace[1340741252] 'process raft request' (duration: 297.995596ms)","trace[1340741252] 'compare' (duration: 29.202529ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:20:06.093993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:05.766335Z","time spent":"327.576838ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18501,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:20:06.094169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.165469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-07-01T07:20:06.094234Z","caller":"traceutil/trace.go:171","msg":"trace[239964616] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:737; }","duration":"274.233779ms","start":"2026-07-01T07:20:05.819985Z","end":"2026-07-01T07:20:06.094218Z","steps":["trace[239964616] 'agreement among raft nodes before linearized reading' (duration: 274.000277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:06.325114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.545612ms","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-01T07:20:06.325188Z","caller":"traceutil/trace.go:171","msg":"trace[1821344091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:737; }","duration":"119.648423ms","start":"2026-07-01T07:20:06.205521Z","end":"2026-07-01T07:20:06.32517Z","steps":["trace[1821344091] 'range keys from in-memory index tree' (duration: 119.35322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:06.325208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.736954ms","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-01T07:20:06.32527Z","caller":"traceutil/trace.go:171","msg":"trace[507230838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:737; }","duration":"118.843875ms","start":"2026-07-01T07:20:06.206412Z","end":"2026-07-01T07:20:06.325256Z","steps":["trace[507230838] 'range keys from in-memory index tree' (duration: 118.584163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:06.325228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.553521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T07:20:06.325349Z","caller":"traceutil/trace.go:171","msg":"trace[1960716043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:737; }","duration":"125.756394ms","start":"2026-07-01T07:20:06.199564Z","end":"2026-07-01T07:20:06.325321Z","steps":["trace[1960716043] 'range keys from in-memory index tree' (duration: 125.290589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:06.824111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.564081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:20:06.824293Z","caller":"traceutil/trace.go:171","msg":"trace[1478291704] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:741; }","duration":"398.754963ms","start":"2026-07-01T07:20:06.425502Z","end":"2026-07-01T07:20:06.824257Z","steps":["trace[1478291704] 'range keys from in-memory index tree' (duration: 398.48164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:06.82438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.425437Z","time spent":"398.926364ms","remote":"127.0.0.1:57344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 "} {"level":"warn","ts":"2026-07-01T07:20:06.82415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.537701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:20:06.824675Z","caller":"traceutil/trace.go:171","msg":"trace[243837072] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:741; }","duration":"399.095906ms","start":"2026-07-01T07:20:06.425531Z","end":"2026-07-01T07:20:06.824627Z","steps":["trace[243837072] 'count revisions from in-memory index tree' (duration: 398.45427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:06.824759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.425507Z","time spent":"399.208588ms","remote":"127.0.0.1:57344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-01T07:20:06.825142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.47281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:20:06.825248Z","caller":"traceutil/trace.go:171","msg":"trace[731537533] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:741; }","duration":"399.623072ms","start":"2026-07-01T07:20:06.425599Z","end":"2026-07-01T07:20:06.825222Z","steps":["trace[731537533] 'range keys from in-memory index tree' (duration: 399.379499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:06.825357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.425587Z","time spent":"399.710933ms","remote":"127.0.0.1:57344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"warn","ts":"2026-07-01T07:20:07.01467Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870448862375957,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T07:20:07.175114Z","caller":"traceutil/trace.go:171","msg":"trace[433627771] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"666.786286ms","start":"2026-07-01T07:20:06.508304Z","end":"2026-07-01T07:20:07.17509Z","steps":["trace[433627771] 'process raft request' (duration: 666.612594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:07.175279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.508269Z","time spent":"666.911566ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4415,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:20:07.187576Z","caller":"traceutil/trace.go:171","msg":"trace[487553329] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:772; }","duration":"672.990296ms","start":"2026-07-01T07:20:06.514566Z","end":"2026-07-01T07:20:07.187556Z","steps":["trace[487553329] 'read index received' (duration: 660.341242ms)","trace[487553329] 'applied index is now lower than readState.Index' (duration: 12.648294ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:20:07.1877Z","caller":"traceutil/trace.go:171","msg":"trace[282690556] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"203.150701ms","start":"2026-07-01T07:20:06.984542Z","end":"2026-07-01T07:20:07.187693Z","steps":["trace[282690556] 'process raft request' (duration: 202.906759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:07.187837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.028191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:20:07.187873Z","caller":"traceutil/trace.go:171","msg":"trace[356129567] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:743; }","duration":"640.081001ms","start":"2026-07-01T07:20:06.547782Z","end":"2026-07-01T07:20:07.187863Z","steps":["trace[356129567] 'agreement among raft nodes before linearized reading' (duration: 639.99664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:07.187872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.33901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:20:07.187899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.547777Z","time spent":"640.115471ms","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"info","ts":"2026-07-01T07:20:07.187926Z","caller":"traceutil/trace.go:171","msg":"trace[426660459] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:743; }","duration":"672.42329ms","start":"2026-07-01T07:20:06.515489Z","end":"2026-07-01T07:20:07.187912Z","steps":["trace[426660459] 'agreement among raft nodes before linearized reading' (duration: 672.281069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:07.187966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.515479Z","time spent":"672.476861ms","remote":"127.0.0.1:57352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-01T07:20:07.18798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.40389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-01T07:20:07.188035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.00236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:20:07.188028Z","caller":"traceutil/trace.go:171","msg":"trace[1444753368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"673.47759ms","start":"2026-07-01T07:20:06.514537Z","end":"2026-07-01T07:20:07.188015Z","steps":["trace[1444753368] 'agreement among raft nodes before linearized reading' (duration: 673.220158ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:07.188061Z","caller":"traceutil/trace.go:171","msg":"trace[591531639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"548.057611ms","start":"2026-07-01T07:20:06.639991Z","end":"2026-07-01T07:20:07.188048Z","steps":["trace[591531639] 'agreement among raft nodes before linearized reading' (duration: 548.0251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:07.188065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.51453Z","time spent":"673.527481ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T07:20:07.188079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.639971Z","time spent":"548.104031ms","remote":"127.0.0.1:57048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T07:20:07.188088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.448744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:20:07.188146Z","caller":"traceutil/trace.go:171","msg":"trace[1144121367] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:743; }","duration":"640.539586ms","start":"2026-07-01T07:20:06.547587Z","end":"2026-07-01T07:20:07.188126Z","steps":["trace[1144121367] 'agreement among raft nodes before linearized reading' (duration: 640.475105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:07.188164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.710653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:20:07.188178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.097975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:20:07.188173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.547564Z","time spent":"640.601596ms","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-01T07:20:07.188214Z","caller":"traceutil/trace.go:171","msg":"trace[1367031445] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:743; }","duration":"605.145345ms","start":"2026-07-01T07:20:06.583063Z","end":"2026-07-01T07:20:07.188208Z","steps":["trace[1367031445] 'agreement among raft nodes before linearized reading' (duration: 605.097615ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:07.188216Z","caller":"traceutil/trace.go:171","msg":"trace[1816956480] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:743; }","duration":"671.801194ms","start":"2026-07-01T07:20:06.516405Z","end":"2026-07-01T07:20:07.188206Z","steps":["trace[1816956480] 'agreement among raft nodes before linearized reading' (duration: 671.733614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:07.188234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.583057Z","time spent":"605.172696ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-07-01T07:20:07.188252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.516385Z","time spent":"671.859285ms","remote":"127.0.0.1:57352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-07-01T07:20:07.188335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.677277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:20:07.188373Z","caller":"traceutil/trace.go:171","msg":"trace[1527661359] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:743; }","duration":"640.739447ms","start":"2026-07-01T07:20:06.547627Z","end":"2026-07-01T07:20:07.188366Z","steps":["trace[1527661359] 'agreement among raft nodes before linearized reading' (duration: 640.686666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:07.188395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.547614Z","time spent":"640.775387ms","remote":"127.0.0.1:57358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-01T07:20:07.188386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.64935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:20:07.188393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.842884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:20:07.188423Z","caller":"traceutil/trace.go:171","msg":"trace[1423597969] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:743; }","duration":"605.70193ms","start":"2026-07-01T07:20:06.582709Z","end":"2026-07-01T07:20:07.188411Z","steps":["trace[1423597969] 'agreement among raft nodes before linearized reading' (duration: 605.64475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:07.188458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.582702Z","time spent":"605.747131ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-01T07:20:07.188485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.828462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:20:07.188506Z","caller":"traceutil/trace.go:171","msg":"trace[2061804615] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:743; }","duration":"605.903172ms","start":"2026-07-01T07:20:06.582597Z","end":"2026-07-01T07:20:07.1885Z","steps":["trace[2061804615] 'agreement among raft nodes before linearized reading' (duration: 605.865512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:07.188526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.582576Z","time spent":"605.945252ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-01T07:20:07.188427Z","caller":"traceutil/trace.go:171","msg":"trace[2054994315] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:743; }","duration":"672.888264ms","start":"2026-07-01T07:20:06.515529Z","end":"2026-07-01T07:20:07.188418Z","steps":["trace[2054994315] 'agreement among raft nodes before linearized reading' (duration: 672.839014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:07.18861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:06.515523Z","time spent":"673.057356ms","remote":"127.0.0.1:57352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"info","ts":"2026-07-01T07:20:07.412682Z","caller":"traceutil/trace.go:171","msg":"trace[779996086] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"219.883644ms","start":"2026-07-01T07:20:07.192777Z","end":"2026-07-01T07:20:07.41266Z","steps":["trace[779996086] 'process raft request' (duration: 219.751953ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:07.429073Z","caller":"traceutil/trace.go:171","msg":"trace[17288936] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:774; }","duration":"118.081193ms","start":"2026-07-01T07:20:07.310975Z","end":"2026-07-01T07:20:07.429056Z","steps":["trace[17288936] 'read index received' (duration: 101.52324ms)","trace[17288936] 'applied index is now lower than readState.Index' (duration: 16.556813ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:20:07.429193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.168723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:20:07.429193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.182434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:20:07.429252Z","caller":"traceutil/trace.go:171","msg":"trace[953197625] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:744; }","duration":"118.250114ms","start":"2026-07-01T07:20:07.310972Z","end":"2026-07-01T07:20:07.429222Z","steps":["trace[953197625] 'agreement among raft nodes before linearized reading' (duration: 118.182484ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:07.429274Z","caller":"traceutil/trace.go:171","msg":"trace[277497569] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:744; }","duration":"118.313315ms","start":"2026-07-01T07:20:07.310941Z","end":"2026-07-01T07:20:07.429255Z","steps":["trace[277497569] 'agreement among raft nodes before linearized reading' (duration: 118.174933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:07.429281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.206494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:20:07.429336Z","caller":"traceutil/trace.go:171","msg":"trace[1361802699] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:744; }","duration":"118.307724ms","start":"2026-07-01T07:20:07.311014Z","end":"2026-07-01T07:20:07.429322Z","steps":["trace[1361802699] 'agreement among raft nodes before linearized reading' (duration: 118.205593ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:14.366005Z","caller":"traceutil/trace.go:171","msg":"trace[531080526] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"154.8294ms","start":"2026-07-01T07:20:14.211126Z","end":"2026-07-01T07:20:14.365956Z","steps":["trace[531080526] 'process raft request' (duration: 62.277852ms)","trace[531080526] 'compare' (duration: 92.324927ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:20:14.37129Z","caller":"traceutil/trace.go:171","msg":"trace[1341170997] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"157.315275ms","start":"2026-07-01T07:20:14.213944Z","end":"2026-07-01T07:20:14.371259Z","steps":["trace[1341170997] 'process raft request' (duration: 157.248292ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:14.371345Z","caller":"traceutil/trace.go:171","msg":"trace[786368980] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"159.58715ms","start":"2026-07-01T07:20:14.211716Z","end":"2026-07-01T07:20:14.371303Z","steps":["trace[786368980] 'process raft request' (duration: 159.308707ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:19.307852Z","caller":"traceutil/trace.go:171","msg":"trace[1605317972] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"261.458738ms","start":"2026-07-01T07:20:19.046375Z","end":"2026-07-01T07:20:19.307834Z","steps":["trace[1605317972] 'process raft request' (duration: 261.117733ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:26.573166Z","caller":"traceutil/trace.go:171","msg":"trace[116784585] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"107.856287ms","start":"2026-07-01T07:20:26.46529Z","end":"2026-07-01T07:20:26.573146Z","steps":["trace[116784585] 'process raft request' (duration: 107.775234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:26.574064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.379799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-07-01T07:20:26.574114Z","caller":"traceutil/trace.go:171","msg":"trace[299525543] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:900; }","duration":"109.455742ms","start":"2026-07-01T07:20:26.464647Z","end":"2026-07-01T07:20:26.574103Z","steps":["trace[299525543] 'agreement among raft nodes before linearized reading' (duration: 109.374899ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:42.489985Z","caller":"traceutil/trace.go:171","msg":"trace[996320193] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"260.298363ms","start":"2026-07-01T07:20:42.229652Z","end":"2026-07-01T07:20:42.48995Z","steps":["trace[996320193] 'process raft request' (duration: 260.075036ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:42.505076Z","caller":"traceutil/trace.go:171","msg":"trace[1311113335] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"216.896926ms","start":"2026-07-01T07:20:42.288137Z","end":"2026-07-01T07:20:42.505034Z","steps":["trace[1311113335] 'process raft request' (duration: 216.618007ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:42.895018Z","caller":"traceutil/trace.go:171","msg":"trace[734970123] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1227; }","duration":"351.830243ms","start":"2026-07-01T07:20:42.543164Z","end":"2026-07-01T07:20:42.894995Z","steps":["trace[734970123] 'read index received' (duration: 351.684537ms)","trace[734970123] 'applied index is now lower than readState.Index' (duration: 144.495µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:20:42.895263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.949988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-01T07:20:42.895275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.096462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T07:20:42.895309Z","caller":"traceutil/trace.go:171","msg":"trace[1266682847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1187; }","duration":"246.038381ms","start":"2026-07-01T07:20:42.649256Z","end":"2026-07-01T07:20:42.895294Z","steps":["trace[1266682847] 'agreement among raft nodes before linearized reading' (duration: 245.884806ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:42.895393Z","caller":"traceutil/trace.go:171","msg":"trace[1937186244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1187; }","duration":"352.241636ms","start":"2026-07-01T07:20:42.543127Z","end":"2026-07-01T07:20:42.895368Z","steps":["trace[1937186244] 'agreement among raft nodes before linearized reading' (duration: 351.960757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:42.895443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:42.543115Z","time spent":"352.31807ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T07:20:42.895674Z","caller":"traceutil/trace.go:171","msg":"trace[2139299190] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"354.699751ms","start":"2026-07-01T07:20:42.540394Z","end":"2026-07-01T07:20:42.895094Z","steps":["trace[2139299190] 'process raft request' (duration: 354.465053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:42.895784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:42.540371Z","time spent":"355.345503ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:20:49.464044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870448862376928,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T07:20:49.54911Z","caller":"traceutil/trace.go:171","msg":"trace[1635381721] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"585.447133ms","start":"2026-07-01T07:20:48.963635Z","end":"2026-07-01T07:20:49.549082Z","steps":["trace[1635381721] 'read index received' (duration: 585.305929ms)","trace[1635381721] 'applied index is now lower than readState.Index' (duration: 139.804µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:20:49.549242Z","caller":"traceutil/trace.go:171","msg":"trace[374825740] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"624.3245ms","start":"2026-07-01T07:20:48.924876Z","end":"2026-07-01T07:20:49.5492Z","steps":["trace[374825740] 'process raft request' (duration: 624.039571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:49.549462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:48.924857Z","time spent":"624.521357ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:20:49.549484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.824646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T07:20:49.54958Z","caller":"traceutil/trace.go:171","msg":"trace[160640788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1218; }","duration":"585.966271ms","start":"2026-07-01T07:20:48.963589Z","end":"2026-07-01T07:20:49.549556Z","steps":["trace[160640788] 'agreement among raft nodes before linearized reading' (duration: 585.668401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:49.549642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:48.963574Z","time spent":"586.055263ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T07:20:49.549963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.270073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:20:49.550078Z","caller":"traceutil/trace.go:171","msg":"trace[51590902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"121.412448ms","start":"2026-07-01T07:20:49.42865Z","end":"2026-07-01T07:20:49.550062Z","steps":["trace[51590902] 'agreement among raft nodes before linearized reading' (duration: 121.266043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:49.550085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.832356ms","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-01T07:20:49.550184Z","caller":"traceutil/trace.go:171","msg":"trace[1057062181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1218; }","duration":"581.936479ms","start":"2026-07-01T07:20:48.968231Z","end":"2026-07-01T07:20:49.550168Z","steps":["trace[1057062181] 'agreement among raft nodes before linearized reading' (duration: 581.575318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:49.550276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:20:48.968203Z","time spent":"582.061404ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-01T07:20:55.468157Z","caller":"traceutil/trace.go:171","msg":"trace[370735589] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"117.096515ms","start":"2026-07-01T07:20:55.351034Z","end":"2026-07-01T07:20:55.468131Z","steps":["trace[370735589] 'read index received' (duration: 94.222997ms)","trace[370735589] 'applied index is now lower than readState.Index' (duration: 22.872508ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:20:55.468216Z","caller":"traceutil/trace.go:171","msg":"trace[698404512] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"129.835554ms","start":"2026-07-01T07:20:55.338358Z","end":"2026-07-01T07:20:55.468193Z","steps":["trace[698404512] 'process raft request' (duration: 106.891233ms)","trace[698404512] 'compare' (duration: 22.725574ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:20:55.468421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.357723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/atmosphere.18be196c417547fc\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-07-01T07:20:55.468466Z","caller":"traceutil/trace.go:171","msg":"trace[1577440564] range","detail":"{range_begin:/registry/events/default/atmosphere.18be196c417547fc; range_end:; response_count:1; response_revision:1272; }","duration":"117.451656ms","start":"2026-07-01T07:20:55.351002Z","end":"2026-07-01T07:20:55.468453Z","steps":["trace[1577440564] 'agreement among raft nodes before linearized reading' (duration: 117.25306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:20:55.508876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.002661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/cert-manager/self-signed-ca\" ","response":"range_response_count:1 size:2360"} {"level":"info","ts":"2026-07-01T07:20:55.508943Z","caller":"traceutil/trace.go:171","msg":"trace[1086900404] range","detail":"{range_begin:/registry/cert-manager.io/certificates/cert-manager/self-signed-ca; range_end:; response_count:1; response_revision:1273; }","duration":"153.100065ms","start":"2026-07-01T07:20:55.355827Z","end":"2026-07-01T07:20:55.508927Z","steps":["trace[1086900404] 'agreement among raft nodes before linearized reading' (duration: 152.932229ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:55.508961Z","caller":"traceutil/trace.go:171","msg":"trace[1193346672] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"151.015041ms","start":"2026-07-01T07:20:55.357923Z","end":"2026-07-01T07:20:55.508938Z","steps":["trace[1193346672] 'process raft request' (duration: 150.733302ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:55.6648Z","caller":"traceutil/trace.go:171","msg":"trace[1319550425] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"150.553787ms","start":"2026-07-01T07:20:55.514228Z","end":"2026-07-01T07:20:55.664782Z","steps":["trace[1319550425] 'process raft request' (duration: 150.266948ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:55.665259Z","caller":"traceutil/trace.go:171","msg":"trace[1793341029] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"149.261698ms","start":"2026-07-01T07:20:55.515967Z","end":"2026-07-01T07:20:55.665228Z","steps":["trace[1793341029] 'process raft request' (duration: 148.901567ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:55.665495Z","caller":"traceutil/trace.go:171","msg":"trace[324536772] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"149.863125ms","start":"2026-07-01T07:20:55.515616Z","end":"2026-07-01T07:20:55.66548Z","steps":["trace[324536772] 'process raft request' (duration: 149.134473ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:55.665577Z","caller":"traceutil/trace.go:171","msg":"trace[1807509627] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"150.019111ms","start":"2026-07-01T07:20:55.515537Z","end":"2026-07-01T07:20:55.665556Z","steps":["trace[1807509627] 'process raft request' (duration: 149.167315ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:55.665229Z","caller":"traceutil/trace.go:171","msg":"trace[387692755] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"149.263658ms","start":"2026-07-01T07:20:55.515769Z","end":"2026-07-01T07:20:55.665033Z","steps":["trace[387692755] 'process raft request' (duration: 149.054031ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:55.665646Z","caller":"traceutil/trace.go:171","msg":"trace[1855388484] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"149.861336ms","start":"2026-07-01T07:20:55.515722Z","end":"2026-07-01T07:20:55.665584Z","steps":["trace[1855388484] 'process raft request' (duration: 149.062641ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:55.665479Z","caller":"traceutil/trace.go:171","msg":"trace[1442027749] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"145.823392ms","start":"2026-07-01T07:20:55.519618Z","end":"2026-07-01T07:20:55.665442Z","steps":["trace[1442027749] 'process raft request' (duration: 145.288836ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:20:55.84992Z","caller":"traceutil/trace.go:171","msg":"trace[764716385] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1289; }","duration":"105.011256ms","start":"2026-07-01T07:20:55.74488Z","end":"2026-07-01T07:20:55.849891Z","steps":["trace[764716385] 'process raft request' (duration: 104.890962ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:16.217501Z","caller":"traceutil/trace.go:171","msg":"trace[168127274] linearizableReadLoop","detail":"{readStateIndex:1574; appliedIndex:1573; }","duration":"109.475643ms","start":"2026-07-01T07:21:16.108006Z","end":"2026-07-01T07:21:16.217482Z","steps":["trace[168127274] 'read index received' (duration: 109.282078ms)","trace[168127274] 'applied index is now lower than readState.Index' (duration: 192.895µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:21:16.21777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.730529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/federations.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:21:16.217808Z","caller":"traceutil/trace.go:171","msg":"trace[1926034946] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/federations.rabbitmq.com; range_end:; response_count:0; response_revision:1522; }","duration":"109.820831ms","start":"2026-07-01T07:21:16.107976Z","end":"2026-07-01T07:21:16.217797Z","steps":["trace[1926034946] 'agreement among raft nodes before linearized reading' (duration: 109.627806ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:16.217999Z","caller":"traceutil/trace.go:171","msg":"trace[1716530056] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"111.768701ms","start":"2026-07-01T07:21:16.106217Z","end":"2026-07-01T07:21:16.217985Z","steps":["trace[1716530056] 'process raft request' (duration: 111.134544ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:17.594537Z","caller":"traceutil/trace.go:171","msg":"trace[1163478713] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"102.157995ms","start":"2026-07-01T07:21:17.492349Z","end":"2026-07-01T07:21:17.594507Z","steps":["trace[1163478713] 'process raft request' (duration: 77.100979ms)","trace[1163478713] 'compare' (duration: 24.937913ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:21:17.595619Z","caller":"traceutil/trace.go:171","msg":"trace[883018083] transaction","detail":"{read_only:false; response_revision:1572; number_of_response:1; }","duration":"102.131944ms","start":"2026-07-01T07:21:17.49346Z","end":"2026-07-01T07:21:17.595592Z","steps":["trace[883018083] 'process raft request' (duration: 102.066912ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:17.595657Z","caller":"traceutil/trace.go:171","msg":"trace[1723519296] transaction","detail":"{read_only:false; response_revision:1571; number_of_response:1; }","duration":"102.714808ms","start":"2026-07-01T07:21:17.492906Z","end":"2026-07-01T07:21:17.59562Z","steps":["trace[1723519296] 'process raft request' (duration: 102.499633ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:28.213054Z","caller":"traceutil/trace.go:171","msg":"trace[160978621] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"117.556044ms","start":"2026-07-01T07:21:28.095477Z","end":"2026-07-01T07:21:28.213033Z","steps":["trace[160978621] 'process raft request' (duration: 117.253647ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:37.667737Z","caller":"traceutil/trace.go:171","msg":"trace[1426390333] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"230.117474ms","start":"2026-07-01T07:21:37.437593Z","end":"2026-07-01T07:21:37.66771Z","steps":["trace[1426390333] 'process raft request' (duration: 229.967641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:38.264638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.041552ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:21:38.264766Z","caller":"traceutil/trace.go:171","msg":"trace[536672309] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1885; }","duration":"200.183355ms","start":"2026-07-01T07:21:38.064565Z","end":"2026-07-01T07:21:38.264748Z","steps":["trace[536672309] 'range keys from in-memory index tree' (duration: 200.020542ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:38.26521Z","caller":"traceutil/trace.go:171","msg":"trace[1949790054] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1962; }","duration":"642.00051ms","start":"2026-07-01T07:21:37.623196Z","end":"2026-07-01T07:21:38.265196Z","steps":["trace[1949790054] 'read index received' (duration: 44.369819ms)","trace[1949790054] 'applied index is now lower than readState.Index' (duration: 597.629761ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:21:38.265326Z","caller":"traceutil/trace.go:171","msg":"trace[291237400] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"753.691772ms","start":"2026-07-01T07:21:37.511598Z","end":"2026-07-01T07:21:38.26529Z","steps":["trace[291237400] 'process raft request' (duration: 713.255866ms)","trace[291237400] 'compare' (duration: 39.745871ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:21:38.265494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:37.511575Z","time spent":"753.838515ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:21:38.265747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.360659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10630"} {"level":"info","ts":"2026-07-01T07:21:38.265801Z","caller":"traceutil/trace.go:171","msg":"trace[1650246788] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1886; }","duration":"594.471271ms","start":"2026-07-01T07:21:37.671318Z","end":"2026-07-01T07:21:38.26579Z","steps":["trace[1650246788] 'agreement among raft nodes before linearized reading' (duration: 594.335808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:38.265819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.440703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-01T07:21:38.265871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:37.671307Z","time spent":"594.554313ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10652,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-07-01T07:21:38.265951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.996145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-01T07:21:38.265985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.946532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-01T07:21:38.266003Z","caller":"traceutil/trace.go:171","msg":"trace[1311307482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1886; }","duration":"294.088588ms","start":"2026-07-01T07:21:37.971898Z","end":"2026-07-01T07:21:38.265987Z","steps":["trace[1311307482] 'agreement among raft nodes before linearized reading' (duration: 293.939265ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:38.266021Z","caller":"traceutil/trace.go:171","msg":"trace[165761676] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1886; }","duration":"158.979153ms","start":"2026-07-01T07:21:38.107031Z","end":"2026-07-01T07:21:38.26601Z","steps":["trace[165761676] 'agreement among raft nodes before linearized reading' (duration: 158.890031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:38.266028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.664835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T07:21:38.266112Z","caller":"traceutil/trace.go:171","msg":"trace[318549579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1886; }","duration":"360.821309ms","start":"2026-07-01T07:21:37.905273Z","end":"2026-07-01T07:21:38.266094Z","steps":["trace[318549579] 'agreement among raft nodes before linearized reading' (duration: 360.568913ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:38.265895Z","caller":"traceutil/trace.go:171","msg":"trace[561566780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1886; }","duration":"288.544416ms","start":"2026-07-01T07:21:37.977334Z","end":"2026-07-01T07:21:38.265879Z","steps":["trace[561566780] 'agreement among raft nodes before linearized reading' (duration: 288.333071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:38.266163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:37.905258Z","time spent":"360.896011ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T07:21:38.266339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.14507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T07:21:38.266382Z","caller":"traceutil/trace.go:171","msg":"trace[946706937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1886; }","duration":"187.200311ms","start":"2026-07-01T07:21:38.079172Z","end":"2026-07-01T07:21:38.266372Z","steps":["trace[946706937] 'agreement among raft nodes before linearized reading' (duration: 187.100479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:38.267634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.436023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-01T07:21:38.267689Z","caller":"traceutil/trace.go:171","msg":"trace[857318611] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1886; }","duration":"644.523114ms","start":"2026-07-01T07:21:37.623152Z","end":"2026-07-01T07:21:38.267675Z","steps":["trace[857318611] 'agreement among raft nodes before linearized reading' (duration: 642.162042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:38.267723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:21:37.623118Z","time spent":"644.597056ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-01T07:21:38.516726Z","caller":"traceutil/trace.go:171","msg":"trace[1447989239] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"158.638816ms","start":"2026-07-01T07:21:38.35806Z","end":"2026-07-01T07:21:38.516699Z","steps":["trace[1447989239] 'process raft request' (duration: 158.438911ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:38.590101Z","caller":"traceutil/trace.go:171","msg":"trace[519802886] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"231.952721ms","start":"2026-07-01T07:21:38.358125Z","end":"2026-07-01T07:21:38.590078Z","steps":["trace[519802886] 'process raft request' (duration: 231.767447ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:38.590373Z","caller":"traceutil/trace.go:171","msg":"trace[1246086312] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1970; }","duration":"161.869926ms","start":"2026-07-01T07:21:38.428485Z","end":"2026-07-01T07:21:38.590355Z","steps":["trace[1246086312] 'read index received' (duration: 88.11492ms)","trace[1246086312] 'applied index is now lower than readState.Index' (duration: 73.753386ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:21:38.590409Z","caller":"traceutil/trace.go:171","msg":"trace[232712214] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"231.659315ms","start":"2026-07-01T07:21:38.358713Z","end":"2026-07-01T07:21:38.590373Z","steps":["trace[232712214] 'process raft request' (duration: 231.304767ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:38.590486Z","caller":"traceutil/trace.go:171","msg":"trace[1674517496] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"231.585563ms","start":"2026-07-01T07:21:38.35889Z","end":"2026-07-01T07:21:38.590475Z","steps":["trace[1674517496] 'process raft request' (duration: 231.338968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:21:38.590635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.135302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:21:38.590746Z","caller":"traceutil/trace.go:171","msg":"trace[601799815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"162.256735ms","start":"2026-07-01T07:21:38.428443Z","end":"2026-07-01T07:21:38.590699Z","steps":["trace[601799815] 'agreement among raft nodes before linearized reading' (duration: 162.110762ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:38.972866Z","caller":"traceutil/trace.go:171","msg":"trace[992293765] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"242.427541ms","start":"2026-07-01T07:21:38.730402Z","end":"2026-07-01T07:21:38.97283Z","steps":["trace[992293765] 'process raft request' (duration: 159.480105ms)","trace[992293765] 'compare' (duration: 82.445175ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:21:38.973085Z","caller":"traceutil/trace.go:171","msg":"trace[279398715] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"231.721266ms","start":"2026-07-01T07:21:38.741329Z","end":"2026-07-01T07:21:38.973051Z","steps":["trace[279398715] 'process raft request' (duration: 231.43083ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:44.833814Z","caller":"traceutil/trace.go:171","msg":"trace[1101173532] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"154.565913ms","start":"2026-07-01T07:21:44.679223Z","end":"2026-07-01T07:21:44.833789Z","steps":["trace[1101173532] 'process raft request' (duration: 154.401699ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:51.115832Z","caller":"traceutil/trace.go:171","msg":"trace[1245367986] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"103.956266ms","start":"2026-07-01T07:21:51.011847Z","end":"2026-07-01T07:21:51.115803Z","steps":["trace[1245367986] 'process raft request' (duration: 103.738722ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:21:51.173747Z","caller":"traceutil/trace.go:171","msg":"trace[379148346] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"159.840679ms","start":"2026-07-01T07:21:51.013873Z","end":"2026-07-01T07:21:51.173714Z","steps":["trace[379148346] 'process raft request' (duration: 159.703446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:23:48.764049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.09771ms","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-01T07:23:48.764228Z","caller":"traceutil/trace.go:171","msg":"trace[409119097] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2754; }","duration":"120.344543ms","start":"2026-07-01T07:23:48.643854Z","end":"2026-07-01T07:23:48.764198Z","steps":["trace[409119097] 'range keys from in-memory index tree' (duration: 120.000319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:23:49.100947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.008862ms","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-01T07:23:49.101011Z","caller":"traceutil/trace.go:171","msg":"trace[813756130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2755; }","duration":"119.125135ms","start":"2026-07-01T07:23:48.981871Z","end":"2026-07-01T07:23:49.100996Z","steps":["trace[813756130] 'range keys from in-memory index tree' (duration: 118.80159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:23:49.101095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.692001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:23:49.10122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.429201ms","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-01T07:23:49.101257Z","caller":"traceutil/trace.go:171","msg":"trace[1414530509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2755; }","duration":"117.496281ms","start":"2026-07-01T07:23:48.983752Z","end":"2026-07-01T07:23:49.101248Z","steps":["trace[1414530509] 'range keys from in-memory index tree' (duration: 117.308349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:23:49.101274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.031195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18be19900cb067fa\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-01T07:23:49.101294Z","caller":"traceutil/trace.go:171","msg":"trace[1125665595] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18be19900cb067fa; range_end:; response_count:1; response_revision:2755; }","duration":"134.084327ms","start":"2026-07-01T07:23:48.967205Z","end":"2026-07-01T07:23:49.101289Z","steps":["trace[1125665595] 'range keys from in-memory index tree' (duration: 133.938204ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:23:49.101366Z","caller":"traceutil/trace.go:171","msg":"trace[185381266] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2755; }","duration":"167.889365ms","start":"2026-07-01T07:23:48.933332Z","end":"2026-07-01T07:23:49.101222Z","steps":["trace[185381266] 'count revisions from in-memory index tree' (duration: 167.57466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:23:49.101414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.561178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-07-01T07:23:49.101425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.50808ms","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-01T07:23:49.101437Z","caller":"traceutil/trace.go:171","msg":"trace[2010791969] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2755; }","duration":"116.603999ms","start":"2026-07-01T07:23:48.984826Z","end":"2026-07-01T07:23:49.10143Z","steps":["trace[2010791969] 'range keys from in-memory index tree' (duration: 116.470637ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:23:49.101442Z","caller":"traceutil/trace.go:171","msg":"trace[1827442381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2755; }","duration":"119.576041ms","start":"2026-07-01T07:23:48.981861Z","end":"2026-07-01T07:23:49.101437Z","steps":["trace[1827442381] 'range keys from in-memory index tree' (duration: 119.370958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:23:49.101674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.69562ms","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-01T07:23:49.101741Z","caller":"traceutil/trace.go:171","msg":"trace[982685038] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2755; }","duration":"116.787812ms","start":"2026-07-01T07:23:48.984938Z","end":"2026-07-01T07:23:49.101726Z","steps":["trace[982685038] 'range keys from in-memory index tree' (duration: 116.564959ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:23:49.220672Z","caller":"traceutil/trace.go:171","msg":"trace[1160364783] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"115.505143ms","start":"2026-07-01T07:23:49.105136Z","end":"2026-07-01T07:23:49.220641Z","steps":["trace[1160364783] 'process raft request' (duration: 115.372391ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:23:49.22103Z","caller":"traceutil/trace.go:171","msg":"trace[603912378] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"115.853738ms","start":"2026-07-01T07:23:49.105138Z","end":"2026-07-01T07:23:49.220992Z","steps":["trace[603912378] 'process raft request' (duration: 115.191138ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:23:49.220992Z","caller":"traceutil/trace.go:171","msg":"trace[870066483] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"115.773687ms","start":"2026-07-01T07:23:49.10515Z","end":"2026-07-01T07:23:49.220923Z","steps":["trace[870066483] 'process raft request' (duration: 115.425492ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:23:49.221009Z","caller":"traceutil/trace.go:171","msg":"trace[1561467722] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"115.753726ms","start":"2026-07-01T07:23:49.105223Z","end":"2026-07-01T07:23:49.220977Z","steps":["trace[1561467722] 'process raft request' (duration: 115.474432ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:23:49.221153Z","caller":"traceutil/trace.go:171","msg":"trace[1603662972] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"115.669195ms","start":"2026-07-01T07:23:49.105449Z","end":"2026-07-01T07:23:49.221118Z","steps":["trace[1603662972] 'process raft request' (duration: 115.31001ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:32.268844Z","caller":"traceutil/trace.go:171","msg":"trace[594159022] linearizableReadLoop","detail":"{readStateIndex:3857; appliedIndex:3856; }","duration":"131.179712ms","start":"2026-07-01T07:26:32.137636Z","end":"2026-07-01T07:26:32.268816Z","steps":["trace[594159022] 'read index received' (duration: 89.824162ms)","trace[594159022] 'applied index is now lower than readState.Index' (duration: 41.35438ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:26:32.268892Z","caller":"traceutil/trace.go:171","msg":"trace[906091521] transaction","detail":"{read_only:false; response_revision:3712; number_of_response:1; }","duration":"146.444394ms","start":"2026-07-01T07:26:32.122419Z","end":"2026-07-01T07:26:32.268864Z","steps":["trace[906091521] 'process raft request' (duration: 105.016152ms)","trace[906091521] 'compare' (duration: 41.267907ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:26:32.26907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.403769ms","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-01T07:26:32.269132Z","caller":"traceutil/trace.go:171","msg":"trace[1665606372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3712; }","duration":"131.515471ms","start":"2026-07-01T07:26:32.137602Z","end":"2026-07-01T07:26:32.269117Z","steps":["trace[1665606372] 'agreement among raft nodes before linearized reading' (duration: 131.340316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:26:32.269286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.318608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-07-01T07:26:32.26929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.57994ms","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-01T07:26:32.26936Z","caller":"traceutil/trace.go:171","msg":"trace[1195821379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3712; }","duration":"127.693364ms","start":"2026-07-01T07:26:32.14165Z","end":"2026-07-01T07:26:32.269344Z","steps":["trace[1195821379] 'agreement among raft nodes before linearized reading' (duration: 127.530309ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:26:32.269354Z","caller":"traceutil/trace.go:171","msg":"trace[1441778559] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3712; }","duration":"130.497922ms","start":"2026-07-01T07:26:32.138835Z","end":"2026-07-01T07:26:32.269333Z","steps":["trace[1441778559] 'agreement among raft nodes before linearized reading' (duration: 130.267796ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:29.873202Z","caller":"traceutil/trace.go:171","msg":"trace[1217078657] transaction","detail":"{read_only:false; response_revision:4203; number_of_response:1; }","duration":"167.226061ms","start":"2026-07-01T07:27:29.705948Z","end":"2026-07-01T07:27:29.873174Z","steps":["trace[1217078657] 'process raft request' (duration: 167.17037ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:29.873246Z","caller":"traceutil/trace.go:171","msg":"trace[407798879] transaction","detail":"{read_only:false; response_revision:4202; number_of_response:1; }","duration":"167.317523ms","start":"2026-07-01T07:27:29.705891Z","end":"2026-07-01T07:27:29.873208Z","steps":["trace[407798879] 'process raft request' (duration: 103.498851ms)","trace[407798879] 'compare' (duration: 63.570876ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:29.87389Z","caller":"traceutil/trace.go:171","msg":"trace[127042248] transaction","detail":"{read_only:false; response_revision:4204; number_of_response:1; }","duration":"106.076733ms","start":"2026-07-01T07:27:29.767767Z","end":"2026-07-01T07:27:29.873844Z","steps":["trace[127042248] 'process raft request' (duration: 105.710665ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:29.874356Z","caller":"traceutil/trace.go:171","msg":"trace[2108606517] transaction","detail":"{read_only:false; response_revision:4205; number_of_response:1; }","duration":"105.485419ms","start":"2026-07-01T07:27:29.768846Z","end":"2026-07-01T07:27:29.874331Z","steps":["trace[2108606517] 'process raft request' (duration: 104.915275ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:29.874363Z","caller":"traceutil/trace.go:171","msg":"trace[1371898962] transaction","detail":"{read_only:false; response_revision:4206; number_of_response:1; }","duration":"105.161811ms","start":"2026-07-01T07:27:29.769159Z","end":"2026-07-01T07:27:29.87432Z","steps":["trace[1371898962] 'process raft request' (duration: 104.664889ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:29.875259Z","caller":"traceutil/trace.go:171","msg":"trace[1286890589] transaction","detail":"{read_only:false; response_revision:4207; number_of_response:1; }","duration":"104.075765ms","start":"2026-07-01T07:27:29.771167Z","end":"2026-07-01T07:27:29.875243Z","steps":["trace[1286890589] 'process raft request' (duration: 102.714372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.378163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.484394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:27:30.378614Z","caller":"traceutil/trace.go:171","msg":"trace[445993337] transaction","detail":"{read_only:false; response_revision:4220; number_of_response:1; }","duration":"225.972187ms","start":"2026-07-01T07:27:30.152613Z","end":"2026-07-01T07:27:30.378586Z","steps":["trace[445993337] 'process raft request' (duration: 121.96697ms)","trace[445993337] 'compare' (duration: 103.232598ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:30.418166Z","caller":"traceutil/trace.go:171","msg":"trace[1332930131] transaction","detail":"{read_only:false; response_revision:4221; number_of_response:1; }","duration":"207.087962ms","start":"2026-07-01T07:27:30.211036Z","end":"2026-07-01T07:27:30.418124Z","steps":["trace[1332930131] 'process raft request' (duration: 206.727784ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.418343Z","caller":"traceutil/trace.go:171","msg":"trace[378808541] transaction","detail":"{read_only:false; response_revision:4226; number_of_response:1; }","duration":"198.983487ms","start":"2026-07-01T07:27:30.219316Z","end":"2026-07-01T07:27:30.4183Z","steps":["trace[378808541] 'process raft request' (duration: 198.930005ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.41854Z","caller":"traceutil/trace.go:171","msg":"trace[734424613] transaction","detail":"{read_only:false; response_revision:4222; number_of_response:1; }","duration":"206.556749ms","start":"2026-07-01T07:27:30.211973Z","end":"2026-07-01T07:27:30.418529Z","steps":["trace[734424613] 'process raft request' (duration: 206.032426ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.418374Z","caller":"traceutil/trace.go:171","msg":"trace[1903680556] transaction","detail":"{read_only:false; response_revision:4223; number_of_response:1; }","duration":"204.754285ms","start":"2026-07-01T07:27:30.213585Z","end":"2026-07-01T07:27:30.418339Z","steps":["trace[1903680556] 'process raft request' (duration: 204.482179ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.418619Z","caller":"traceutil/trace.go:171","msg":"trace[320988306] transaction","detail":"{read_only:false; response_revision:4224; number_of_response:1; }","duration":"200.979335ms","start":"2026-07-01T07:27:30.217577Z","end":"2026-07-01T07:27:30.418556Z","steps":["trace[320988306] 'process raft request' (duration: 200.566605ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.418768Z","caller":"traceutil/trace.go:171","msg":"trace[2004529882] transaction","detail":"{read_only:false; response_revision:4225; number_of_response:1; }","duration":"199.759106ms","start":"2026-07-01T07:27:30.219Z","end":"2026-07-01T07:27:30.418759Z","steps":["trace[2004529882] 'process raft request' (duration: 199.197352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.614894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.592898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:27:30.615332Z","caller":"traceutil/trace.go:171","msg":"trace[2062049391] transaction","detail":"{read_only:false; response_revision:4227; number_of_response:1; }","duration":"228.926278ms","start":"2026-07-01T07:27:30.386369Z","end":"2026-07-01T07:27:30.615295Z","steps":["trace[2062049391] 'process raft request' (duration: 119.838449ms)","trace[2062049391] 'compare' (duration: 108.306201ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:30.615549Z","caller":"traceutil/trace.go:171","msg":"trace[1906866203] transaction","detail":"{read_only:false; response_revision:4228; number_of_response:1; }","duration":"228.826976ms","start":"2026-07-01T07:27:30.386673Z","end":"2026-07-01T07:27:30.6155Z","steps":["trace[1906866203] 'process raft request' (duration: 228.352435ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.615907Z","caller":"traceutil/trace.go:171","msg":"trace[1389051480] transaction","detail":"{read_only:false; response_revision:4229; number_of_response:1; }","duration":"227.567956ms","start":"2026-07-01T07:27:30.388326Z","end":"2026-07-01T07:27:30.615894Z","steps":["trace[1389051480] 'process raft request' (duration: 226.768507ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.625048Z","caller":"traceutil/trace.go:171","msg":"trace[1680512786] transaction","detail":"{read_only:false; response_revision:4233; number_of_response:1; }","duration":"198.569976ms","start":"2026-07-01T07:27:30.426439Z","end":"2026-07-01T07:27:30.625009Z","steps":["trace[1680512786] 'process raft request' (duration: 198.193677ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.625093Z","caller":"traceutil/trace.go:171","msg":"trace[972651677] transaction","detail":"{read_only:false; response_revision:4230; number_of_response:1; }","duration":"199.075769ms","start":"2026-07-01T07:27:30.425998Z","end":"2026-07-01T07:27:30.625074Z","steps":["trace[972651677] 'process raft request' (duration: 198.325301ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.625116Z","caller":"traceutil/trace.go:171","msg":"trace[1002268614] transaction","detail":"{read_only:false; response_revision:4231; number_of_response:1; }","duration":"198.844473ms","start":"2026-07-01T07:27:30.426245Z","end":"2026-07-01T07:27:30.625089Z","steps":["trace[1002268614] 'process raft request' (duration: 198.242599ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.625337Z","caller":"traceutil/trace.go:171","msg":"trace[1478638631] transaction","detail":"{read_only:false; response_revision:4235; number_of_response:1; }","duration":"198.72201ms","start":"2026-07-01T07:27:30.426606Z","end":"2026-07-01T07:27:30.625328Z","steps":["trace[1478638631] 'process raft request' (duration: 198.085235ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.625456Z","caller":"traceutil/trace.go:171","msg":"trace[199354300] transaction","detail":"{read_only:false; response_revision:4234; number_of_response:1; }","duration":"198.960406ms","start":"2026-07-01T07:27:30.426487Z","end":"2026-07-01T07:27:30.625447Z","steps":["trace[199354300] 'process raft request' (duration: 198.180857ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.625456Z","caller":"traceutil/trace.go:171","msg":"trace[1243322752] transaction","detail":"{read_only:false; response_revision:4232; number_of_response:1; }","duration":"199.12738ms","start":"2026-07-01T07:27:30.426315Z","end":"2026-07-01T07:27:30.625442Z","steps":["trace[1243322752] 'process raft request' (duration: 198.284129ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.625677Z","caller":"traceutil/trace.go:171","msg":"trace[899441403] transaction","detail":"{read_only:false; response_revision:4236; number_of_response:1; }","duration":"198.968436ms","start":"2026-07-01T07:27:30.426697Z","end":"2026-07-01T07:27:30.625665Z","steps":["trace[899441403] 'process raft request' (duration: 198.029764ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.625978Z","caller":"traceutil/trace.go:171","msg":"trace[687932806] transaction","detail":"{read_only:false; response_revision:4237; number_of_response:1; }","duration":"195.317098ms","start":"2026-07-01T07:27:30.430651Z","end":"2026-07-01T07:27:30.625968Z","steps":["trace[687932806] 'process raft request' (duration: 194.101309ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.629135Z","caller":"traceutil/trace.go:171","msg":"trace[465253337] linearizableReadLoop","detail":"{readStateIndex:4408; appliedIndex:4398; }","duration":"201.091867ms","start":"2026-07-01T07:27:30.428016Z","end":"2026-07-01T07:27:30.629108Z","steps":["trace[465253337] 'read index received' (duration: 78.281097ms)","trace[465253337] 'applied index is now lower than readState.Index' (duration: 122.80954ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:30.629624Z","caller":"traceutil/trace.go:171","msg":"trace[1042347246] transaction","detail":"{read_only:false; response_revision:4238; number_of_response:1; }","duration":"197.553192ms","start":"2026-07-01T07:27:30.432052Z","end":"2026-07-01T07:27:30.629605Z","steps":["trace[1042347246] 'process raft request' (duration: 192.737526ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.629931Z","caller":"traceutil/trace.go:171","msg":"trace[1865886254] transaction","detail":"{read_only:false; response_revision:4239; number_of_response:1; }","duration":"197.621344ms","start":"2026-07-01T07:27:30.432298Z","end":"2026-07-01T07:27:30.629919Z","steps":["trace[1865886254] 'process raft request' (duration: 196.576628ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.62994Z","caller":"traceutil/trace.go:171","msg":"trace[835869528] transaction","detail":"{read_only:false; response_revision:4241; number_of_response:1; }","duration":"194.669633ms","start":"2026-07-01T07:27:30.435254Z","end":"2026-07-01T07:27:30.629923Z","steps":["trace[835869528] 'process raft request' (duration: 193.72919ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.630117Z","caller":"traceutil/trace.go:171","msg":"trace[858716799] transaction","detail":"{read_only:false; response_revision:4240; number_of_response:1; }","duration":"196.476706ms","start":"2026-07-01T07:27:30.433633Z","end":"2026-07-01T07:27:30.63011Z","steps":["trace[858716799] 'process raft request' (duration: 195.302838ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.630122Z","caller":"traceutil/trace.go:171","msg":"trace[799813296] transaction","detail":"{read_only:false; response_revision:4242; number_of_response:1; }","duration":"193.971775ms","start":"2026-07-01T07:27:30.436137Z","end":"2026-07-01T07:27:30.630109Z","steps":["trace[799813296] 'process raft request' (duration: 192.91844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.630392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.351298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:30.630468Z","caller":"traceutil/trace.go:171","msg":"trace[1687363348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4242; }","duration":"202.46731ms","start":"2026-07-01T07:27:30.427988Z","end":"2026-07-01T07:27:30.630455Z","steps":["trace[1687363348] 'agreement among raft nodes before linearized reading' (duration: 202.324757ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.680004Z","caller":"traceutil/trace.go:171","msg":"trace[122586250] transaction","detail":"{read_only:false; response_revision:4243; number_of_response:1; }","duration":"139.980954ms","start":"2026-07-01T07:27:30.539995Z","end":"2026-07-01T07:27:30.679976Z","steps":["trace[122586250] 'process raft request' (duration: 139.568334ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.740456Z","caller":"traceutil/trace.go:171","msg":"trace[1338429232] transaction","detail":"{read_only:false; response_revision:4247; number_of_response:1; }","duration":"116.055967ms","start":"2026-07-01T07:27:30.624362Z","end":"2026-07-01T07:27:30.740418Z","steps":["trace[1338429232] 'process raft request' (duration: 115.019712ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.740566Z","caller":"traceutil/trace.go:171","msg":"trace[526525353] transaction","detail":"{read_only:false; response_revision:4249; number_of_response:1; }","duration":"101.820574ms","start":"2026-07-01T07:27:30.638713Z","end":"2026-07-01T07:27:30.740534Z","steps":["trace[526525353] 'process raft request' (duration: 101.717262ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.740654Z","caller":"traceutil/trace.go:171","msg":"trace[1506402377] transaction","detail":"{read_only:false; response_revision:4248; number_of_response:1; }","duration":"102.291575ms","start":"2026-07-01T07:27:30.638334Z","end":"2026-07-01T07:27:30.740625Z","steps":["trace[1506402377] 'process raft request' (duration: 102.038339ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.74087Z","caller":"traceutil/trace.go:171","msg":"trace[1061847792] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4247; }","duration":"110.039343ms","start":"2026-07-01T07:27:30.63076Z","end":"2026-07-01T07:27:30.740799Z","steps":["trace[1061847792] 'process raft request' (duration: 109.543891ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.741006Z","caller":"traceutil/trace.go:171","msg":"trace[1828017214] transaction","detail":"{read_only:false; response_revision:4250; number_of_response:1; }","duration":"101.995538ms","start":"2026-07-01T07:27:30.638997Z","end":"2026-07-01T07:27:30.740992Z","steps":["trace[1828017214] 'process raft request' (duration: 101.493076ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.742548Z","caller":"traceutil/trace.go:171","msg":"trace[373732772] linearizableReadLoop","detail":"{readStateIndex:4419; appliedIndex:4414; }","duration":"113.300581ms","start":"2026-07-01T07:27:30.629221Z","end":"2026-07-01T07:27:30.742521Z","steps":["trace[373732772] 'read index received' (duration: 50.451106ms)","trace[373732772] 'applied index is now lower than readState.Index' (duration: 62.848385ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:30.745067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.273318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/kube-prometheus-stack-kube-state-metrics\" ","response":"range_response_count:1 size:4756"} {"level":"info","ts":"2026-07-01T07:27:30.746926Z","caller":"traceutil/trace.go:171","msg":"trace[2118300237] range","detail":"{range_begin:/registry/deployments/monitoring/kube-prometheus-stack-kube-state-metrics; range_end:; response_count:1; response_revision:4272; }","duration":"124.163073ms","start":"2026-07-01T07:27:30.622741Z","end":"2026-07-01T07:27:30.746904Z","steps":["trace[2118300237] 'agreement among raft nodes before linearized reading' (duration: 122.212436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.745356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.43022ms","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-01T07:27:30.748138Z","caller":"traceutil/trace.go:171","msg":"trace[354403015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4272; }","duration":"278.263658ms","start":"2026-07-01T07:27:30.469858Z","end":"2026-07-01T07:27:30.748121Z","steps":["trace[354403015] 'agreement among raft nodes before linearized reading' (duration: 275.309177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.752359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.185402ms","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-01T07:27:30.752429Z","caller":"traceutil/trace.go:171","msg":"trace[2079669605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4274; }","duration":"104.267504ms","start":"2026-07-01T07:27:30.648144Z","end":"2026-07-01T07:27:30.752412Z","steps":["trace[2079669605] 'agreement among raft nodes before linearized reading' (duration: 104.054908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.752499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.202691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/monitoring/kube-prometheus-stack-prometheus-node-exporter\" ","response":"range_response_count:1 size:8235"} {"level":"info","ts":"2026-07-01T07:27:30.752555Z","caller":"traceutil/trace.go:171","msg":"trace[86823060] range","detail":"{range_begin:/registry/daemonsets/monitoring/kube-prometheus-stack-prometheus-node-exporter; range_end:; response_count:1; response_revision:4274; }","duration":"116.307444ms","start":"2026-07-01T07:27:30.636234Z","end":"2026-07-01T07:27:30.752541Z","steps":["trace[86823060] 'agreement among raft nodes before linearized reading' (duration: 115.7483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.752669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.722985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-4mpf2\" ","response":"range_response_count:1 size:8087"} {"level":"warn","ts":"2026-07-01T07:27:30.752709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.06901ms","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-01T07:27:30.752728Z","caller":"traceutil/trace.go:171","msg":"trace[527008900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4274; }","duration":"114.122311ms","start":"2026-07-01T07:27:30.638599Z","end":"2026-07-01T07:27:30.752722Z","steps":["trace[527008900] 'agreement among raft nodes before linearized reading' (duration: 114.06549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.752759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.091583ms","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-01T07:27:30.752832Z","caller":"traceutil/trace.go:171","msg":"trace[33522180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4274; }","duration":"105.193516ms","start":"2026-07-01T07:27:30.647622Z","end":"2026-07-01T07:27:30.752816Z","steps":["trace[33522180] 'agreement among raft nodes before linearized reading' (duration: 104.9432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.752793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.428457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:30.752916Z","caller":"traceutil/trace.go:171","msg":"trace[1710630259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4274; }","duration":"116.559689ms","start":"2026-07-01T07:27:30.636347Z","end":"2026-07-01T07:27:30.752906Z","steps":["trace[1710630259] 'agreement among raft nodes before linearized reading' (duration: 116.417256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.753103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.156537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:10568"} {"level":"info","ts":"2026-07-01T07:27:30.753166Z","caller":"traceutil/trace.go:171","msg":"trace[377240637] range","detail":"{range_begin:/registry/deployments/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:4274; }","duration":"103.276239ms","start":"2026-07-01T07:27:30.649877Z","end":"2026-07-01T07:27:30.753153Z","steps":["trace[377240637] 'agreement among raft nodes before linearized reading' (duration: 103.000253ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.75325Z","caller":"traceutil/trace.go:171","msg":"trace[1935264000] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-4mpf2; range_end:; response_count:1; response_revision:4274; }","duration":"109.814807ms","start":"2026-07-01T07:27:30.642898Z","end":"2026-07-01T07:27:30.752712Z","steps":["trace[1935264000] 'agreement among raft nodes before linearized reading' (duration: 109.616232ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.934884Z","caller":"traceutil/trace.go:171","msg":"trace[364414858] linearizableReadLoop","detail":"{readStateIndex:4478; appliedIndex:4476; }","duration":"105.210077ms","start":"2026-07-01T07:27:30.829646Z","end":"2026-07-01T07:27:30.934856Z","steps":["trace[364414858] 'read index received' (duration: 20.787191ms)","trace[364414858] 'applied index is now lower than readState.Index' (duration: 84.404015ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:30.93515Z","caller":"traceutil/trace.go:171","msg":"trace[286799017] transaction","detail":"{read_only:false; response_revision:4301; number_of_response:1; }","duration":"153.937251ms","start":"2026-07-01T07:27:30.781197Z","end":"2026-07-01T07:27:30.935134Z","steps":["trace[286799017] 'process raft request' (duration: 69.240839ms)","trace[286799017] 'compare' (duration: 83.886483ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:30.93539Z","caller":"traceutil/trace.go:171","msg":"trace[590192614] transaction","detail":"{read_only:false; response_revision:4302; number_of_response:1; }","duration":"153.460539ms","start":"2026-07-01T07:27:30.781918Z","end":"2026-07-01T07:27:30.935379Z","steps":["trace[590192614] 'process raft request' (duration: 152.835184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.935668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.996895ms","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-01T07:27:30.93571Z","caller":"traceutil/trace.go:171","msg":"trace[1561491254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4302; }","duration":"106.112288ms","start":"2026-07-01T07:27:30.829588Z","end":"2026-07-01T07:27:30.9357Z","steps":["trace[1561491254] 'agreement among raft nodes before linearized reading' (duration: 105.936784ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.937061Z","caller":"traceutil/trace.go:171","msg":"trace[176745157] transaction","detail":"{read_only:false; response_revision:4303; number_of_response:1; }","duration":"102.871399ms","start":"2026-07-01T07:27:30.834172Z","end":"2026-07-01T07:27:30.937043Z","steps":["trace[176745157] 'process raft request' (duration: 102.038849ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:30.937313Z","caller":"traceutil/trace.go:171","msg":"trace[1429642742] transaction","detail":"{read_only:false; response_revision:4304; number_of_response:1; }","duration":"101.062256ms","start":"2026-07-01T07:27:30.836239Z","end":"2026-07-01T07:27:30.937301Z","steps":["trace[1429642742] 'process raft request' (duration: 100.059712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.965785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.5248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-rmk5h\" ","response":"range_response_count:1 size:3858"} {"level":"info","ts":"2026-07-01T07:27:30.965854Z","caller":"traceutil/trace.go:171","msg":"trace[1379837783] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-rmk5h; range_end:; response_count:1; response_revision:4316; }","duration":"131.616782ms","start":"2026-07-01T07:27:30.834219Z","end":"2026-07-01T07:27:30.965836Z","steps":["trace[1379837783] 'agreement among raft nodes before linearized reading' (duration: 131.398547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.965965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.157401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:10878"} {"level":"info","ts":"2026-07-01T07:27:30.96611Z","caller":"traceutil/trace.go:171","msg":"trace[1928116804] range","detail":"{range_begin:/registry/deployments/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:4316; }","duration":"126.292385ms","start":"2026-07-01T07:27:30.839767Z","end":"2026-07-01T07:27:30.966059Z","steps":["trace[1928116804] 'agreement among raft nodes before linearized reading' (duration: 125.981857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:30.966281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.652343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/kube-prometheus-stack-operator\" ","response":"range_response_count:1 size:5382"} {"level":"info","ts":"2026-07-01T07:27:30.966385Z","caller":"traceutil/trace.go:171","msg":"trace[1543772983] range","detail":"{range_begin:/registry/deployments/monitoring/kube-prometheus-stack-operator; range_end:; response_count:1; response_revision:4316; }","duration":"126.792016ms","start":"2026-07-01T07:27:30.839575Z","end":"2026-07-01T07:27:30.966367Z","steps":["trace[1543772983] 'agreement among raft nodes before linearized reading' (duration: 126.424257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:31.483893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.268871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/grafana-tls\" ","response":"range_response_count:1 size:2997"} {"level":"info","ts":"2026-07-01T07:27:31.483992Z","caller":"traceutil/trace.go:171","msg":"trace[562938859] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/grafana-tls; range_end:; response_count:1; response_revision:4384; }","duration":"169.424675ms","start":"2026-07-01T07:27:31.314544Z","end":"2026-07-01T07:27:31.483969Z","steps":["trace[562938859] 'range keys from in-memory index tree' (duration: 169.181218ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:31.484688Z","caller":"traceutil/trace.go:171","msg":"trace[474706683] transaction","detail":"{read_only:false; response_revision:4385; number_of_response:1; }","duration":"167.55768ms","start":"2026-07-01T07:27:31.317072Z","end":"2026-07-01T07:27:31.48463Z","steps":["trace[474706683] 'process raft request' (duration: 126.863041ms)","trace[474706683] 'compare' (duration: 39.91532ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:31.485065Z","caller":"traceutil/trace.go:171","msg":"trace[660651670] linearizableReadLoop","detail":"{readStateIndex:4561; appliedIndex:4560; }","duration":"167.790805ms","start":"2026-07-01T07:27:31.317235Z","end":"2026-07-01T07:27:31.485026Z","steps":["trace[660651670] 'read index received' (duration: 126.855821ms)","trace[660651670] 'applied index is now lower than readState.Index' (duration: 40.931894ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:31.485093Z","caller":"traceutil/trace.go:171","msg":"trace[630735683] transaction","detail":"{read_only:false; response_revision:4386; number_of_response:1; }","duration":"167.677133ms","start":"2026-07-01T07:27:31.317367Z","end":"2026-07-01T07:27:31.485044Z","steps":["trace[630735683] 'process raft request' (duration: 167.14881ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:31.485293Z","caller":"traceutil/trace.go:171","msg":"trace[1488281117] transaction","detail":"{read_only:false; response_revision:4387; number_of_response:1; }","duration":"153.386539ms","start":"2026-07-01T07:27:31.331894Z","end":"2026-07-01T07:27:31.485281Z","steps":["trace[1488281117] 'process raft request' (duration: 152.687212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:31.485314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.043171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:31.485357Z","caller":"traceutil/trace.go:171","msg":"trace[1852615667] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-tls; range_end:; response_count:0; response_revision:4388; }","duration":"168.152114ms","start":"2026-07-01T07:27:31.317189Z","end":"2026-07-01T07:27:31.485341Z","steps":["trace[1852615667] 'agreement among raft nodes before linearized reading' (duration: 168.026231ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:35.460307Z","caller":"traceutil/trace.go:171","msg":"trace[243945754] linearizableReadLoop","detail":"{readStateIndex:4717; appliedIndex:4716; }","duration":"144.683928ms","start":"2026-07-01T07:27:35.315598Z","end":"2026-07-01T07:27:35.460281Z","steps":["trace[243945754] 'read index received' (duration: 144.463392ms)","trace[243945754] 'applied index is now lower than readState.Index' (duration: 219.236µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:35.4604Z","caller":"traceutil/trace.go:171","msg":"trace[1353164281] transaction","detail":"{read_only:false; response_revision:4539; number_of_response:1; }","duration":"147.903024ms","start":"2026-07-01T07:27:35.312471Z","end":"2026-07-01T07:27:35.460374Z","steps":["trace[1353164281] 'process raft request' (duration: 147.608127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:35.46066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.028956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-07-01T07:27:35.460779Z","caller":"traceutil/trace.go:171","msg":"trace[349276425] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4539; }","duration":"145.180009ms","start":"2026-07-01T07:27:35.315571Z","end":"2026-07-01T07:27:35.460751Z","steps":["trace[349276425] 'agreement among raft nodes before linearized reading' (duration: 144.82543ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:35.472466Z","caller":"traceutil/trace.go:171","msg":"trace[1628174351] transaction","detail":"{read_only:false; response_revision:4540; number_of_response:1; }","duration":"107.06616ms","start":"2026-07-01T07:27:35.365381Z","end":"2026-07-01T07:27:35.472448Z","steps":["trace[1628174351] 'process raft request' (duration: 106.708602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:38.56234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.427448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:38.56259Z","caller":"traceutil/trace.go:171","msg":"trace[681294165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4585; }","duration":"133.842797ms","start":"2026-07-01T07:27:38.428612Z","end":"2026-07-01T07:27:38.562455Z","steps":["trace[681294165] 'range keys from in-memory index tree' (duration: 133.322125ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:41.581361Z","caller":"traceutil/trace.go:171","msg":"trace[1875507475] transaction","detail":"{read_only:false; response_revision:4639; number_of_response:1; }","duration":"118.605486ms","start":"2026-07-01T07:27:41.4627Z","end":"2026-07-01T07:27:41.581305Z","steps":["trace[1875507475] 'process raft request' (duration: 117.960511ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:41.859574Z","caller":"traceutil/trace.go:171","msg":"trace[576702002] transaction","detail":"{read_only:false; response_revision:4643; number_of_response:1; }","duration":"109.694309ms","start":"2026-07-01T07:27:41.749838Z","end":"2026-07-01T07:27:41.859533Z","steps":["trace[576702002] 'process raft request' (duration: 106.503873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:42.033886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.805399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-07-01T07:27:42.033983Z","caller":"traceutil/trace.go:171","msg":"trace[978901610] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4646; }","duration":"126.938491ms","start":"2026-07-01T07:27:41.90702Z","end":"2026-07-01T07:27:42.033959Z","steps":["trace[978901610] 'agreement among raft nodes before linearized reading' (duration: 52.288529ms)","trace[978901610] 'range keys from in-memory index tree' (duration: 74.375836ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:42.060998Z","caller":"traceutil/trace.go:171","msg":"trace[1652649898] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"126.646904ms","start":"2026-07-01T07:27:41.934315Z","end":"2026-07-01T07:27:42.060962Z","steps":["trace[1652649898] 'process raft request' (duration: 126.455909ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:48.462034Z","caller":"traceutil/trace.go:171","msg":"trace[1592570654] linearizableReadLoop","detail":"{readStateIndex:4953; appliedIndex:4952; }","duration":"315.679137ms","start":"2026-07-01T07:27:48.146324Z","end":"2026-07-01T07:27:48.462003Z","steps":["trace[1592570654] 'read index received' (duration: 315.407771ms)","trace[1592570654] 'applied index is now lower than readState.Index' (duration: 269.806µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:48.462175Z","caller":"traceutil/trace.go:171","msg":"trace[306324468] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"380.59461ms","start":"2026-07-01T07:27:48.081531Z","end":"2026-07-01T07:27:48.462125Z","steps":["trace[306324468] 'process raft request' (duration: 380.270272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:48.462412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:48.081492Z","time spent":"380.800514ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:27:48.462548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.927673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T07:27:48.4627Z","caller":"traceutil/trace.go:171","msg":"trace[1224040048] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:4766; }","duration":"316.419234ms","start":"2026-07-01T07:27:48.146254Z","end":"2026-07-01T07:27:48.462674Z","steps":["trace[1224040048] 'agreement among raft nodes before linearized reading' (duration: 315.881982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:48.462774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:48.146231Z","time spent":"316.525226ms","remote":"127.0.0.1:57390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-07-01T07:27:48.46317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.818127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-01T07:27:48.463251Z","caller":"traceutil/trace.go:171","msg":"trace[62704388] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4766; }","duration":"114.902969ms","start":"2026-07-01T07:27:48.34833Z","end":"2026-07-01T07:27:48.463233Z","steps":["trace[62704388] 'agreement among raft nodes before linearized reading' (duration: 114.028539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:49.294013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.91284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:27:49.294124Z","caller":"traceutil/trace.go:171","msg":"trace[1312652882] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4955; }","duration":"397.430745ms","start":"2026-07-01T07:27:48.896675Z","end":"2026-07-01T07:27:49.294106Z","steps":["trace[1312652882] 'read index received' (duration: 90.29281ms)","trace[1312652882] 'applied index is now lower than readState.Index' (duration: 307.136575ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:49.29442Z","caller":"traceutil/trace.go:171","msg":"trace[1595493640] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"536.179057ms","start":"2026-07-01T07:27:48.758212Z","end":"2026-07-01T07:27:49.294391Z","steps":["trace[1595493640] 'process raft request' (duration: 228.796046ms)","trace[1595493640] 'compare' (duration: 306.601023ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:49.294517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:48.758197Z","time spent":"536.251478ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:27:49.294828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.162852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f6ca53cd-7b04-4eb7-8f1f-b62f97874ea7\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-07-01T07:27:49.294856Z","caller":"traceutil/trace.go:171","msg":"trace[456850252] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f6ca53cd-7b04-4eb7-8f1f-b62f97874ea7; range_end:; response_count:1; response_revision:4769; }","duration":"398.249434ms","start":"2026-07-01T07:27:48.896598Z","end":"2026-07-01T07:27:49.294848Z","steps":["trace[456850252] 'agreement among raft nodes before linearized reading' (duration: 398.119731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:49.294877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:48.89657Z","time spent":"398.302685ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f6ca53cd-7b04-4eb7-8f1f-b62f97874ea7\" "} {"level":"warn","ts":"2026-07-01T07:27:49.295175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.629993ms","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-01T07:27:49.295201Z","caller":"traceutil/trace.go:171","msg":"trace[1408576125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4769; }","duration":"325.701924ms","start":"2026-07-01T07:27:48.969492Z","end":"2026-07-01T07:27:49.295194Z","steps":["trace[1408576125] 'agreement among raft nodes before linearized reading' (duration: 325.565811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:49.295216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:48.969475Z","time spent":"325.737266ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T07:27:49.295219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.687463ms","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-01T07:27:49.29524Z","caller":"traceutil/trace.go:171","msg":"trace[945450488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4769; }","duration":"112.750114ms","start":"2026-07-01T07:27:49.182484Z","end":"2026-07-01T07:27:49.295234Z","steps":["trace[945450488] 'agreement among raft nodes before linearized reading' (duration: 112.686463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:49.29537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.994301ms","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-01T07:27:49.295511Z","caller":"traceutil/trace.go:171","msg":"trace[1834193125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4769; }","duration":"326.076044ms","start":"2026-07-01T07:27:48.969309Z","end":"2026-07-01T07:27:49.295385Z","steps":["trace[1834193125] 'agreement among raft nodes before linearized reading' (duration: 325.996312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:49.295546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:48.969281Z","time spent":"326.256947ms","remote":"127.0.0.1:57284","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-01T07:27:49.29554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.577713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18be19cc18d0aae1\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-07-01T07:27:49.295588Z","caller":"traceutil/trace.go:171","msg":"trace[1228684169] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18be19cc18d0aae1; range_end:; response_count:1; response_revision:4769; }","duration":"129.724846ms","start":"2026-07-01T07:27:49.165855Z","end":"2026-07-01T07:27:49.29558Z","steps":["trace[1228684169] 'agreement among raft nodes before linearized reading' (duration: 129.556652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:49.295806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.903119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-01T07:27:49.295881Z","caller":"traceutil/trace.go:171","msg":"trace[1543365282] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4769; }","duration":"328.999691ms","start":"2026-07-01T07:27:48.966863Z","end":"2026-07-01T07:27:49.295862Z","steps":["trace[1543365282] 'agreement among raft nodes before linearized reading' (duration: 328.703254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:49.295916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:48.966848Z","time spent":"329.062413ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-01T07:27:49.295708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.162135ms","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-01T07:27:49.296038Z","caller":"traceutil/trace.go:171","msg":"trace[34088110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4769; }","duration":"326.530174ms","start":"2026-07-01T07:27:48.969484Z","end":"2026-07-01T07:27:49.296014Z","steps":["trace[34088110] 'agreement among raft nodes before linearized reading' (duration: 325.973741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:49.296088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:48.969477Z","time spent":"326.600776ms","remote":"127.0.0.1:57284","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":"warn","ts":"2026-07-01T07:27:49.29673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.379154ms","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-01T07:27:49.296764Z","caller":"traceutil/trace.go:171","msg":"trace[1383193228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4769; }","duration":"346.437354ms","start":"2026-07-01T07:27:48.950318Z","end":"2026-07-01T07:27:49.296755Z","steps":["trace[1383193228] 'agreement among raft nodes before linearized reading' (duration: 346.344742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:49.296783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:48.950306Z","time spent":"346.472166ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T07:27:50.564755Z","caller":"traceutil/trace.go:171","msg":"trace[1069673588] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"115.414571ms","start":"2026-07-01T07:27:50.44931Z","end":"2026-07-01T07:27:50.564724Z","steps":["trace[1069673588] 'process raft request' (duration: 108.611994ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:50.564941Z","caller":"traceutil/trace.go:171","msg":"trace[2113033229] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"114.375636ms","start":"2026-07-01T07:27:50.45055Z","end":"2026-07-01T07:27:50.564926Z","steps":["trace[2113033229] 'process raft request' (duration: 113.885665ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:51.72205Z","caller":"traceutil/trace.go:171","msg":"trace[198538794] linearizableReadLoop","detail":"{readStateIndex:5004; appliedIndex:5003; }","duration":"180.43858ms","start":"2026-07-01T07:27:51.541571Z","end":"2026-07-01T07:27:51.72201Z","steps":["trace[198538794] 'read index received' (duration: 136.836378ms)","trace[198538794] 'applied index is now lower than readState.Index' (duration: 43.599792ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:51.722101Z","caller":"traceutil/trace.go:171","msg":"trace[1505303178] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"188.402793ms","start":"2026-07-01T07:27:51.533668Z","end":"2026-07-01T07:27:51.722071Z","steps":["trace[1505303178] 'process raft request' (duration: 144.79716ms)","trace[1505303178] 'compare' (duration: 43.266975ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:51.72247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.87077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-bb001077-ed51-4fbe-9e85-8f74eed922e5\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-07-01T07:27:51.722515Z","caller":"traceutil/trace.go:171","msg":"trace[712817657] range","detail":"{range_begin:/registry/persistentvolumes/pvc-bb001077-ed51-4fbe-9e85-8f74eed922e5; range_end:; response_count:1; response_revision:4817; }","duration":"180.960262ms","start":"2026-07-01T07:27:51.541543Z","end":"2026-07-01T07:27:51.722503Z","steps":["trace[712817657] 'agreement among raft nodes before linearized reading' (duration: 180.761717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:51.723005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.791934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-07-01T07:27:51.723037Z","caller":"traceutil/trace.go:171","msg":"trace[913090799] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4817; }","duration":"132.873096ms","start":"2026-07-01T07:27:51.590155Z","end":"2026-07-01T07:27:51.723028Z","steps":["trace[913090799] 'agreement among raft nodes before linearized reading' (duration: 132.786304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:03.052548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870448862385749,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T07:28:03.265627Z","caller":"traceutil/trace.go:171","msg":"trace[766937467] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"714.400174ms","start":"2026-07-01T07:28:02.551173Z","end":"2026-07-01T07:28:03.265573Z","steps":["trace[766937467] 'process raft request' (duration: 714.140118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:03.266108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:02.551154Z","time spent":"714.612439ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:28:03.308291Z","caller":"traceutil/trace.go:171","msg":"trace[1674031494] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"731.551798ms","start":"2026-07-01T07:28:02.57671Z","end":"2026-07-01T07:28:03.308262Z","steps":["trace[1674031494] 'process raft request' (duration: 731.356724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:03.308496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:02.57668Z","time spent":"731.716172ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:28:03.308732Z","caller":"traceutil/trace.go:171","msg":"trace[950562455] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"447.987071ms","start":"2026-07-01T07:28:02.860706Z","end":"2026-07-01T07:28:03.308693Z","steps":["trace[950562455] 'process raft request' (duration: 447.761806ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:03.308699Z","caller":"traceutil/trace.go:171","msg":"trace[2133089995] linearizableReadLoop","detail":"{readStateIndex:5142; appliedIndex:5138; }","duration":"757.188914ms","start":"2026-07-01T07:28:02.551477Z","end":"2026-07-01T07:28:03.308666Z","steps":["trace[2133089995] 'read index received' (duration: 713.895312ms)","trace[2133089995] 'applied index is now lower than readState.Index' (duration: 43.292062ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:03.309106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:02.86067Z","time spent":"448.191446ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:28:03.309065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.506072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-01T07:28:03.309288Z","caller":"traceutil/trace.go:171","msg":"trace[2098644872] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"694.077585ms","start":"2026-07-01T07:28:02.615167Z","end":"2026-07-01T07:28:03.309244Z","steps":["trace[2098644872] 'process raft request' (duration: 693.053552ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:03.309377Z","caller":"traceutil/trace.go:171","msg":"trace[664300635] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4948; }","duration":"757.87775ms","start":"2026-07-01T07:28:02.551458Z","end":"2026-07-01T07:28:03.309336Z","steps":["trace[664300635] 'agreement among raft nodes before linearized reading' (duration: 757.317638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:03.309741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:02.551449Z","time spent":"758.201117ms","remote":"127.0.0.1:57174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-01T07:28:03.309901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:02.615149Z","time spent":"694.24338ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:28:03.419354Z","caller":"traceutil/trace.go:171","msg":"trace[1727071031] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"144.79546ms","start":"2026-07-01T07:28:03.274517Z","end":"2026-07-01T07:28:03.419312Z","steps":["trace[1727071031] 'process raft request' (duration: 144.709118ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:03.41939Z","caller":"traceutil/trace.go:171","msg":"trace[673986588] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"144.79342ms","start":"2026-07-01T07:28:03.274517Z","end":"2026-07-01T07:28:03.41931Z","steps":["trace[673986588] 'process raft request' (duration: 144.653797ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:03.419702Z","caller":"traceutil/trace.go:171","msg":"trace[1147638456] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"145.170528ms","start":"2026-07-01T07:28:03.274509Z","end":"2026-07-01T07:28:03.419679Z","steps":["trace[1147638456] 'process raft request' (duration: 70.000651ms)","trace[1147638456] 'compare' (duration: 74.443691ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:03.419843Z","caller":"traceutil/trace.go:171","msg":"trace[1288109419] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"144.666027ms","start":"2026-07-01T07:28:03.275145Z","end":"2026-07-01T07:28:03.419811Z","steps":["trace[1288109419] 'process raft request' (duration: 144.132345ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:03.420127Z","caller":"traceutil/trace.go:171","msg":"trace[1015687421] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5142; }","duration":"111.130575ms","start":"2026-07-01T07:28:03.308987Z","end":"2026-07-01T07:28:03.420118Z","steps":["trace[1015687421] 'read index received' (duration: 35.577099ms)","trace[1015687421] 'applied index is now lower than readState.Index' (duration: 75.552506ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:03.420311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.814222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2102"} {"level":"info","ts":"2026-07-01T07:28:03.420347Z","caller":"traceutil/trace.go:171","msg":"trace[623430533] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4953; }","duration":"748.927354ms","start":"2026-07-01T07:28:02.67141Z","end":"2026-07-01T07:28:03.420338Z","steps":["trace[623430533] 'agreement among raft nodes before linearized reading' (duration: 748.76458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:03.420371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:02.67137Z","time spent":"748.993926ms","remote":"127.0.0.1:44530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":2124,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"warn","ts":"2026-07-01T07:28:03.420456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.959511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f6ca53cd-7b04-4eb7-8f1f-b62f97874ea7\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-01T07:28:03.420539Z","caller":"traceutil/trace.go:171","msg":"trace[759361381] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f6ca53cd-7b04-4eb7-8f1f-b62f97874ea7; range_end:; response_count:1; response_revision:4953; }","duration":"107.103974ms","start":"2026-07-01T07:28:03.313417Z","end":"2026-07-01T07:28:03.420521Z","steps":["trace[759361381] 'agreement among raft nodes before linearized reading' (duration: 106.790797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:03.669163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.294124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:28:03.669833Z","caller":"traceutil/trace.go:171","msg":"trace[1361662725] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"147.942401ms","start":"2026-07-01T07:28:03.521862Z","end":"2026-07-01T07:28:03.669805Z","steps":["trace[1361662725] 'process raft request' (duration: 147.42956ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:03.66994Z","caller":"traceutil/trace.go:171","msg":"trace[1233858366] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"148.216726ms","start":"2026-07-01T07:28:03.521685Z","end":"2026-07-01T07:28:03.669902Z","steps":["trace[1233858366] 'compare' (duration: 138.150991ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:27.921354Z","caller":"traceutil/trace.go:171","msg":"trace[2049524276] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"102.940402ms","start":"2026-07-01T07:28:27.818375Z","end":"2026-07-01T07:28:27.921315Z","steps":["trace[2049524276] 'process raft request' (duration: 102.776138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:40.04496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.034075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:28:40.045375Z","caller":"traceutil/trace.go:171","msg":"trace[603008648] linearizableReadLoop","detail":"{readStateIndex:5491; appliedIndex:5490; }","duration":"271.409435ms","start":"2026-07-01T07:28:39.773946Z","end":"2026-07-01T07:28:40.045356Z","steps":["trace[603008648] 'read index received' (duration: 11.846389ms)","trace[603008648] 'applied index is now lower than readState.Index' (duration: 259.562406ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:40.045385Z","caller":"traceutil/trace.go:171","msg":"trace[536800542] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"273.216733ms","start":"2026-07-01T07:28:39.772143Z","end":"2026-07-01T07:28:40.04536Z","steps":["trace[536800542] 'process raft request' (duration: 13.686288ms)","trace[536800542] 'compare' (duration: 258.855111ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:40.045514Z","caller":"traceutil/trace.go:171","msg":"trace[481714633] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"270.580568ms","start":"2026-07-01T07:28:39.774906Z","end":"2026-07-01T07:28:40.045487Z","steps":["trace[481714633] 'process raft request' (duration: 270.18229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:40.045575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.62374ms","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-01T07:28:40.045597Z","caller":"traceutil/trace.go:171","msg":"trace[1350137764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5290; }","duration":"271.658571ms","start":"2026-07-01T07:28:39.773931Z","end":"2026-07-01T07:28:40.045589Z","steps":["trace[1350137764] 'agreement among raft nodes before linearized reading' (duration: 271.517808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:40.04574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.422342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:28:40.045766Z","caller":"traceutil/trace.go:171","msg":"trace[1462273821] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5290; }","duration":"258.520284ms","start":"2026-07-01T07:28:39.787239Z","end":"2026-07-01T07:28:40.045759Z","steps":["trace[1462273821] 'agreement among raft nodes before linearized reading' (duration: 258.457982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:40.045837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.264358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:28:40.045862Z","caller":"traceutil/trace.go:171","msg":"trace[1860774258] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5290; }","duration":"258.30101ms","start":"2026-07-01T07:28:39.787554Z","end":"2026-07-01T07:28:40.045855Z","steps":["trace[1860774258] 'agreement among raft nodes before linearized reading' (duration: 258.260949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:40.045923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.142737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-07-01T07:28:40.046024Z","caller":"traceutil/trace.go:171","msg":"trace[124094781] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5290; }","duration":"249.365881ms","start":"2026-07-01T07:28:39.796635Z","end":"2026-07-01T07:28:40.046001Z","steps":["trace[124094781] 'agreement among raft nodes before linearized reading' (duration: 249.089195ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:40.281778Z","caller":"traceutil/trace.go:171","msg":"trace[893420848] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"229.66699ms","start":"2026-07-01T07:28:40.052083Z","end":"2026-07-01T07:28:40.28175Z","steps":["trace[893420848] 'process raft request' (duration: 226.966833ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:40.282161Z","caller":"traceutil/trace.go:171","msg":"trace[1373403704] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"229.879244ms","start":"2026-07-01T07:28:40.052273Z","end":"2026-07-01T07:28:40.282152Z","steps":["trace[1373403704] 'process raft request' (duration: 229.433145ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:01.78117Z","caller":"traceutil/trace.go:171","msg":"trace[529688743] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"129.225956ms","start":"2026-07-01T07:29:01.651913Z","end":"2026-07-01T07:29:01.781139Z","steps":["trace[529688743] 'process raft request' (duration: 129.019981ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:01.781334Z","caller":"traceutil/trace.go:171","msg":"trace[1387224565] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"127.761546ms","start":"2026-07-01T07:29:01.653535Z","end":"2026-07-01T07:29:01.781297Z","steps":["trace[1387224565] 'process raft request' (duration: 127.559172ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:09.260398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2862} {"level":"info","ts":"2026-07-01T07:29:09.350544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2862,"took":"88.307661ms","hash":1086697952,"current-db-size-bytes":53698560,"current-db-size":"54 MB","current-db-size-in-use-bytes":28712960,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-01T07:29:09.350659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1086697952,"revision":2862,"compact-revision":-1} {"level":"info","ts":"2026-07-01T07:34:09.265669Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5499} {"level":"info","ts":"2026-07-01T07:34:09.344392Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5499,"took":"77.101376ms","hash":719475332,"current-db-size-bytes":53698560,"current-db-size":"54 MB","current-db-size-in-use-bytes":18296832,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-01T07:34:09.344457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":719475332,"revision":5499,"compact-revision":2862}