{"level":"warn","ts":"2026-06-30T17:46:57.613072Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-30T17:46:57.613152Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-06-30T17:46:57.613208Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-30T17:46:57.613216Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-06-30T17:46:57.613231Z","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-06-30T17:46:57.61409Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-06-30T17:46:57.614164Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-06-30T17:46:57.618751Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.358402ms"} {"level":"info","ts":"2026-06-30T17:46:57.624669Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-06-30T17:46:57.624755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-06-30T17:46:57.62478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-06-30T17:46:57.624793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-30T17:46:57.624804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-06-30T17:46:57.624881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-06-30T17:46:57.632093Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-30T17:46:57.633227Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-30T17:46:57.634366Z","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-06-30T17:46:57.636052Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-30T17:46:57.636541Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-30T17:46:57.636733Z","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-06-30T17:46:57.636791Z","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-06-30T17:46:57.636854Z","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-06-30T17:46:57.638986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-06-30T17:46:57.639513Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-06-30T17:46:57.640937Z","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-06-30T17:46:57.641072Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-06-30T17:46:57.641102Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-06-30T17:46:57.64186Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-30T17:46:57.641908Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-30T17:46:57.725341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-06-30T17:46:57.72543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-30T17:46:57.725489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-06-30T17:46:57.725537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-06-30T17:46:57.725575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-06-30T17:46:57.72562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-06-30T17:46:57.725662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-06-30T17:46:57.726732Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-06-30T17:46:57.726817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T17:46:57.72678Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:46:57.727448Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T17:46:57.727943Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:46:57.728076Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:46:57.728117Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T17:46:57.730387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-06-30T17:46:57.730553Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-30T17:46:57.730613Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-30T17:46:57.73087Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-30T17:47:29.01492Z","caller":"traceutil/trace.go:171","msg":"trace[1590453452] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"151.245499ms","start":"2026-06-30T17:47:28.863648Z","end":"2026-06-30T17:47:29.014894Z","steps":["trace[1590453452] 'process raft request' (duration: 151.096451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:31.168424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.054653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-06-30T17:47:31.168539Z","caller":"traceutil/trace.go:171","msg":"trace[41634669] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:437; }","duration":"133.237403ms","start":"2026-06-30T17:47:31.035272Z","end":"2026-06-30T17:47:31.16851Z","steps":["trace[41634669] 'agreement among raft nodes before linearized reading' (duration: 64.4633ms)","trace[41634669] 'range keys from in-memory index tree' (duration: 68.418553ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:47:33.392671Z","caller":"traceutil/trace.go:171","msg":"trace[1096390939] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"235.908281ms","start":"2026-06-30T17:47:33.15673Z","end":"2026-06-30T17:47:33.392638Z","steps":["trace[1096390939] 'process raft request' (duration: 200.838157ms)","trace[1096390939] 'compare' (duration: 34.906825ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:47:33.394571Z","caller":"traceutil/trace.go:171","msg":"trace[1018562427] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"227.498506ms","start":"2026-06-30T17:47:33.167035Z","end":"2026-06-30T17:47:33.394534Z","steps":["trace[1018562427] 'process raft request' (duration: 227.411651ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:33.394664Z","caller":"traceutil/trace.go:171","msg":"trace[1492718308] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"227.874457ms","start":"2026-06-30T17:47:33.166762Z","end":"2026-06-30T17:47:33.394637Z","steps":["trace[1492718308] 'process raft request' (duration: 227.58083ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:33.394794Z","caller":"traceutil/trace.go:171","msg":"trace[1851694796] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"231.505352ms","start":"2026-06-30T17:47:33.163276Z","end":"2026-06-30T17:47:33.394781Z","steps":["trace[1851694796] 'process raft request' (duration: 230.780941ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:33.394904Z","caller":"traceutil/trace.go:171","msg":"trace[1296798529] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"223.754214ms","start":"2026-06-30T17:47:33.17114Z","end":"2026-06-30T17:47:33.394894Z","steps":["trace[1296798529] 'process raft request' (duration: 223.356012ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:33.394888Z","caller":"traceutil/trace.go:171","msg":"trace[2142624892] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"227.783482ms","start":"2026-06-30T17:47:33.166995Z","end":"2026-06-30T17:47:33.394778Z","steps":["trace[2142624892] 'process raft request' (duration: 227.411741ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:33.395219Z","caller":"traceutil/trace.go:171","msg":"trace[1404154380] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"219.910446ms","start":"2026-06-30T17:47:33.175277Z","end":"2026-06-30T17:47:33.395188Z","steps":["trace[1404154380] 'process raft request' (duration: 219.719895ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:33.395087Z","caller":"traceutil/trace.go:171","msg":"trace[879847567] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"223.743513ms","start":"2026-06-30T17:47:33.171267Z","end":"2026-06-30T17:47:33.395011Z","steps":["trace[879847567] 'process raft request' (duration: 223.376602ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:33.395163Z","caller":"traceutil/trace.go:171","msg":"trace[871845015] transaction","detail":"{read_only:false; number_of_response:1; response_revision:491; }","duration":"223.816378ms","start":"2026-06-30T17:47:33.171332Z","end":"2026-06-30T17:47:33.395148Z","steps":["trace[871845015] 'process raft request' (duration: 223.445297ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:33.395446Z","caller":"traceutil/trace.go:171","msg":"trace[648693227] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"219.378496ms","start":"2026-06-30T17:47:33.176055Z","end":"2026-06-30T17:47:33.395433Z","steps":["trace[648693227] 'process raft request' (duration: 219.116601ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:33.395545Z","caller":"traceutil/trace.go:171","msg":"trace[1718044162] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"219.580877ms","start":"2026-06-30T17:47:33.17595Z","end":"2026-06-30T17:47:33.39553Z","steps":["trace[1718044162] 'process raft request' (duration: 219.117651ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:33.395576Z","caller":"traceutil/trace.go:171","msg":"trace[1264244964] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"219.43435ms","start":"2026-06-30T17:47:33.176125Z","end":"2026-06-30T17:47:33.395559Z","steps":["trace[1264244964] 'process raft request' (duration: 219.112392ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:33.395634Z","caller":"traceutil/trace.go:171","msg":"trace[31635521] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"211.023284ms","start":"2026-06-30T17:47:33.184601Z","end":"2026-06-30T17:47:33.395625Z","steps":["trace[31635521] 'process raft request' (duration: 210.819222ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:33.395649Z","caller":"traceutil/trace.go:171","msg":"trace[176158303] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:502; }","duration":"220.492719ms","start":"2026-06-30T17:47:33.17512Z","end":"2026-06-30T17:47:33.395613Z","steps":["trace[176158303] 'read index received' (duration: 182.327901ms)","trace[176158303] 'applied index is now lower than readState.Index' (duration: 38.163598ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:47:33.395714Z","caller":"traceutil/trace.go:171","msg":"trace[1260557827] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"211.978598ms","start":"2026-06-30T17:47:33.183727Z","end":"2026-06-30T17:47:33.395706Z","steps":["trace[1260557827] 'process raft request' (duration: 211.627768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:33.395855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.702482ms","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-06-30T17:47:33.395899Z","caller":"traceutil/trace.go:171","msg":"trace[771044840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:498; }","duration":"220.805957ms","start":"2026-06-30T17:47:33.175082Z","end":"2026-06-30T17:47:33.395888Z","steps":["trace[771044840] 'agreement among raft nodes before linearized reading' (duration: 220.667069ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:37.49413Z","caller":"traceutil/trace.go:171","msg":"trace[168453987] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"121.282741ms","start":"2026-06-30T17:47:37.372816Z","end":"2026-06-30T17:47:37.494099Z","steps":["trace[168453987] 'process raft request' (duration: 118.061918ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:38.897988Z","caller":"traceutil/trace.go:171","msg":"trace[1880804923] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"238.861816ms","start":"2026-06-30T17:47:38.659098Z","end":"2026-06-30T17:47:38.89796Z","steps":["trace[1880804923] 'process raft request' (duration: 196.764193ms)","trace[1880804923] 'compare' (duration: 41.971936ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:47:39.425833Z","caller":"traceutil/trace.go:171","msg":"trace[1483018601] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"228.569311ms","start":"2026-06-30T17:47:39.197244Z","end":"2026-06-30T17:47:39.425814Z","steps":["trace[1483018601] 'process raft request' (duration: 228.420242ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:47:39.933634Z","caller":"traceutil/trace.go:171","msg":"trace[1614963028] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"339.251442ms","start":"2026-06-30T17:47:39.594358Z","end":"2026-06-30T17:47:39.933609Z","steps":["trace[1614963028] 'read index received' (duration: 338.458586ms)","trace[1614963028] 'applied index is now lower than readState.Index' (duration: 791.555µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:47:39.933777Z","caller":"traceutil/trace.go:171","msg":"trace[204470716] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"619.615703ms","start":"2026-06-30T17:47:39.314131Z","end":"2026-06-30T17:47:39.933746Z","steps":["trace[204470716] 'process raft request' (duration: 618.881511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:39.933895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.522236ms","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-06-30T17:47:39.93539Z","caller":"traceutil/trace.go:171","msg":"trace[1141607140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"341.026272ms","start":"2026-06-30T17:47:39.594342Z","end":"2026-06-30T17:47:39.935368Z","steps":["trace[1141607140] 'agreement among raft nodes before linearized reading' (duration: 339.41173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:39.935505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:47:39.594282Z","time spent":"341.15959ms","remote":"127.0.0.1:42786","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-06-30T17:47:39.934067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.488291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:47:39.935843Z","caller":"traceutil/trace.go:171","msg":"trace[1475813666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"307.326715ms","start":"2026-06-30T17:47:39.628495Z","end":"2026-06-30T17:47:39.935821Z","steps":["trace[1475813666] 'agreement among raft nodes before linearized reading' (duration: 305.458478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:39.935914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:47:39.628473Z","time spent":"307.423621ms","remote":"127.0.0.1:42560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T17:47:39.934195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.809124ms","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-06-30T17:47:39.936111Z","caller":"traceutil/trace.go:171","msg":"trace[1812083081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"195.715053ms","start":"2026-06-30T17:47:39.740332Z","end":"2026-06-30T17:47:39.936047Z","steps":["trace[1812083081] 'agreement among raft nodes before linearized reading' (duration: 193.619353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:39.936385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:47:39.31411Z","time spent":"620.78017ms","remote":"127.0.0.1:42624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:47:40.267592Z","caller":"traceutil/trace.go:171","msg":"trace[290666619] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"327.060309ms","start":"2026-06-30T17:47:39.940505Z","end":"2026-06-30T17:47:40.267566Z","steps":["trace[290666619] 'process raft request' (duration: 325.742213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:40.26811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:47:39.940477Z","time spent":"327.189277ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:47:40.341006Z","caller":"traceutil/trace.go:171","msg":"trace[308236630] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:600; }","duration":"331.34197ms","start":"2026-06-30T17:47:40.009642Z","end":"2026-06-30T17:47:40.340983Z","steps":["trace[308236630] 'read index received' (duration: 256.586578ms)","trace[308236630] 'applied index is now lower than readState.Index' (duration: 74.754472ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:47:40.341228Z","caller":"traceutil/trace.go:171","msg":"trace[414644806] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"338.983928ms","start":"2026-06-30T17:47:40.00221Z","end":"2026-06-30T17:47:40.341194Z","steps":["trace[414644806] 'process raft request' (duration: 338.726084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:40.341385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:47:40.002187Z","time spent":"339.126646ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-30T17:47:40.341049Z","caller":"traceutil/trace.go:171","msg":"trace[1369893180] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"399.978936ms","start":"2026-06-30T17:47:39.941035Z","end":"2026-06-30T17:47:40.341014Z","steps":["trace[1369893180] 'process raft request' (duration: 399.739822ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:40.342265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.605512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k5rn2\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-06-30T17:47:40.342946Z","caller":"traceutil/trace.go:171","msg":"trace[1117648962] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k5rn2; range_end:; response_count:1; response_revision:583; }","duration":"333.321624ms","start":"2026-06-30T17:47:40.009608Z","end":"2026-06-30T17:47:40.342929Z","steps":["trace[1117648962] 'agreement among raft nodes before linearized reading' (duration: 331.597455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:40.343131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:47:40.009592Z","time spent":"333.521964ms","remote":"127.0.0.1:42724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-k5rn2\" "} {"level":"warn","ts":"2026-06-30T17:47:40.342042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:47:39.941024Z","time spent":"400.842306ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:47:40.875259Z","caller":"traceutil/trace.go:171","msg":"trace[468912783] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"516.7402ms","start":"2026-06-30T17:47:40.358488Z","end":"2026-06-30T17:47:40.875229Z","steps":["trace[468912783] 'process raft request' (duration: 516.516437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:40.87542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:47:40.35847Z","time spent":"516.871548ms","remote":"127.0.0.1:42724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:47:41.213539Z","caller":"traceutil/trace.go:171","msg":"trace[400379200] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"747.930862ms","start":"2026-06-30T17:47:40.465564Z","end":"2026-06-30T17:47:41.213495Z","steps":["trace[400379200] 'process raft request' (duration: 746.642178ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:41.214023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:47:40.465536Z","time spent":"748.120493ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:47:41.219696Z","caller":"traceutil/trace.go:171","msg":"trace[428656224] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"456.194612ms","start":"2026-06-30T17:47:40.763474Z","end":"2026-06-30T17:47:41.219669Z","steps":["trace[428656224] 'process raft request' (duration: 451.570847ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:41.219802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.013834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:47:41.219844Z","caller":"traceutil/trace.go:171","msg":"trace[693271922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"591.097759ms","start":"2026-06-30T17:47:40.628736Z","end":"2026-06-30T17:47:41.219834Z","steps":["trace[693271922] 'agreement among raft nodes before linearized reading' (duration: 591.021975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:47:41.219847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:47:40.763433Z","time spent":"456.33668ms","remote":"127.0.0.1:42624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:47:41.219871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:47:40.628714Z","time spent":"591.152342ms","remote":"127.0.0.1:42560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-30T17:47:41.220138Z","caller":"traceutil/trace.go:171","msg":"trace[2124567957] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"590.794252ms","start":"2026-06-30T17:47:40.62878Z","end":"2026-06-30T17:47:41.219574Z","steps":["trace[2124567957] 'read index received' (duration: 246.390494ms)","trace[2124567957] 'applied index is now lower than readState.Index' (duration: 344.402397ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:47:41.22049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.741449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-06-30T17:47:41.220555Z","caller":"traceutil/trace.go:171","msg":"trace[246166115] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:587; }","duration":"184.811663ms","start":"2026-06-30T17:47:41.035729Z","end":"2026-06-30T17:47:41.22054Z","steps":["trace[246166115] 'agreement among raft nodes before linearized reading' (duration: 184.677795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:48:06.642654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.721319ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-30T17:48:06.642756Z","caller":"traceutil/trace.go:171","msg":"trace[578083191] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:804; }","duration":"258.889274ms","start":"2026-06-30T17:48:06.38385Z","end":"2026-06-30T17:48:06.64274Z","steps":["trace[578083191] 'read index received' (duration: 141.830829ms)","trace[578083191] 'applied index is now lower than readState.Index' (duration: 117.056775ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:48:06.643032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.477206ms","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-06-30T17:48:06.64307Z","caller":"traceutil/trace.go:171","msg":"trace[1363715009] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"190.561125ms","start":"2026-06-30T17:48:06.452498Z","end":"2026-06-30T17:48:06.643059Z","steps":["trace[1363715009] 'agreement among raft nodes before linearized reading' (duration: 190.425166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:48:06.643102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.237411ms","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-06-30T17:48:06.643193Z","caller":"traceutil/trace.go:171","msg":"trace[382751249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"259.368419ms","start":"2026-06-30T17:48:06.383793Z","end":"2026-06-30T17:48:06.643162Z","steps":["trace[382751249] 'agreement among raft nodes before linearized reading' (duration: 259.124551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:48:06.643599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.182031ms","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-06-30T17:48:06.643722Z","caller":"traceutil/trace.go:171","msg":"trace[340279511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"170.360379ms","start":"2026-06-30T17:48:06.473343Z","end":"2026-06-30T17:48:06.643704Z","steps":["trace[340279511] 'agreement among raft nodes before linearized reading' (duration: 170.029713ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:48:38.317098Z","caller":"traceutil/trace.go:171","msg":"trace[1152212526] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"519.946189ms","start":"2026-06-30T17:48:37.797122Z","end":"2026-06-30T17:48:38.317068Z","steps":["trace[1152212526] 'process raft request' (duration: 519.651985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:48:38.317304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:48:37.797094Z","time spent":"520.097231ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:48:39.06074Z","caller":"traceutil/trace.go:171","msg":"trace[1720413560] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1219; }","duration":"136.672261ms","start":"2026-06-30T17:48:38.924033Z","end":"2026-06-30T17:48:39.060705Z","steps":["trace[1720413560] 'read index received' (duration: 136.421477ms)","trace[1720413560] 'applied index is now lower than readState.Index' (duration: 250.164µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:48:39.060871Z","caller":"traceutil/trace.go:171","msg":"trace[35063469] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"234.853527ms","start":"2026-06-30T17:48:38.826003Z","end":"2026-06-30T17:48:39.060856Z","steps":["trace[35063469] 'process raft request' (duration: 234.526192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:48:39.061226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.196451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-30T17:48:39.061261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.203269ms","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-06-30T17:48:39.061339Z","caller":"traceutil/trace.go:171","msg":"trace[883788251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1185; }","duration":"135.364493ms","start":"2026-06-30T17:48:38.92595Z","end":"2026-06-30T17:48:39.061315Z","steps":["trace[883788251] 'agreement among raft nodes before linearized reading' (duration: 135.014288ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:48:39.061372Z","caller":"traceutil/trace.go:171","msg":"trace[679196946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1185; }","duration":"137.363781ms","start":"2026-06-30T17:48:38.923986Z","end":"2026-06-30T17:48:39.06135Z","steps":["trace[679196946] 'agreement among raft nodes before linearized reading' (duration: 137.009016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:48:42.575143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.105586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:48:42.57526Z","caller":"traceutil/trace.go:171","msg":"trace[2004099564] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"233.757774ms","start":"2026-06-30T17:48:42.341479Z","end":"2026-06-30T17:48:42.575237Z","steps":["trace[2004099564] 'process raft request' (duration: 104.483545ms)","trace[2004099564] 'compare' (duration: 128.958094ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:48:42.793786Z","caller":"traceutil/trace.go:171","msg":"trace[777221685] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"166.529579ms","start":"2026-06-30T17:48:42.627189Z","end":"2026-06-30T17:48:42.793719Z","steps":["trace[777221685] 'process raft request' (duration: 101.309085ms)","trace[777221685] 'compare' (duration: 64.894678ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:48:43.024718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.113073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T17:48:43.024823Z","caller":"traceutil/trace.go:171","msg":"trace[1644973338] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1250; }","duration":"116.232074ms","start":"2026-06-30T17:48:42.908574Z","end":"2026-06-30T17:48:43.024806Z","steps":["trace[1644973338] 'read index received' (duration: 36.57µs)","trace[1644973338] 'applied index is now lower than readState.Index' (duration: 116.194294ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:48:43.0249Z","caller":"traceutil/trace.go:171","msg":"trace[2075088147] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1214; }","duration":"133.170453ms","start":"2026-06-30T17:48:42.891696Z","end":"2026-06-30T17:48:43.024866Z","steps":["trace[2075088147] 'compare' (duration: 122.990001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:48:43.02493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.358207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:48:43.024963Z","caller":"traceutil/trace.go:171","msg":"trace[1526978737] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1214; }","duration":"116.418627ms","start":"2026-06-30T17:48:42.908536Z","end":"2026-06-30T17:48:43.024955Z","steps":["trace[1526978737] 'agreement among raft nodes before linearized reading' (duration: 116.316756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:48:54.748805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.165664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:48:54.74889Z","caller":"traceutil/trace.go:171","msg":"trace[393277467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1322; }","duration":"118.304897ms","start":"2026-06-30T17:48:54.630567Z","end":"2026-06-30T17:48:54.748872Z","steps":["trace[393277467] 'range keys from in-memory index tree' (duration: 118.050401ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:49:01.710746Z","caller":"traceutil/trace.go:171","msg":"trace[969514404] linearizableReadLoop","detail":"{readStateIndex:1492; appliedIndex:1491; }","duration":"185.056161ms","start":"2026-06-30T17:49:01.525663Z","end":"2026-06-30T17:49:01.710719Z","steps":["trace[969514404] 'read index received' (duration: 184.817435ms)","trace[969514404] 'applied index is now lower than readState.Index' (duration: 237.456µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:49:01.710886Z","caller":"traceutil/trace.go:171","msg":"trace[394308239] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"254.453416ms","start":"2026-06-30T17:49:01.456373Z","end":"2026-06-30T17:49:01.710827Z","steps":["trace[394308239] 'process raft request' (duration: 254.088627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:49:01.711122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.42156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-30T17:49:01.711125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.866732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:49:01.711195Z","caller":"traceutil/trace.go:171","msg":"trace[805691392] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1447; }","duration":"185.555294ms","start":"2026-06-30T17:49:01.525624Z","end":"2026-06-30T17:49:01.711179Z","steps":["trace[805691392] 'agreement among raft nodes before linearized reading' (duration: 185.309458ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:49:01.711268Z","caller":"traceutil/trace.go:171","msg":"trace[365104091] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1447; }","duration":"118.049137ms","start":"2026-06-30T17:49:01.5932Z","end":"2026-06-30T17:49:01.711249Z","steps":["trace[365104091] 'agreement among raft nodes before linearized reading' (duration: 117.816651ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:49:25.632773Z","caller":"traceutil/trace.go:171","msg":"trace[1620504618] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"230.584261ms","start":"2026-06-30T17:49:25.402157Z","end":"2026-06-30T17:49:25.632742Z","steps":["trace[1620504618] 'process raft request' (duration: 230.428226ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:49:25.63275Z","caller":"traceutil/trace.go:171","msg":"trace[493712865] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1909; }","duration":"220.372458ms","start":"2026-06-30T17:49:25.412352Z","end":"2026-06-30T17:49:25.632724Z","steps":["trace[493712865] 'read index received' (duration: 219.884262ms)","trace[493712865] 'applied index is now lower than readState.Index' (duration: 487.186µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:49:25.632945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.564295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4ac71c30-341b-41b7-92d6-f13e2d5293c8\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-06-30T17:49:25.633078Z","caller":"traceutil/trace.go:171","msg":"trace[583053118] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4ac71c30-341b-41b7-92d6-f13e2d5293c8; range_end:; response_count:1; response_revision:1839; }","duration":"220.719289ms","start":"2026-06-30T17:49:25.412327Z","end":"2026-06-30T17:49:25.633046Z","steps":["trace[583053118] 'agreement among raft nodes before linearized reading' (duration: 220.485861ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:49:25.63986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.086164ms","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-06-30T17:49:25.639997Z","caller":"traceutil/trace.go:171","msg":"trace[474111133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1840; }","duration":"216.245639ms","start":"2026-06-30T17:49:25.423726Z","end":"2026-06-30T17:49:25.639972Z","steps":["trace[474111133] 'agreement among raft nodes before linearized reading' (duration: 215.997261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:49:25.64019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.5462ms","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-06-30T17:49:25.640231Z","caller":"traceutil/trace.go:171","msg":"trace[1546588339] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1840; }","duration":"202.609982ms","start":"2026-06-30T17:49:25.43761Z","end":"2026-06-30T17:49:25.64022Z","steps":["trace[1546588339] 'agreement among raft nodes before linearized reading' (duration: 202.5246ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:49:25.640348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.48725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4ac71c30-341b-41b7-92d6-f13e2d5293c8\" ","response":"range_response_count:1 size:3625"} {"level":"warn","ts":"2026-06-30T17:49:25.640401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.605797ms","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-06-30T17:49:25.640435Z","caller":"traceutil/trace.go:171","msg":"trace[675801354] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4ac71c30-341b-41b7-92d6-f13e2d5293c8; range_end:; response_count:1; response_revision:1840; }","duration":"103.632074ms","start":"2026-06-30T17:49:25.536787Z","end":"2026-06-30T17:49:25.640419Z","steps":["trace[675801354] 'agreement among raft nodes before linearized reading' (duration: 103.390136ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:49:25.640514Z","caller":"traceutil/trace.go:171","msg":"trace[494427729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1840; }","duration":"206.768742ms","start":"2026-06-30T17:49:25.433723Z","end":"2026-06-30T17:49:25.640492Z","steps":["trace[494427729] 'agreement among raft nodes before linearized reading' (duration: 206.42982ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:49:25.64014Z","caller":"traceutil/trace.go:171","msg":"trace[1900789636] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"142.909111ms","start":"2026-06-30T17:49:25.497183Z","end":"2026-06-30T17:49:25.640092Z","steps":["trace[1900789636] 'process raft request' (duration: 142.450226ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:49:25.943273Z","caller":"traceutil/trace.go:171","msg":"trace[600797748] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"298.260969ms","start":"2026-06-30T17:49:25.644983Z","end":"2026-06-30T17:49:25.943244Z","steps":["trace[600797748] 'process raft request' (duration: 213.133585ms)","trace[600797748] 'compare' (duration: 84.960838ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:49:25.943623Z","caller":"traceutil/trace.go:171","msg":"trace[1907953293] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"298.408984ms","start":"2026-06-30T17:49:25.645198Z","end":"2026-06-30T17:49:25.943607Z","steps":["trace[1907953293] 'process raft request' (duration: 298.005121ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:49:25.943681Z","caller":"traceutil/trace.go:171","msg":"trace[1643807186] linearizableReadLoop","detail":"{readStateIndex:1915; appliedIndex:1912; }","duration":"244.691423ms","start":"2026-06-30T17:49:25.698965Z","end":"2026-06-30T17:49:25.943657Z","steps":["trace[1643807186] 'read index received' (duration: 159.261119ms)","trace[1643807186] 'applied index is now lower than readState.Index' (duration: 85.429274ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:49:25.943783Z","caller":"traceutil/trace.go:171","msg":"trace[680015490] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"297.143141ms","start":"2026-06-30T17:49:25.646619Z","end":"2026-06-30T17:49:25.943762Z","steps":["trace[680015490] 'process raft request' (duration: 296.926964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:49:25.943957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.982562ms","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-06-30T17:49:25.944007Z","caller":"traceutil/trace.go:171","msg":"trace[1194506948] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1844; }","duration":"245.167109ms","start":"2026-06-30T17:49:25.698827Z","end":"2026-06-30T17:49:25.943994Z","steps":["trace[1194506948] 'agreement among raft nodes before linearized reading' (duration: 245.009834ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:49:25.9646Z","caller":"traceutil/trace.go:171","msg":"trace[1695504913] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"143.423788ms","start":"2026-06-30T17:49:25.821146Z","end":"2026-06-30T17:49:25.96457Z","steps":["trace[1695504913] 'process raft request' (duration: 143.264182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:49:26.375134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.628697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-30T17:49:26.375247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.085485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-30T17:49:26.375248Z","caller":"traceutil/trace.go:171","msg":"trace[1887969679] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1845; }","duration":"146.857725ms","start":"2026-06-30T17:49:26.228371Z","end":"2026-06-30T17:49:26.375229Z","steps":["trace[1887969679] 'count revisions from in-memory index tree' (duration: 146.479232ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:49:26.375348Z","caller":"traceutil/trace.go:171","msg":"trace[482835904] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1845; }","duration":"111.2404ms","start":"2026-06-30T17:49:26.264078Z","end":"2026-06-30T17:49:26.375318Z","steps":["trace[482835904] 'range keys from in-memory index tree' (duration: 110.888108ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:49:39.799877Z","caller":"traceutil/trace.go:171","msg":"trace[2140088990] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"111.927237ms","start":"2026-06-30T17:49:39.687917Z","end":"2026-06-30T17:49:39.799845Z","steps":["trace[2140088990] 'process raft request' (duration: 111.592064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:50:00.362225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.967869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:50:00.36248Z","caller":"traceutil/trace.go:171","msg":"trace[572980468] linearizableReadLoop","detail":"{readStateIndex:2165; appliedIndex:2163; }","duration":"252.883504ms","start":"2026-06-30T17:50:00.109573Z","end":"2026-06-30T17:50:00.362457Z","steps":["trace[572980468] 'read index received' (duration: 22.625403ms)","trace[572980468] 'applied index is now lower than readState.Index' (duration: 230.256711ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:50:00.362667Z","caller":"traceutil/trace.go:171","msg":"trace[1473501704] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"277.812103ms","start":"2026-06-30T17:50:00.084809Z","end":"2026-06-30T17:50:00.362621Z","steps":["trace[1473501704] 'process raft request' (duration: 277.555512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:50:00.362916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.341893ms","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-06-30T17:50:00.362977Z","caller":"traceutil/trace.go:171","msg":"trace[459740158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2086; }","duration":"253.434817ms","start":"2026-06-30T17:50:00.109525Z","end":"2026-06-30T17:50:00.36296Z","steps":["trace[459740158] 'agreement among raft nodes before linearized reading' (duration: 253.068372ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:50:00.363198Z","caller":"traceutil/trace.go:171","msg":"trace[1962032804] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"446.699407ms","start":"2026-06-30T17:49:59.916452Z","end":"2026-06-30T17:50:00.363151Z","steps":["trace[1962032804] 'process raft request' (duration: 215.698595ms)","trace[1962032804] 'compare' (duration: 229.779371ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:50:00.363281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.600612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-30T17:50:00.36332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:49:59.916438Z","time spent":"446.822742ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:50:00.363313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.730161ms","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-06-30T17:50:00.363336Z","caller":"traceutil/trace.go:171","msg":"trace[562623620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2086; }","duration":"252.699397ms","start":"2026-06-30T17:50:00.110619Z","end":"2026-06-30T17:50:00.363319Z","steps":["trace[562623620] 'agreement among raft nodes before linearized reading' (duration: 252.483748ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:50:00.363372Z","caller":"traceutil/trace.go:171","msg":"trace[1322114372] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2086; }","duration":"183.841175ms","start":"2026-06-30T17:50:00.179512Z","end":"2026-06-30T17:50:00.363353Z","steps":["trace[1322114372] 'agreement among raft nodes before linearized reading' (duration: 183.689579ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:50:00.753139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.540077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:50:00.753574Z","caller":"traceutil/trace.go:171","msg":"trace[1041516544] linearizableReadLoop","detail":"{readStateIndex:2167; appliedIndex:2165; }","duration":"140.573331ms","start":"2026-06-30T17:50:00.612973Z","end":"2026-06-30T17:50:00.753547Z","steps":["trace[1041516544] 'read index received' (duration: 84.57242ms)","trace[1041516544] 'applied index is now lower than readState.Index' (duration: 55.9994ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:50:00.753712Z","caller":"traceutil/trace.go:171","msg":"trace[1243245054] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"386.122652ms","start":"2026-06-30T17:50:00.367575Z","end":"2026-06-30T17:50:00.753697Z","steps":["trace[1243245054] 'process raft request' (duration: 174.887596ms)","trace[1243245054] 'compare' (duration: 210.37062ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:50:00.753793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:50:00.367556Z","time spent":"386.194775ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:50:00.75404Z","caller":"traceutil/trace.go:171","msg":"trace[2022341222] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"386.224236ms","start":"2026-06-30T17:50:00.367785Z","end":"2026-06-30T17:50:00.75401Z","steps":["trace[2022341222] 'process raft request' (duration: 385.508426ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:50:00.754198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:50:00.367749Z","time spent":"386.360312ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:50:00.754205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.232907ms","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-06-30T17:50:00.754441Z","caller":"traceutil/trace.go:171","msg":"trace[1815343315] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2088; }","duration":"141.52011ms","start":"2026-06-30T17:50:00.612903Z","end":"2026-06-30T17:50:00.754423Z","steps":["trace[1815343315] 'agreement among raft nodes before linearized reading' (duration: 141.05336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:50:00.754439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.992185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-06-30T17:50:00.754492Z","caller":"traceutil/trace.go:171","msg":"trace[1572504132] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2088; }","duration":"139.101049ms","start":"2026-06-30T17:50:00.615378Z","end":"2026-06-30T17:50:00.754479Z","steps":["trace[1572504132] 'agreement among raft nodes before linearized reading' (duration: 138.662191ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:50:01.24144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.207854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:50:01.241755Z","caller":"traceutil/trace.go:171","msg":"trace[504298750] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"482.673014ms","start":"2026-06-30T17:50:00.759054Z","end":"2026-06-30T17:50:01.241727Z","steps":["trace[504298750] 'process raft request' (duration: 268.086125ms)","trace[504298750] 'compare' (duration: 214.038046ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:50:01.24186Z","caller":"traceutil/trace.go:171","msg":"trace[1165055891] linearizableReadLoop","detail":"{readStateIndex:2169; appliedIndex:2167; }","duration":"445.143736ms","start":"2026-06-30T17:50:00.796699Z","end":"2026-06-30T17:50:01.241843Z","steps":["trace[1165055891] 'read index received' (duration: 231.11775ms)","trace[1165055891] 'applied index is now lower than readState.Index' (duration: 214.024786ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:50:01.241941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:50:00.759028Z","time spent":"482.785019ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:50:01.242092Z","caller":"traceutil/trace.go:171","msg":"trace[461642572] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"482.81389ms","start":"2026-06-30T17:50:00.759252Z","end":"2026-06-30T17:50:01.242066Z","steps":["trace[461642572] 'process raft request' (duration: 482.308409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:50:01.242137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.183824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:50:01.242173Z","caller":"traceutil/trace.go:171","msg":"trace[816750790] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2091; }","duration":"284.271938ms","start":"2026-06-30T17:50:00.957891Z","end":"2026-06-30T17:50:01.242163Z","steps":["trace[816750790] 'agreement among raft nodes before linearized reading' (duration: 284.197285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:50:01.242195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.487691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-30T17:50:01.242254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:50:00.759237Z","time spent":"482.919265ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:50:01.242273Z","caller":"traceutil/trace.go:171","msg":"trace[1460551012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2091; }","duration":"445.601175ms","start":"2026-06-30T17:50:00.796654Z","end":"2026-06-30T17:50:01.242255Z","steps":["trace[1460551012] 'agreement among raft nodes before linearized reading' (duration: 445.391136ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:50:01.242262Z","caller":"traceutil/trace.go:171","msg":"trace[1051170011] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"406.599046ms","start":"2026-06-30T17:50:00.835625Z","end":"2026-06-30T17:50:01.242224Z","steps":["trace[1051170011] 'process raft request' (duration: 406.168968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:50:01.242317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:50:00.796642Z","time spent":"445.667117ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T17:50:01.242442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:50:00.8356Z","time spent":"406.756062ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:50:01.242653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.248371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-06-30T17:50:01.242688Z","caller":"traceutil/trace.go:171","msg":"trace[1431037315] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:2091; }","duration":"197.343075ms","start":"2026-06-30T17:50:01.045336Z","end":"2026-06-30T17:50:01.242679Z","steps":["trace[1431037315] 'agreement among raft nodes before linearized reading' (duration: 197.22115ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:51:37.855579Z","caller":"traceutil/trace.go:171","msg":"trace[2029153021] linearizableReadLoop","detail":"{readStateIndex:2813; appliedIndex:2812; }","duration":"225.553314ms","start":"2026-06-30T17:51:37.630003Z","end":"2026-06-30T17:51:37.855556Z","steps":["trace[2029153021] 'read index received' (duration: 225.2784ms)","trace[2029153021] 'applied index is now lower than readState.Index' (duration: 273.914µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:51:37.855713Z","caller":"traceutil/trace.go:171","msg":"trace[2001886730] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"229.457528ms","start":"2026-06-30T17:51:37.62624Z","end":"2026-06-30T17:51:37.855697Z","steps":["trace[2001886730] 'process raft request' (duration: 229.0924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:51:37.856036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.994466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:51:37.856149Z","caller":"traceutil/trace.go:171","msg":"trace[1391177472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2711; }","duration":"226.181176ms","start":"2026-06-30T17:51:37.629937Z","end":"2026-06-30T17:51:37.856118Z","steps":["trace[1391177472] 'agreement among raft nodes before linearized reading' (duration: 225.997057ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:51:37.969085Z","caller":"traceutil/trace.go:171","msg":"trace[315103301] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"107.359084ms","start":"2026-06-30T17:51:37.861694Z","end":"2026-06-30T17:51:37.969053Z","steps":["trace[315103301] 'process raft request' (duration: 103.757236ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:51:38.030162Z","caller":"traceutil/trace.go:171","msg":"trace[1456530236] transaction","detail":"{read_only:false; response_revision:2714; number_of_response:1; }","duration":"167.864179ms","start":"2026-06-30T17:51:37.862264Z","end":"2026-06-30T17:51:38.030128Z","steps":["trace[1456530236] 'process raft request' (duration: 167.815447ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:51:38.030231Z","caller":"traceutil/trace.go:171","msg":"trace[833098617] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"167.987886ms","start":"2026-06-30T17:51:37.862196Z","end":"2026-06-30T17:51:38.030184Z","steps":["trace[833098617] 'process raft request' (duration: 167.711513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:51:38.594698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.143336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:51:38.59514Z","caller":"traceutil/trace.go:171","msg":"trace[2125058409] linearizableReadLoop","detail":"{readStateIndex:2817; appliedIndex:2816; }","duration":"300.724076ms","start":"2026-06-30T17:51:38.294388Z","end":"2026-06-30T17:51:38.595112Z","steps":["trace[2125058409] 'read index received' (duration: 21.934097ms)","trace[2125058409] 'applied index is now lower than readState.Index' (duration: 278.786908ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:51:38.595352Z","caller":"traceutil/trace.go:171","msg":"trace[871152885] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"400.403402ms","start":"2026-06-30T17:51:38.194929Z","end":"2026-06-30T17:51:38.595332Z","steps":["trace[871152885] 'process raft request' (duration: 121.499118ms)","trace[871152885] 'compare' (duration: 277.934066ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:51:38.59551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:51:38.194884Z","time spent":"400.572402ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T17:51:38.595606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.67018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:51:38.595711Z","caller":"traceutil/trace.go:171","msg":"trace[911295489] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2715; }","duration":"243.814767ms","start":"2026-06-30T17:51:38.351874Z","end":"2026-06-30T17:51:38.595689Z","steps":["trace[911295489] 'agreement among raft nodes before linearized reading' (duration: 243.590876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:51:38.595943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.930714ms","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-06-30T17:51:38.59606Z","caller":"traceutil/trace.go:171","msg":"trace[1150660252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2715; }","duration":"156.124073ms","start":"2026-06-30T17:51:38.439909Z","end":"2026-06-30T17:51:38.596033Z","steps":["trace[1150660252] 'agreement among raft nodes before linearized reading' (duration: 155.835099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:51:38.596415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.01936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18bded3e0e34b3ef\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-06-30T17:51:38.596493Z","caller":"traceutil/trace.go:171","msg":"trace[1957111445] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18bded3e0e34b3ef; range_end:; response_count:1; response_revision:2715; }","duration":"302.172717ms","start":"2026-06-30T17:51:38.294304Z","end":"2026-06-30T17:51:38.596477Z","steps":["trace[1957111445] 'agreement among raft nodes before linearized reading' (duration: 301.187778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:51:38.596526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:51:38.294266Z","time spent":"302.253641ms","remote":"127.0.0.1:42598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18bded3e0e34b3ef\" "} {"level":"info","ts":"2026-06-30T17:51:38.815973Z","caller":"traceutil/trace.go:171","msg":"trace[804997028] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"215.180668ms","start":"2026-06-30T17:51:38.600768Z","end":"2026-06-30T17:51:38.815948Z","steps":["trace[804997028] 'process raft request' (duration: 144.624623ms)","trace[804997028] 'compare' (duration: 69.829129ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:52:21.746397Z","caller":"traceutil/trace.go:171","msg":"trace[1827740185] linearizableReadLoop","detail":"{readStateIndex:3056; appliedIndex:3055; }","duration":"171.7711ms","start":"2026-06-30T17:52:21.57459Z","end":"2026-06-30T17:52:21.746361Z","steps":["trace[1827740185] 'read index received' (duration: 171.390694ms)","trace[1827740185] 'applied index is now lower than readState.Index' (duration: 378.976µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:52:21.746431Z","caller":"traceutil/trace.go:171","msg":"trace[2099783501] transaction","detail":"{read_only:false; response_revision:2944; number_of_response:1; }","duration":"172.394045ms","start":"2026-06-30T17:52:21.574004Z","end":"2026-06-30T17:52:21.746398Z","steps":["trace[2099783501] 'process raft request' (duration: 172.104387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:52:21.746684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.703831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:52:21.746762Z","caller":"traceutil/trace.go:171","msg":"trace[1061998109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2944; }","duration":"118.82171ms","start":"2026-06-30T17:52:21.627921Z","end":"2026-06-30T17:52:21.746743Z","steps":["trace[1061998109] 'agreement among raft nodes before linearized reading' (duration: 118.704442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:52:21.746896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.216366ms","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-06-30T17:52:21.746989Z","caller":"traceutil/trace.go:171","msg":"trace[1253662532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2944; }","duration":"172.442974ms","start":"2026-06-30T17:52:21.574527Z","end":"2026-06-30T17:52:21.74697Z","steps":["trace[1253662532] 'agreement among raft nodes before linearized reading' (duration: 171.984369ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:52:21.864837Z","caller":"traceutil/trace.go:171","msg":"trace[1954533612] transaction","detail":"{read_only:false; response_revision:2945; number_of_response:1; }","duration":"112.707248ms","start":"2026-06-30T17:52:21.752102Z","end":"2026-06-30T17:52:21.86481Z","steps":["trace[1954533612] 'process raft request' (duration: 20.978753ms)","trace[1954533612] 'compare' (duration: 91.566357ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:52:50.602962Z","caller":"traceutil/trace.go:171","msg":"trace[1789496656] transaction","detail":"{read_only:false; response_revision:3096; number_of_response:1; }","duration":"106.641851ms","start":"2026-06-30T17:52:50.49629Z","end":"2026-06-30T17:52:50.602932Z","steps":["trace[1789496656] 'process raft request' (duration: 63.572722ms)","trace[1789496656] 'compare' (duration: 42.911749ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:52:52.023532Z","caller":"traceutil/trace.go:171","msg":"trace[193949876] transaction","detail":"{read_only:false; response_revision:3102; number_of_response:1; }","duration":"115.919283ms","start":"2026-06-30T17:52:51.907587Z","end":"2026-06-30T17:52:52.023506Z","steps":["trace[193949876] 'process raft request' (duration: 115.723523ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:52:52.177498Z","caller":"traceutil/trace.go:171","msg":"trace[1784899185] transaction","detail":"{read_only:false; response_revision:3103; number_of_response:1; }","duration":"140.214931ms","start":"2026-06-30T17:52:52.037228Z","end":"2026-06-30T17:52:52.177443Z","steps":["trace[1784899185] 'process raft request' (duration: 59.306216ms)","trace[1784899185] 'compare' (duration: 80.668234ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:52:52.581082Z","caller":"traceutil/trace.go:171","msg":"trace[302859537] linearizableReadLoop","detail":"{readStateIndex:3223; appliedIndex:3222; }","duration":"116.591227ms","start":"2026-06-30T17:52:52.46447Z","end":"2026-06-30T17:52:52.581061Z","steps":["trace[302859537] 'read index received' (duration: 116.330026ms)","trace[302859537] 'applied index is now lower than readState.Index' (duration: 260.471µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:52:52.581234Z","caller":"traceutil/trace.go:171","msg":"trace[1277741629] transaction","detail":"{read_only:false; response_revision:3105; number_of_response:1; }","duration":"120.553526ms","start":"2026-06-30T17:52:52.460591Z","end":"2026-06-30T17:52:52.581145Z","steps":["trace[1277741629] 'process raft request' (duration: 120.255415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:52:52.581333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.916427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-06-30T17:52:52.581381Z","caller":"traceutil/trace.go:171","msg":"trace[1883578654] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3105; }","duration":"103.994487ms","start":"2026-06-30T17:52:52.47737Z","end":"2026-06-30T17:52:52.581364Z","steps":["trace[1883578654] 'agreement among raft nodes before linearized reading' (duration: 103.814427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:52:52.581566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.877397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:52:52.581657Z","caller":"traceutil/trace.go:171","msg":"trace[127719260] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:3105; }","duration":"117.195968ms","start":"2026-06-30T17:52:52.464445Z","end":"2026-06-30T17:52:52.581641Z","steps":["trace[127719260] 'agreement among raft nodes before linearized reading' (duration: 116.697317ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:53:06.429759Z","caller":"traceutil/trace.go:171","msg":"trace[719599836] transaction","detail":"{read_only:false; response_revision:3176; number_of_response:1; }","duration":"107.702962ms","start":"2026-06-30T17:53:06.322028Z","end":"2026-06-30T17:53:06.429731Z","steps":["trace[719599836] 'process raft request' (duration: 104.522141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:53:07.009706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.179846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:53:07.010377Z","caller":"traceutil/trace.go:171","msg":"trace[486797101] transaction","detail":"{read_only:false; response_revision:3179; number_of_response:1; }","duration":"256.343452ms","start":"2026-06-30T17:53:06.754007Z","end":"2026-06-30T17:53:07.01035Z","steps":["trace[486797101] 'process raft request' (duration: 110.35841ms)","trace[486797101] 'compare' (duration: 144.794393ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:53:08.284205Z","caller":"traceutil/trace.go:171","msg":"trace[628842086] transaction","detail":"{read_only:false; response_revision:3184; number_of_response:1; }","duration":"117.909821ms","start":"2026-06-30T17:53:08.166246Z","end":"2026-06-30T17:53:08.284156Z","steps":["trace[628842086] 'process raft request' (duration: 28.238566ms)","trace[628842086] 'compare' (duration: 89.150991ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:53:48.126205Z","caller":"traceutil/trace.go:171","msg":"trace[686634860] transaction","detail":"{read_only:false; response_revision:3385; number_of_response:1; }","duration":"248.494399ms","start":"2026-06-30T17:53:47.877682Z","end":"2026-06-30T17:53:48.126177Z","steps":["trace[686634860] 'process raft request' (duration: 181.877429ms)","trace[686634860] 'compare' (duration: 66.498718ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:53:49.052078Z","caller":"traceutil/trace.go:171","msg":"trace[1067218838] transaction","detail":"{read_only:false; response_revision:3390; number_of_response:1; }","duration":"102.012905ms","start":"2026-06-30T17:53:48.95004Z","end":"2026-06-30T17:53:49.052053Z","steps":["trace[1067218838] 'process raft request' (duration: 94.691956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:53:50.152802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.196844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:53:50.152969Z","caller":"traceutil/trace.go:171","msg":"trace[1504196911] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:3402; }","duration":"164.386788ms","start":"2026-06-30T17:53:49.988559Z","end":"2026-06-30T17:53:50.152946Z","steps":["trace[1504196911] 'count revisions from in-memory index tree' (duration: 164.095923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:53:50.153059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.727286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:53:50.15336Z","caller":"traceutil/trace.go:171","msg":"trace[65023904] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:3402; }","duration":"106.084373ms","start":"2026-06-30T17:53:50.047241Z","end":"2026-06-30T17:53:50.153326Z","steps":["trace[65023904] 'count revisions from in-memory index tree' (duration: 105.646355ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:53:50.754611Z","caller":"traceutil/trace.go:171","msg":"trace[1820409607] linearizableReadLoop","detail":"{readStateIndex:3534; appliedIndex:3533; }","duration":"125.407257ms","start":"2026-06-30T17:53:50.629184Z","end":"2026-06-30T17:53:50.754591Z","steps":["trace[1820409607] 'read index received' (duration: 125.357196ms)","trace[1820409607] 'applied index is now lower than readState.Index' (duration: 49.151µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:53:50.75472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.518709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:53:50.754753Z","caller":"traceutil/trace.go:171","msg":"trace[1102962126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3405; }","duration":"125.583799ms","start":"2026-06-30T17:53:50.629158Z","end":"2026-06-30T17:53:50.754742Z","steps":["trace[1102962126] 'agreement among raft nodes before linearized reading' (duration: 125.520948ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:53:50.754766Z","caller":"traceutil/trace.go:171","msg":"trace[2105289181] transaction","detail":"{read_only:false; response_revision:3405; number_of_response:1; }","duration":"157.733473ms","start":"2026-06-30T17:53:50.596952Z","end":"2026-06-30T17:53:50.754685Z","steps":["trace[2105289181] 'process raft request' (duration: 157.485709ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:53:50.782936Z","caller":"traceutil/trace.go:171","msg":"trace[47749450] transaction","detail":"{read_only:false; response_revision:3406; number_of_response:1; }","duration":"126.528817ms","start":"2026-06-30T17:53:50.65637Z","end":"2026-06-30T17:53:50.782899Z","steps":["trace[47749450] 'process raft request' (duration: 126.261652ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:54:07.796693Z","caller":"traceutil/trace.go:171","msg":"trace[177957673] transaction","detail":"{read_only:false; response_revision:3579; number_of_response:1; }","duration":"101.043943ms","start":"2026-06-30T17:54:07.695596Z","end":"2026-06-30T17:54:07.796639Z","steps":["trace[177957673] 'process raft request' (duration: 72.937247ms)","trace[177957673] 'compare' (duration: 25.825288ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:54:10.46991Z","caller":"traceutil/trace.go:171","msg":"trace[1985165514] transaction","detail":"{read_only:false; response_revision:3593; number_of_response:1; }","duration":"180.051167ms","start":"2026-06-30T17:54:10.289831Z","end":"2026-06-30T17:54:10.469882Z","steps":["trace[1985165514] 'process raft request' (duration: 179.843323ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:54:31.857275Z","caller":"traceutil/trace.go:171","msg":"trace[1428454879] transaction","detail":"{read_only:false; response_revision:3712; number_of_response:1; }","duration":"179.517905ms","start":"2026-06-30T17:54:31.67771Z","end":"2026-06-30T17:54:31.857227Z","steps":["trace[1428454879] 'process raft request' (duration: 87.970142ms)","trace[1428454879] 'compare' (duration: 91.290886ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:54:31.857337Z","caller":"traceutil/trace.go:171","msg":"trace[1019184068] transaction","detail":"{read_only:false; response_revision:3713; number_of_response:1; }","duration":"175.247047ms","start":"2026-06-30T17:54:31.682069Z","end":"2026-06-30T17:54:31.857316Z","steps":["trace[1019184068] 'process raft request' (duration: 175.121904ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:54:37.403859Z","caller":"traceutil/trace.go:171","msg":"trace[686492397] transaction","detail":"{read_only:false; response_revision:3739; number_of_response:1; }","duration":"187.075014ms","start":"2026-06-30T17:54:37.216749Z","end":"2026-06-30T17:54:37.403824Z","steps":["trace[686492397] 'process raft request' (duration: 173.604472ms)","trace[686492397] 'compare' (duration: 13.236226ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:54:37.40401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.357964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:54:37.404094Z","caller":"traceutil/trace.go:171","msg":"trace[2134373909] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret; range_end:; response_count:0; response_revision:3739; }","duration":"103.496888ms","start":"2026-06-30T17:54:37.300575Z","end":"2026-06-30T17:54:37.404072Z","steps":["trace[2134373909] 'agreement among raft nodes before linearized reading' (duration: 103.341184ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:54:37.404523Z","caller":"traceutil/trace.go:171","msg":"trace[1824319177] linearizableReadLoop","detail":"{readStateIndex:3882; appliedIndex:3881; }","duration":"103.151848ms","start":"2026-06-30T17:54:37.300632Z","end":"2026-06-30T17:54:37.403784Z","steps":["trace[1824319177] 'read index received' (duration: 89.605704ms)","trace[1824319177] 'applied index is now lower than readState.Index' (duration: 13.544914ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:54:37.669381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.520308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:54:37.669523Z","caller":"traceutil/trace.go:171","msg":"trace[133061777] transaction","detail":"{read_only:false; response_revision:3743; number_of_response:1; }","duration":"170.818299ms","start":"2026-06-30T17:54:37.498684Z","end":"2026-06-30T17:54:37.669502Z","steps":["trace[133061777] 'process raft request' (duration: 54.076645ms)","trace[133061777] 'compare' (duration: 116.394125ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:54:37.686191Z","caller":"traceutil/trace.go:171","msg":"trace[1050427293] transaction","detail":"{read_only:false; response_revision:3744; number_of_response:1; }","duration":"183.802578ms","start":"2026-06-30T17:54:37.502366Z","end":"2026-06-30T17:54:37.686168Z","steps":["trace[1050427293] 'process raft request' (duration: 183.247074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:39.517263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.300803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:54:39.517358Z","caller":"traceutil/trace.go:171","msg":"trace[1097072563] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:3750; }","duration":"100.445177ms","start":"2026-06-30T17:54:39.416894Z","end":"2026-06-30T17:54:39.517339Z","steps":["trace[1097072563] 'count revisions from in-memory index tree' (duration: 100.211001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:39.517349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.86687ms","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-06-30T17:54:39.517425Z","caller":"traceutil/trace.go:171","msg":"trace[1450788079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3750; }","duration":"105.994224ms","start":"2026-06-30T17:54:39.411411Z","end":"2026-06-30T17:54:39.517405Z","steps":["trace[1450788079] 'range keys from in-memory index tree' (duration: 105.675276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:58.531233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.908787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/scrapeconfigs.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:54:58.5314Z","caller":"traceutil/trace.go:171","msg":"trace[1800169310] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/scrapeconfigs.monitoring.coreos.com; range_end:; response_count:0; response_revision:3902; }","duration":"121.135593ms","start":"2026-06-30T17:54:58.410206Z","end":"2026-06-30T17:54:58.531342Z","steps":["trace[1800169310] 'range keys from in-memory index tree' (duration: 120.715341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:58.853885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.746729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:54:58.854042Z","caller":"traceutil/trace.go:171","msg":"trace[357458837] transaction","detail":"{read_only:false; response_revision:3904; number_of_response:1; }","duration":"280.37399ms","start":"2026-06-30T17:54:58.573641Z","end":"2026-06-30T17:54:58.854015Z","steps":["trace[357458837] 'process raft request' (duration: 97.391064ms)","trace[357458837] 'compare' (duration: 182.272455ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:54:58.85595Z","caller":"traceutil/trace.go:171","msg":"trace[1528768424] linearizableReadLoop","detail":"{readStateIndex:4056; appliedIndex:4054; }","duration":"271.994847ms","start":"2026-06-30T17:54:58.58392Z","end":"2026-06-30T17:54:58.855915Z","steps":["trace[1528768424] 'read index received' (duration: 86.364385ms)","trace[1528768424] 'applied index is now lower than readState.Index' (duration: 185.628912ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:54:58.855979Z","caller":"traceutil/trace.go:171","msg":"trace[1250144752] transaction","detail":"{read_only:false; response_revision:3905; number_of_response:1; }","duration":"275.155689ms","start":"2026-06-30T17:54:58.58079Z","end":"2026-06-30T17:54:58.855946Z","steps":["trace[1250144752] 'process raft request' (duration: 274.84396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:58.856337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.677444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:54:58.856359Z","caller":"traceutil/trace.go:171","msg":"trace[1927463367] transaction","detail":"{read_only:false; response_revision:3906; number_of_response:1; }","duration":"188.247679ms","start":"2026-06-30T17:54:58.668097Z","end":"2026-06-30T17:54:58.856345Z","steps":["trace[1927463367] 'process raft request' (duration: 187.734604ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:54:58.8564Z","caller":"traceutil/trace.go:171","msg":"trace[1752531925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3906; }","duration":"225.809658ms","start":"2026-06-30T17:54:58.630571Z","end":"2026-06-30T17:54:58.85638Z","steps":["trace[1752531925] 'agreement among raft nodes before linearized reading' (duration: 225.702575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:54:58.856645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.720348ms","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-06-30T17:54:58.85672Z","caller":"traceutil/trace.go:171","msg":"trace[169096169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3906; }","duration":"272.859912ms","start":"2026-06-30T17:54:58.58384Z","end":"2026-06-30T17:54:58.8567Z","steps":["trace[169096169] 'agreement among raft nodes before linearized reading' (duration: 272.643186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:07.703166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.323488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:55:07.703253Z","caller":"traceutil/trace.go:171","msg":"trace[1125453943] linearizableReadLoop","detail":"{readStateIndex:4119; appliedIndex:4118; }","duration":"201.400037ms","start":"2026-06-30T17:55:07.50184Z","end":"2026-06-30T17:55:07.70324Z","steps":["trace[1125453943] 'read index received' (duration: 94.719528ms)","trace[1125453943] 'applied index is now lower than readState.Index' (duration: 106.679729ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:07.703596Z","caller":"traceutil/trace.go:171","msg":"trace[1751288617] transaction","detail":"{read_only:false; response_revision:3966; number_of_response:1; }","duration":"329.81934ms","start":"2026-06-30T17:55:07.373751Z","end":"2026-06-30T17:55:07.703571Z","steps":["trace[1751288617] 'process raft request' (duration: 223.034338ms)","trace[1751288617] 'compare' (duration: 106.101651ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:07.703693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:07.373719Z","time spent":"329.930723ms","remote":"127.0.0.1:42634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":683747,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-30T17:55:07.703702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.837211ms","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-06-30T17:55:07.703793Z","caller":"traceutil/trace.go:171","msg":"trace[600598867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3966; }","duration":"201.968815ms","start":"2026-06-30T17:55:07.501799Z","end":"2026-06-30T17:55:07.703768Z","steps":["trace[600598867] 'agreement among raft nodes before linearized reading' (duration: 201.670256ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:09.945639Z","caller":"traceutil/trace.go:171","msg":"trace[1358085010] transaction","detail":"{read_only:false; response_revision:3977; number_of_response:1; }","duration":"224.574867ms","start":"2026-06-30T17:55:09.720992Z","end":"2026-06-30T17:55:09.945567Z","steps":["trace[1358085010] 'process raft request' (duration: 224.389661ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:09.984713Z","caller":"traceutil/trace.go:171","msg":"trace[110325404] linearizableReadLoop","detail":"{readStateIndex:4131; appliedIndex:4129; }","duration":"191.657598ms","start":"2026-06-30T17:55:09.793037Z","end":"2026-06-30T17:55:09.984695Z","steps":["trace[110325404] 'read index received' (duration: 152.408909ms)","trace[110325404] 'applied index is now lower than readState.Index' (duration: 39.248139ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:09.984973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.914906ms","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-06-30T17:55:09.985061Z","caller":"traceutil/trace.go:171","msg":"trace[2076144859] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3978; }","duration":"192.058561ms","start":"2026-06-30T17:55:09.792985Z","end":"2026-06-30T17:55:09.985044Z","steps":["trace[2076144859] 'agreement among raft nodes before linearized reading' (duration: 191.811643ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:09.985013Z","caller":"traceutil/trace.go:171","msg":"trace[1023378221] transaction","detail":"{read_only:false; response_revision:3978; number_of_response:1; }","duration":"256.906345ms","start":"2026-06-30T17:55:09.727853Z","end":"2026-06-30T17:55:09.98476Z","steps":["trace[1023378221] 'process raft request' (duration: 255.786551ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:10.970518Z","caller":"traceutil/trace.go:171","msg":"trace[718363166] transaction","detail":"{read_only:false; response_revision:3985; number_of_response:1; }","duration":"134.278725ms","start":"2026-06-30T17:55:10.836176Z","end":"2026-06-30T17:55:10.970455Z","steps":["trace[718363166] 'process raft request' (duration: 133.887513ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:31.257933Z","caller":"traceutil/trace.go:171","msg":"trace[1696918442] transaction","detail":"{read_only:false; response_revision:4096; number_of_response:1; }","duration":"100.378473ms","start":"2026-06-30T17:55:31.157513Z","end":"2026-06-30T17:55:31.257892Z","steps":["trace[1696918442] 'process raft request' (duration: 99.992851ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:35.221171Z","caller":"traceutil/trace.go:171","msg":"trace[751317460] transaction","detail":"{read_only:false; response_revision:4116; number_of_response:1; }","duration":"113.828785ms","start":"2026-06-30T17:55:35.107318Z","end":"2026-06-30T17:55:35.221146Z","steps":["trace[751317460] 'process raft request' (duration: 113.537246ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:35.556324Z","caller":"traceutil/trace.go:171","msg":"trace[1464401038] transaction","detail":"{read_only:false; response_revision:4119; number_of_response:1; }","duration":"162.311174ms","start":"2026-06-30T17:55:35.393996Z","end":"2026-06-30T17:55:35.556308Z","steps":["trace[1464401038] 'process raft request' (duration: 161.826909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:35.556551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.233627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:55:35.556659Z","caller":"traceutil/trace.go:171","msg":"trace[1260361047] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4119; }","duration":"127.367191ms","start":"2026-06-30T17:55:35.429267Z","end":"2026-06-30T17:55:35.556634Z","steps":["trace[1260361047] 'agreement among raft nodes before linearized reading' (duration: 127.148794ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:35.556245Z","caller":"traceutil/trace.go:171","msg":"trace[1772258970] linearizableReadLoop","detail":"{readStateIndex:4288; appliedIndex:4287; }","duration":"126.933917ms","start":"2026-06-30T17:55:35.429283Z","end":"2026-06-30T17:55:35.556217Z","steps":["trace[1772258970] 'read index received' (duration: 126.298317ms)","trace[1772258970] 'applied index is now lower than readState.Index' (duration: 634.5µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:35.822175Z","caller":"traceutil/trace.go:171","msg":"trace[1525869957] transaction","detail":"{read_only:false; response_revision:4121; number_of_response:1; }","duration":"156.650448ms","start":"2026-06-30T17:55:35.665497Z","end":"2026-06-30T17:55:35.822148Z","steps":["trace[1525869957] 'process raft request' (duration: 156.577305ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:35.82222Z","caller":"traceutil/trace.go:171","msg":"trace[1521993563] transaction","detail":"{read_only:false; response_revision:4120; number_of_response:1; }","duration":"227.97552ms","start":"2026-06-30T17:55:35.594188Z","end":"2026-06-30T17:55:35.822163Z","steps":["trace[1521993563] 'process raft request' (duration: 191.961882ms)","trace[1521993563] 'compare' (duration: 35.728739ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:42.73672Z","caller":"traceutil/trace.go:171","msg":"trace[283458399] transaction","detail":"{read_only:false; response_revision:4183; number_of_response:1; }","duration":"167.013545ms","start":"2026-06-30T17:55:42.569647Z","end":"2026-06-30T17:55:42.73666Z","steps":["trace[283458399] 'process raft request' (duration: 165.234085ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:48.515771Z","caller":"traceutil/trace.go:171","msg":"trace[1071623363] transaction","detail":"{read_only:false; response_revision:4549; number_of_response:1; }","duration":"110.134029ms","start":"2026-06-30T17:55:48.405614Z","end":"2026-06-30T17:55:48.515748Z","steps":["trace[1071623363] 'process raft request' (duration: 109.85978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:51.188276Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933266871907844794,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-30T17:55:51.192897Z","caller":"traceutil/trace.go:171","msg":"trace[492883738] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"511.841503ms","start":"2026-06-30T17:55:50.681036Z","end":"2026-06-30T17:55:51.192878Z","steps":["trace[492883738] 'process raft request' (duration: 511.47245ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:51.193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:50.681015Z","time spent":"511.922886ms","remote":"127.0.0.1:42624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":499298,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:55:51.19313Z","caller":"traceutil/trace.go:171","msg":"trace[1718716388] linearizableReadLoop","detail":"{readStateIndex:4825; appliedIndex:4823; }","duration":"505.679382ms","start":"2026-06-30T17:55:50.687441Z","end":"2026-06-30T17:55:51.193121Z","steps":["trace[1718716388] 'read index received' (duration: 505.01739ms)","trace[1718716388] 'applied index is now lower than readState.Index' (duration: 661.582µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:51.1932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.751275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:55:51.193484Z","caller":"traceutil/trace.go:171","msg":"trace[563811401] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:4646; }","duration":"506.043664ms","start":"2026-06-30T17:55:50.687428Z","end":"2026-06-30T17:55:51.193471Z","steps":["trace[563811401] 'agreement among raft nodes before linearized reading' (duration: 505.721703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:51.19352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:50.68742Z","time spent":"506.088126ms","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":3,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-06-30T17:55:51.193226Z","caller":"traceutil/trace.go:171","msg":"trace[637418566] transaction","detail":"{read_only:false; response_revision:4646; number_of_response:1; }","duration":"275.909861ms","start":"2026-06-30T17:55:50.91731Z","end":"2026-06-30T17:55:51.19322Z","steps":["trace[637418566] 'process raft request' (duration: 275.764286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:51.193708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.365288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:55:51.19386Z","caller":"traceutil/trace.go:171","msg":"trace[136039197] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4646; }","duration":"425.477291ms","start":"2026-06-30T17:55:50.768272Z","end":"2026-06-30T17:55:51.193749Z","steps":["trace[136039197] 'agreement among raft nodes before linearized reading' (duration: 425.243313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:51.193919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:50.768242Z","time spent":"425.659577ms","remote":"127.0.0.1:32994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-30T17:55:51.194334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.725046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-06-30T17:55:51.194376Z","caller":"traceutil/trace.go:171","msg":"trace[687156679] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4646; }","duration":"336.81824ms","start":"2026-06-30T17:55:50.857543Z","end":"2026-06-30T17:55:51.194361Z","steps":["trace[687156679] 'agreement among raft nodes before linearized reading' (duration: 336.692766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:51.194409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:50.857514Z","time spent":"336.887072ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-06-30T17:55:51.194583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.836625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bbbb0c2d-119e-4e37-b14b-64f8fb07fe30\" ","response":"range_response_count:1 size:3772"} {"level":"info","ts":"2026-06-30T17:55:51.194611Z","caller":"traceutil/trace.go:171","msg":"trace[1206115205] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bbbb0c2d-119e-4e37-b14b-64f8fb07fe30; range_end:; response_count:1; response_revision:4646; }","duration":"365.905667ms","start":"2026-06-30T17:55:50.828697Z","end":"2026-06-30T17:55:51.194602Z","steps":["trace[1206115205] 'agreement among raft nodes before linearized reading' (duration: 365.822364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:51.194637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:55:50.828674Z","time spent":"365.955459ms","remote":"127.0.0.1:42724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3795,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bbbb0c2d-119e-4e37-b14b-64f8fb07fe30\" "} {"level":"warn","ts":"2026-06-30T17:55:51.194739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.077306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362280"} {"level":"info","ts":"2026-06-30T17:55:51.194775Z","caller":"traceutil/trace.go:171","msg":"trace[1535650466] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4646; }","duration":"276.114848ms","start":"2026-06-30T17:55:50.918649Z","end":"2026-06-30T17:55:51.194764Z","steps":["trace[1535650466] 'agreement among raft nodes before linearized reading' (duration: 275.368322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:51.195415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.621066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-06-30T17:55:51.195439Z","caller":"traceutil/trace.go:171","msg":"trace[1908212769] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:4646; }","duration":"122.705659ms","start":"2026-06-30T17:55:51.072727Z","end":"2026-06-30T17:55:51.195432Z","steps":["trace[1908212769] 'agreement among raft nodes before linearized reading' (duration: 122.630576ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:53.933446Z","caller":"traceutil/trace.go:171","msg":"trace[1210656931] linearizableReadLoop","detail":"{readStateIndex:4866; appliedIndex:4865; }","duration":"196.5491ms","start":"2026-06-30T17:55:53.736877Z","end":"2026-06-30T17:55:53.933426Z","steps":["trace[1210656931] 'read index received' (duration: 196.491638ms)","trace[1210656931] 'applied index is now lower than readState.Index' (duration: 56.092µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:53.933588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.696515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:55:53.933633Z","caller":"traceutil/trace.go:171","msg":"trace[1340972894] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:4686; }","duration":"196.83137ms","start":"2026-06-30T17:55:53.736788Z","end":"2026-06-30T17:55:53.933619Z","steps":["trace[1340972894] 'agreement among raft nodes before linearized reading' (duration: 196.726657ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:53.933945Z","caller":"traceutil/trace.go:171","msg":"trace[900594820] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"204.692051ms","start":"2026-06-30T17:55:53.729219Z","end":"2026-06-30T17:55:53.933911Z","steps":["trace[900594820] 'process raft request' (duration: 204.088791ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:53.962199Z","caller":"traceutil/trace.go:171","msg":"trace[549919772] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"166.892905ms","start":"2026-06-30T17:55:53.795287Z","end":"2026-06-30T17:55:53.96218Z","steps":["trace[549919772] 'process raft request' (duration: 166.761491ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:55.266268Z","caller":"traceutil/trace.go:171","msg":"trace[218098335] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"131.526784ms","start":"2026-06-30T17:55:55.134719Z","end":"2026-06-30T17:55:55.266246Z","steps":["trace[218098335] 'process raft request' (duration: 88.503071ms)","trace[218098335] 'compare' (duration: 42.897619ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:55.266263Z","caller":"traceutil/trace.go:171","msg":"trace[2012046526] linearizableReadLoop","detail":"{readStateIndex:4890; appliedIndex:4888; }","duration":"128.667876ms","start":"2026-06-30T17:55:55.137571Z","end":"2026-06-30T17:55:55.266239Z","steps":["trace[2012046526] 'read index received' (duration: 13.658314ms)","trace[2012046526] 'applied index is now lower than readState.Index' (duration: 115.008332ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:55.266374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.7869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:55:55.266429Z","caller":"traceutil/trace.go:171","msg":"trace[1909209853] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4709; }","duration":"128.886423ms","start":"2026-06-30T17:55:55.13753Z","end":"2026-06-30T17:55:55.266417Z","steps":["trace[1909209853] 'agreement among raft nodes before linearized reading' (duration: 128.753509ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:55.372618Z","caller":"traceutil/trace.go:171","msg":"trace[970846254] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"209.791911ms","start":"2026-06-30T17:55:55.162796Z","end":"2026-06-30T17:55:55.372588Z","steps":["trace[970846254] 'process raft request' (duration: 203.585596ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:55.372886Z","caller":"traceutil/trace.go:171","msg":"trace[1540665621] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4710; }","duration":"208.512967ms","start":"2026-06-30T17:55:55.164348Z","end":"2026-06-30T17:55:55.372861Z","steps":["trace[1540665621] 'process raft request' (duration: 208.170005ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:55.372602Z","caller":"traceutil/trace.go:171","msg":"trace[39072491] linearizableReadLoop","detail":"{readStateIndex:4892; appliedIndex:4890; }","duration":"106.187796ms","start":"2026-06-30T17:55:55.266387Z","end":"2026-06-30T17:55:55.372575Z","steps":["trace[39072491] 'read index received' (duration: 99.912678ms)","trace[39072491] 'applied index is now lower than readState.Index' (duration: 6.273648ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:55.373185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.67362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cb6657b7-0943-4b6b-9161-e9f4ffb7b9e1\" ","response":"range_response_count:1 size:4063"} {"level":"warn","ts":"2026-06-30T17:55:55.373369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.981832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-qrnws-tls\" ","response":"range_response_count:1 size:1580"} {"level":"info","ts":"2026-06-30T17:55:55.373433Z","caller":"traceutil/trace.go:171","msg":"trace[489249211] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-qrnws-tls; range_end:; response_count:1; response_revision:4710; }","duration":"213.084776ms","start":"2026-06-30T17:55:55.160329Z","end":"2026-06-30T17:55:55.373413Z","steps":["trace[489249211] 'agreement among raft nodes before linearized reading' (duration: 212.966752ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:55.373395Z","caller":"traceutil/trace.go:171","msg":"trace[497514584] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cb6657b7-0943-4b6b-9161-e9f4ffb7b9e1; range_end:; response_count:1; response_revision:4710; }","duration":"114.922449ms","start":"2026-06-30T17:55:55.258439Z","end":"2026-06-30T17:55:55.373362Z","steps":["trace[497514584] 'agreement among raft nodes before linearized reading' (duration: 114.628719ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:55.842534Z","caller":"traceutil/trace.go:171","msg":"trace[621745695] linearizableReadLoop","detail":"{readStateIndex:4926; appliedIndex:4924; }","duration":"203.587166ms","start":"2026-06-30T17:55:55.638923Z","end":"2026-06-30T17:55:55.84251Z","steps":["trace[621745695] 'read index received' (duration: 144.668101ms)","trace[621745695] 'applied index is now lower than readState.Index' (duration: 58.918225ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:55.84267Z","caller":"traceutil/trace.go:171","msg":"trace[213340330] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4743; }","duration":"204.540589ms","start":"2026-06-30T17:55:55.638116Z","end":"2026-06-30T17:55:55.842656Z","steps":["trace[213340330] 'process raft request' (duration: 204.360883ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:55.842643Z","caller":"traceutil/trace.go:171","msg":"trace[108577204] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"204.861861ms","start":"2026-06-30T17:55:55.637728Z","end":"2026-06-30T17:55:55.842589Z","steps":["trace[108577204] 'process raft request' (duration: 145.852283ms)","trace[108577204] 'compare' (duration: 58.78207ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:55.842983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.789912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:55:55.843036Z","caller":"traceutil/trace.go:171","msg":"trace[1095130360] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:4743; }","duration":"137.865755ms","start":"2026-06-30T17:55:55.705159Z","end":"2026-06-30T17:55:55.843024Z","steps":["trace[1095130360] 'agreement among raft nodes before linearized reading' (duration: 137.663678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:55.843067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.124976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-06-30T17:55:55.843125Z","caller":"traceutil/trace.go:171","msg":"trace[443148733] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4743; }","duration":"204.28079ms","start":"2026-06-30T17:55:55.638831Z","end":"2026-06-30T17:55:55.843112Z","steps":["trace[443148733] 'agreement among raft nodes before linearized reading' (duration: 204.091113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:56.009835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.057057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-06-30T17:55:56.009915Z","caller":"traceutil/trace.go:171","msg":"trace[715620394] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4745; }","duration":"156.164211ms","start":"2026-06-30T17:55:55.85373Z","end":"2026-06-30T17:55:56.009895Z","steps":["trace[715620394] 'agreement among raft nodes before linearized reading' (duration: 155.968944ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:56.009904Z","caller":"traceutil/trace.go:171","msg":"trace[1899619818] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"147.666986ms","start":"2026-06-30T17:55:55.862207Z","end":"2026-06-30T17:55:56.009874Z","steps":["trace[1899619818] 'process raft request' (duration: 134.049343ms)","trace[1899619818] 'compare' (duration: 13.316162ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:55:56.010276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.792925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-06-30T17:55:56.010323Z","caller":"traceutil/trace.go:171","msg":"trace[975420548] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4745; }","duration":"131.864767ms","start":"2026-06-30T17:55:55.878447Z","end":"2026-06-30T17:55:56.010311Z","steps":["trace[975420548] 'agreement among raft nodes before linearized reading' (duration: 131.725132ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:56.172403Z","caller":"traceutil/trace.go:171","msg":"trace[1820794329] linearizableReadLoop","detail":"{readStateIndex:4931; appliedIndex:4930; }","duration":"134.591192ms","start":"2026-06-30T17:55:56.037787Z","end":"2026-06-30T17:55:56.172378Z","steps":["trace[1820794329] 'read index received' (duration: 134.359084ms)","trace[1820794329] 'applied index is now lower than readState.Index' (duration: 230.808µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:56.17276Z","caller":"traceutil/trace.go:171","msg":"trace[664234010] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"134.977585ms","start":"2026-06-30T17:55:56.037756Z","end":"2026-06-30T17:55:56.172733Z","steps":["trace[664234010] 'process raft request' (duration: 134.411896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:56.173034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.218524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-06-30T17:55:56.173124Z","caller":"traceutil/trace.go:171","msg":"trace[636354614] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4748; }","duration":"135.323607ms","start":"2026-06-30T17:55:56.037774Z","end":"2026-06-30T17:55:56.173098Z","steps":["trace[636354614] 'agreement among raft nodes before linearized reading' (duration: 134.970855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:56.173237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.440162ms","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-06-30T17:55:56.173285Z","caller":"traceutil/trace.go:171","msg":"trace[843821086] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4748; }","duration":"133.604158ms","start":"2026-06-30T17:55:56.039669Z","end":"2026-06-30T17:55:56.173273Z","steps":["trace[843821086] 'agreement among raft nodes before linearized reading' (duration: 133.35755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:56.17366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.302957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:55:56.173757Z","caller":"traceutil/trace.go:171","msg":"trace[1142824533] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:4748; }","duration":"135.6897ms","start":"2026-06-30T17:55:56.038045Z","end":"2026-06-30T17:55:56.173734Z","steps":["trace[1142824533] 'agreement among raft nodes before linearized reading' (duration: 135.184543ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:58.86703Z","caller":"traceutil/trace.go:171","msg":"trace[1881111085] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"139.213725ms","start":"2026-06-30T17:55:58.727788Z","end":"2026-06-30T17:55:58.867002Z","steps":["trace[1881111085] 'process raft request' (duration: 138.902974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:55:58.867188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.410091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:55:58.867278Z","caller":"traceutil/trace.go:171","msg":"trace[1945908838] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4772; }","duration":"137.583618ms","start":"2026-06-30T17:55:58.729662Z","end":"2026-06-30T17:55:58.867246Z","steps":["trace[1945908838] 'agreement among raft nodes before linearized reading' (duration: 137.434612ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:55:58.869008Z","caller":"traceutil/trace.go:171","msg":"trace[437421257] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4955; }","duration":"137.176104ms","start":"2026-06-30T17:55:58.729759Z","end":"2026-06-30T17:55:58.866936Z","steps":["trace[437421257] 'read index received' (duration: 136.817781ms)","trace[437421257] 'applied index is now lower than readState.Index' (duration: 357.043µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:55:58.883909Z","caller":"traceutil/trace.go:171","msg":"trace[1686253516] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"152.25057ms","start":"2026-06-30T17:55:58.731635Z","end":"2026-06-30T17:55:58.883886Z","steps":["trace[1686253516] 'process raft request' (duration: 151.438282ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:01.390582Z","caller":"traceutil/trace.go:171","msg":"trace[1590128972] linearizableReadLoop","detail":"{readStateIndex:5026; appliedIndex:5025; }","duration":"266.357848ms","start":"2026-06-30T17:56:01.124194Z","end":"2026-06-30T17:56:01.390551Z","steps":["trace[1590128972] 'read index received' (duration: 240.791699ms)","trace[1590128972] 'applied index is now lower than readState.Index' (duration: 25.564189ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:56:01.391247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.036112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T17:56:01.39135Z","caller":"traceutil/trace.go:171","msg":"trace[1901530718] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4841; }","duration":"267.198808ms","start":"2026-06-30T17:56:01.124136Z","end":"2026-06-30T17:56:01.391335Z","steps":["trace[1901530718] 'agreement among raft nodes before linearized reading' (duration: 266.992111ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:01.391423Z","caller":"traceutil/trace.go:171","msg":"trace[1143764735] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"296.991795ms","start":"2026-06-30T17:56:01.093703Z","end":"2026-06-30T17:56:01.390695Z","steps":["trace[1143764735] 'process raft request' (duration: 271.195428ms)","trace[1143764735] 'compare' (duration: 25.430245ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:01.731522Z","caller":"traceutil/trace.go:171","msg":"trace[1963853175] linearizableReadLoop","detail":"{readStateIndex:5027; appliedIndex:5026; }","duration":"340.846499ms","start":"2026-06-30T17:56:01.390652Z","end":"2026-06-30T17:56:01.731499Z","steps":["trace[1963853175] 'read index received' (duration: 340.456285ms)","trace[1963853175] 'applied index is now lower than readState.Index' (duration: 389.274µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:01.731616Z","caller":"traceutil/trace.go:171","msg":"trace[1621947297] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"395.445479ms","start":"2026-06-30T17:56:01.336134Z","end":"2026-06-30T17:56:01.731579Z","steps":["trace[1621947297] 'process raft request' (duration: 395.038494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:01.731748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.98416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cda019d8-c8cc-4175-b4cb-4c343601cde2\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-30T17:56:01.731824Z","caller":"traceutil/trace.go:171","msg":"trace[2076370792] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cda019d8-c8cc-4175-b4cb-4c343601cde2; range_end:; response_count:1; response_revision:4842; }","duration":"522.117875ms","start":"2026-06-30T17:56:01.209688Z","end":"2026-06-30T17:56:01.731806Z","steps":["trace[2076370792] 'agreement among raft nodes before linearized reading' (duration: 521.900127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:01.731883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:01.209659Z","time spent":"522.213668ms","remote":"127.0.0.1:42724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cda019d8-c8cc-4175-b4cb-4c343601cde2\" "} {"level":"warn","ts":"2026-06-30T17:56:01.732048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.655509ms","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-06-30T17:56:01.732121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.416277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:56:01.732159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.329054ms","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-06-30T17:56:01.732188Z","caller":"traceutil/trace.go:171","msg":"trace[2126534360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4842; }","duration":"103.50183ms","start":"2026-06-30T17:56:01.628669Z","end":"2026-06-30T17:56:01.732171Z","steps":["trace[2126534360] 'agreement among raft nodes before linearized reading' (duration: 103.431488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:01.731855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.958497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:420"} {"level":"info","ts":"2026-06-30T17:56:01.732221Z","caller":"traceutil/trace.go:171","msg":"trace[1882732157] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4842; }","duration":"246.444409ms","start":"2026-06-30T17:56:01.485764Z","end":"2026-06-30T17:56:01.732209Z","steps":["trace[1882732157] 'agreement among raft nodes before linearized reading' (duration: 246.242432ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:01.732269Z","caller":"traceutil/trace.go:171","msg":"trace[1850870531] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4842; }","duration":"338.490776ms","start":"2026-06-30T17:56:01.39376Z","end":"2026-06-30T17:56:01.732251Z","steps":["trace[1850870531] 'agreement among raft nodes before linearized reading' (duration: 337.991589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:01.732305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.480249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-06-30T17:56:01.73238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:01.393742Z","time spent":"338.595129ms","remote":"127.0.0.1:42712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-06-30T17:56:01.73212Z","caller":"traceutil/trace.go:171","msg":"trace[1623497481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4842; }","duration":"210.745372ms","start":"2026-06-30T17:56:01.521361Z","end":"2026-06-30T17:56:01.732107Z","steps":["trace[1623497481] 'agreement among raft nodes before linearized reading' (duration: 210.582936ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:01.732406Z","caller":"traceutil/trace.go:171","msg":"trace[322257045] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4842; }","duration":"329.602343ms","start":"2026-06-30T17:56:01.402787Z","end":"2026-06-30T17:56:01.73239Z","steps":["trace[322257045] 'agreement among raft nodes before linearized reading' (duration: 329.354484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:01.732573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:01.402771Z","time spent":"329.7869ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-06-30T17:56:01.73202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.88574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cda019d8-c8cc-4175-b4cb-4c343601cde2\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-30T17:56:01.732677Z","caller":"traceutil/trace.go:171","msg":"trace[602649349] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cda019d8-c8cc-4175-b4cb-4c343601cde2; range_end:; response_count:1; response_revision:4842; }","duration":"200.568825ms","start":"2026-06-30T17:56:01.532094Z","end":"2026-06-30T17:56:01.732663Z","steps":["trace[602649349] 'agreement among raft nodes before linearized reading' (duration: 199.774627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:01.732757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:01.336107Z","time spent":"395.608454ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:56:03.916085Z","caller":"traceutil/trace.go:171","msg":"trace[2105663327] linearizableReadLoop","detail":"{readStateIndex:5052; appliedIndex:5051; }","duration":"116.161523ms","start":"2026-06-30T17:56:03.799889Z","end":"2026-06-30T17:56:03.916051Z","steps":["trace[2105663327] 'read index received' (duration: 114.990721ms)","trace[2105663327] 'applied index is now lower than readState.Index' (duration: 1.168342ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:56:03.916502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.050839ms","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-06-30T17:56:03.916581Z","caller":"traceutil/trace.go:171","msg":"trace[1576319804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4867; }","duration":"116.150623ms","start":"2026-06-30T17:56:03.800412Z","end":"2026-06-30T17:56:03.916563Z","steps":["trace[1576319804] 'agreement among raft nodes before linearized reading' (duration: 115.961236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:03.916618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.705743ms","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-06-30T17:56:03.916696Z","caller":"traceutil/trace.go:171","msg":"trace[1432299928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4867; }","duration":"116.866908ms","start":"2026-06-30T17:56:03.799813Z","end":"2026-06-30T17:56:03.91668Z","steps":["trace[1432299928] 'agreement among raft nodes before linearized reading' (duration: 116.658841ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:03.916261Z","caller":"traceutil/trace.go:171","msg":"trace[1608986546] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"156.094114ms","start":"2026-06-30T17:56:03.760149Z","end":"2026-06-30T17:56:03.916243Z","steps":["trace[1608986546] 'process raft request' (duration: 154.822869ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:13.264721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.402747ms","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-06-30T17:56:13.264795Z","caller":"traceutil/trace.go:171","msg":"trace[1658960003] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4996; }","duration":"292.518691ms","start":"2026-06-30T17:56:12.972261Z","end":"2026-06-30T17:56:13.264779Z","steps":["trace[1658960003] 'range keys from in-memory index tree' (duration: 292.169958ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:13.386291Z","caller":"traceutil/trace.go:171","msg":"trace[2070608839] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"117.773764ms","start":"2026-06-30T17:56:13.268497Z","end":"2026-06-30T17:56:13.38627Z","steps":["trace[2070608839] 'process raft request' (duration: 117.642129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:18.56863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.672164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-06-30T17:56:18.568735Z","caller":"traceutil/trace.go:171","msg":"trace[277668874] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5033; }","duration":"541.859951ms","start":"2026-06-30T17:56:18.026849Z","end":"2026-06-30T17:56:18.568709Z","steps":["trace[277668874] 'range keys from in-memory index tree' (duration: 541.495358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:18.568784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:18.026827Z","time spent":"541.942834ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-06-30T17:56:18.568991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.384611ms","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-06-30T17:56:18.569066Z","caller":"traceutil/trace.go:171","msg":"trace[165727605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5033; }","duration":"437.491025ms","start":"2026-06-30T17:56:18.131558Z","end":"2026-06-30T17:56:18.569049Z","steps":["trace[165727605] 'range keys from in-memory index tree' (duration: 437.198114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:18.569103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:18.13154Z","time spent":"437.555927ms","remote":"127.0.0.1:42786","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":"warn","ts":"2026-06-30T17:56:18.569286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.101993ms","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-06-30T17:56:18.569447Z","caller":"traceutil/trace.go:171","msg":"trace[671875976] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:5033; }","duration":"794.263558ms","start":"2026-06-30T17:56:17.775134Z","end":"2026-06-30T17:56:18.569398Z","steps":["trace[671875976] 'count revisions from in-memory index tree' (duration: 794.015769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:18.569584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:17.775113Z","time spent":"794.459516ms","remote":"127.0.0.1:57248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-06-30T17:56:18.56934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.153012ms","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-06-30T17:56:18.569928Z","caller":"traceutil/trace.go:171","msg":"trace[1460573836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5033; }","duration":"429.801195ms","start":"2026-06-30T17:56:18.140116Z","end":"2026-06-30T17:56:18.569917Z","steps":["trace[1460573836] 'range keys from in-memory index tree' (duration: 429.015056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:18.57002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:18.140084Z","time spent":"429.925709ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-30T17:56:18.570073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.346454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10390"} {"level":"info","ts":"2026-06-30T17:56:18.57014Z","caller":"traceutil/trace.go:171","msg":"trace[1126060387] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5033; }","duration":"861.450858ms","start":"2026-06-30T17:56:17.708673Z","end":"2026-06-30T17:56:18.570123Z","steps":["trace[1126060387] 'range keys from in-memory index tree' (duration: 861.206919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:18.570193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:17.708652Z","time spent":"861.52293ms","remote":"127.0.0.1:42724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10413,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-30T17:56:18.569798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053552842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-30T17:56:18.570067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.237304ms","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-06-30T17:56:18.570383Z","caller":"traceutil/trace.go:171","msg":"trace[914448553] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5033; }","duration":"1.054038011s","start":"2026-06-30T17:56:17.516203Z","end":"2026-06-30T17:56:18.570241Z","steps":["trace[914448553] 'range keys from in-memory index tree' (duration: 1.05348398s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:18.570497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:17.516183Z","time spent":"1.05429528s","remote":"127.0.0.1:42998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-30T17:56:18.569838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024907223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T17:56:18.570716Z","caller":"traceutil/trace.go:171","msg":"trace[1697032763] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:5033; }","duration":"1.025798985s","start":"2026-06-30T17:56:17.544889Z","end":"2026-06-30T17:56:18.570688Z","steps":["trace[1697032763] 'count revisions from in-memory index tree' (duration: 1.024843831s)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:18.570808Z","caller":"traceutil/trace.go:171","msg":"trace[662716378] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5033; }","duration":"830.583557ms","start":"2026-06-30T17:56:17.739767Z","end":"2026-06-30T17:56:18.57035Z","steps":["trace[662716378] 'range keys from in-memory index tree' (duration: 830.055258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:18.570845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:17.544869Z","time spent":"1.025962801s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-06-30T17:56:18.570906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:17.739755Z","time spent":"831.135077ms","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-06-30T17:56:18.573169Z","caller":"traceutil/trace.go:171","msg":"trace[321306384] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"311.785222ms","start":"2026-06-30T17:56:18.261368Z","end":"2026-06-30T17:56:18.573154Z","steps":["trace[321306384] 'process raft request' (duration: 311.523972ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:18.573356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T17:56:18.261334Z","time spent":"311.940187ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T17:56:18.806565Z","caller":"traceutil/trace.go:171","msg":"trace[1668236190] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"144.988494ms","start":"2026-06-30T17:56:18.66155Z","end":"2026-06-30T17:56:18.806538Z","steps":["trace[1668236190] 'process raft request' (duration: 144.929202ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:18.806737Z","caller":"traceutil/trace.go:171","msg":"trace[9129931] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"145.180292ms","start":"2026-06-30T17:56:18.661534Z","end":"2026-06-30T17:56:18.806714Z","steps":["trace[9129931] 'process raft request' (duration: 144.705694ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:18.832381Z","caller":"traceutil/trace.go:171","msg":"trace[398212777] linearizableReadLoop","detail":"{readStateIndex:5235; appliedIndex:5230; }","duration":"162.4551ms","start":"2026-06-30T17:56:18.669896Z","end":"2026-06-30T17:56:18.832351Z","steps":["trace[398212777] 'read index received' (duration: 136.483026ms)","trace[398212777] 'applied index is now lower than readState.Index' (duration: 25.970744ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:18.832492Z","caller":"traceutil/trace.go:171","msg":"trace[883184909] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"168.920815ms","start":"2026-06-30T17:56:18.663529Z","end":"2026-06-30T17:56:18.83245Z","steps":["trace[883184909] 'process raft request' (duration: 168.660285ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:18.832768Z","caller":"traceutil/trace.go:171","msg":"trace[2007044682] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"163.875172ms","start":"2026-06-30T17:56:18.668868Z","end":"2026-06-30T17:56:18.832743Z","steps":["trace[2007044682] 'process raft request' (duration: 163.403834ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:18.832921Z","caller":"traceutil/trace.go:171","msg":"trace[1962447580] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"171.295121ms","start":"2026-06-30T17:56:18.661582Z","end":"2026-06-30T17:56:18.832877Z","steps":["trace[1962447580] 'process raft request' (duration: 170.292785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:18.832954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.029541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-06-30T17:56:18.833205Z","caller":"traceutil/trace.go:171","msg":"trace[198996630] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5044; }","duration":"163.313462ms","start":"2026-06-30T17:56:18.669869Z","end":"2026-06-30T17:56:18.833183Z","steps":["trace[198996630] 'agreement among raft nodes before linearized reading' (duration: 162.921947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T17:56:19.125255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.781779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:56:19.125375Z","caller":"traceutil/trace.go:171","msg":"trace[1173743561] linearizableReadLoop","detail":"{readStateIndex:5237; appliedIndex:5236; }","duration":"166.907529ms","start":"2026-06-30T17:56:18.958452Z","end":"2026-06-30T17:56:19.12536Z","steps":["trace[1173743561] 'read index received' (duration: 24.781µs)","trace[1173743561] 'applied index is now lower than readState.Index' (duration: 166.881538ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:19.125633Z","caller":"traceutil/trace.go:171","msg":"trace[1442580384] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"278.942425ms","start":"2026-06-30T17:56:18.846656Z","end":"2026-06-30T17:56:19.125599Z","steps":["trace[1442580384] 'process raft request' (duration: 32.72508ms)","trace[1442580384] 'compare' (duration: 245.630954ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:56:19.125745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.289234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-30T17:56:19.125843Z","caller":"traceutil/trace.go:171","msg":"trace[999251395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5046; }","duration":"167.406377ms","start":"2026-06-30T17:56:18.958419Z","end":"2026-06-30T17:56:19.125825Z","steps":["trace[999251395] 'agreement among raft nodes before linearized reading' (duration: 166.982452ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:36.622368Z","caller":"traceutil/trace.go:171","msg":"trace[1084260684] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"118.373838ms","start":"2026-06-30T17:56:36.503955Z","end":"2026-06-30T17:56:36.622329Z","steps":["trace[1084260684] 'process raft request' (duration: 65.176614ms)","trace[1084260684] 'compare' (duration: 52.975866ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:56:40.874982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.243571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T17:56:40.875081Z","caller":"traceutil/trace.go:171","msg":"trace[1032910911] linearizableReadLoop","detail":"{readStateIndex:5396; appliedIndex:5395; }","duration":"162.179958ms","start":"2026-06-30T17:56:40.712885Z","end":"2026-06-30T17:56:40.875065Z","steps":["trace[1032910911] 'read index received' (duration: 39.706888ms)","trace[1032910911] 'applied index is now lower than readState.Index' (duration: 122.47197ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:40.87513Z","caller":"traceutil/trace.go:171","msg":"trace[2045721496] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"164.6309ms","start":"2026-06-30T17:56:40.710475Z","end":"2026-06-30T17:56:40.875106Z","steps":["trace[2045721496] 'process raft request' (duration: 42.183901ms)","trace[2045721496] 'compare' (duration: 122.121987ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T17:56:40.875276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.388445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-06-30T17:56:40.875311Z","caller":"traceutil/trace.go:171","msg":"trace[232539686] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5201; }","duration":"162.451697ms","start":"2026-06-30T17:56:40.712846Z","end":"2026-06-30T17:56:40.875298Z","steps":["trace[232539686] 'agreement among raft nodes before linearized reading' (duration: 162.301602ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:50.862065Z","caller":"traceutil/trace.go:171","msg":"trace[615685753] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"122.209866ms","start":"2026-06-30T17:56:50.73983Z","end":"2026-06-30T17:56:50.86204Z","steps":["trace[615685753] 'process raft request' (duration: 122.011258ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:51.029189Z","caller":"traceutil/trace.go:171","msg":"trace[1222767526] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"105.865141ms","start":"2026-06-30T17:56:50.923307Z","end":"2026-06-30T17:56:51.029172Z","steps":["trace[1222767526] 'process raft request' (duration: 105.217196ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:57.208328Z","caller":"traceutil/trace.go:171","msg":"trace[1969510941] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"122.756109ms","start":"2026-06-30T17:56:57.085537Z","end":"2026-06-30T17:56:57.208294Z","steps":["trace[1969510941] 'process raft request' (duration: 91.325906ms)","trace[1969510941] 'compare' (duration: 31.123792ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:56:57.208885Z","caller":"traceutil/trace.go:171","msg":"trace[140172516] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"122.534781ms","start":"2026-06-30T17:56:57.086317Z","end":"2026-06-30T17:56:57.208851Z","steps":["trace[140172516] 'process raft request' (duration: 121.901177ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:57.208946Z","caller":"traceutil/trace.go:171","msg":"trace[877247300] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"121.506591ms","start":"2026-06-30T17:56:57.08742Z","end":"2026-06-30T17:56:57.208926Z","steps":["trace[877247300] 'process raft request' (duration: 121.207659ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:58.137285Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2818} {"level":"info","ts":"2026-06-30T17:56:58.225971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2818,"took":"86.582927ms","hash":1169296205,"current-db-size-bytes":53698560,"current-db-size":"54 MB","current-db-size-in-use-bytes":28901376,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-30T17:56:58.226043Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1169296205,"revision":2818,"compact-revision":-1} {"level":"info","ts":"2026-06-30T17:56:59.351392Z","caller":"traceutil/trace.go:171","msg":"trace[1385620311] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"126.481246ms","start":"2026-06-30T17:56:59.22487Z","end":"2026-06-30T17:56:59.351351Z","steps":["trace[1385620311] 'process raft request' (duration: 126.167944ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:59.381546Z","caller":"traceutil/trace.go:171","msg":"trace[681245936] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"155.514919ms","start":"2026-06-30T17:56:59.225986Z","end":"2026-06-30T17:56:59.381501Z","steps":["trace[681245936] 'process raft request' (duration: 155.252339ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:56:59.381591Z","caller":"traceutil/trace.go:171","msg":"trace[919359447] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"155.201717ms","start":"2026-06-30T17:56:59.226357Z","end":"2026-06-30T17:56:59.381558Z","steps":["trace[919359447] 'process raft request' (duration: 155.070592ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:57:02.166024Z","caller":"traceutil/trace.go:171","msg":"trace[1139181548] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"121.068273ms","start":"2026-06-30T17:57:02.044899Z","end":"2026-06-30T17:57:02.165968Z","steps":["trace[1139181548] 'process raft request' (duration: 72.938753ms)","trace[1139181548] 'compare' (duration: 47.915592ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:57:06.238773Z","caller":"traceutil/trace.go:171","msg":"trace[1948828744] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"104.414685ms","start":"2026-06-30T17:57:06.134318Z","end":"2026-06-30T17:57:06.238733Z","steps":["trace[1948828744] 'process raft request' (duration: 103.970868ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:57:09.225294Z","caller":"traceutil/trace.go:171","msg":"trace[40206342] transaction","detail":"{read_only:false; response_revision:5476; number_of_response:1; }","duration":"102.173432ms","start":"2026-06-30T17:57:09.123083Z","end":"2026-06-30T17:57:09.225256Z","steps":["trace[40206342] 'process raft request' (duration: 67.462692ms)","trace[40206342] 'compare' (duration: 34.477141ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:57:25.90553Z","caller":"traceutil/trace.go:171","msg":"trace[1779598329] transaction","detail":"{read_only:false; response_revision:5611; number_of_response:1; }","duration":"112.287973ms","start":"2026-06-30T17:57:25.793195Z","end":"2026-06-30T17:57:25.905483Z","steps":["trace[1779598329] 'process raft request' (duration: 61.52509ms)","trace[1779598329] 'compare' (duration: 50.529824ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:57:31.457842Z","caller":"traceutil/trace.go:171","msg":"trace[1212220710] transaction","detail":"{read_only:false; response_revision:5645; number_of_response:1; }","duration":"276.986627ms","start":"2026-06-30T17:57:31.180817Z","end":"2026-06-30T17:57:31.457804Z","steps":["trace[1212220710] 'process raft request' (duration: 229.964874ms)","trace[1212220710] 'compare' (duration: 46.639098ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T17:57:31.512401Z","caller":"traceutil/trace.go:171","msg":"trace[1184452112] transaction","detail":"{read_only:false; response_revision:5646; number_of_response:1; }","duration":"173.570604ms","start":"2026-06-30T17:57:31.338807Z","end":"2026-06-30T17:57:31.512378Z","steps":["trace[1184452112] 'process raft request' (duration: 173.440829ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:57:31.51632Z","caller":"traceutil/trace.go:171","msg":"trace[2092424468] transaction","detail":"{read_only:false; response_revision:5647; number_of_response:1; }","duration":"102.407993ms","start":"2026-06-30T17:57:31.413881Z","end":"2026-06-30T17:57:31.516289Z","steps":["trace[2092424468] 'process raft request' (duration: 102.222395ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:59:18.601069Z","caller":"traceutil/trace.go:171","msg":"trace[1948569677] transaction","detail":"{read_only:false; response_revision:6283; number_of_response:1; }","duration":"176.117164ms","start":"2026-06-30T17:59:18.424881Z","end":"2026-06-30T17:59:18.600998Z","steps":["trace[1948569677] 'process raft request' (duration: 176.054341ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T17:59:18.601432Z","caller":"traceutil/trace.go:171","msg":"trace[1032724651] transaction","detail":"{read_only:false; response_revision:6282; number_of_response:1; }","duration":"178.110559ms","start":"2026-06-30T17:59:18.423268Z","end":"2026-06-30T17:59:18.601378Z","steps":["trace[1032724651] 'process raft request' (duration: 102.8498ms)","trace[1032724651] 'compare' (duration: 74.605781ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:00:02.55025Z","caller":"traceutil/trace.go:171","msg":"trace[821487174] transaction","detail":"{read_only:false; response_revision:6663; number_of_response:1; }","duration":"145.455349ms","start":"2026-06-30T18:00:02.404755Z","end":"2026-06-30T18:00:02.55021Z","steps":["trace[821487174] 'process raft request' (duration: 145.23696ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:00:16.879681Z","caller":"traceutil/trace.go:171","msg":"trace[1155281043] linearizableReadLoop","detail":"{readStateIndex:7040; appliedIndex:7039; }","duration":"103.483344ms","start":"2026-06-30T18:00:16.776167Z","end":"2026-06-30T18:00:16.879651Z","steps":["trace[1155281043] 'read index received' (duration: 103.201712ms)","trace[1155281043] 'applied index is now lower than readState.Index' (duration: 280.622µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:00:16.879868Z","caller":"traceutil/trace.go:171","msg":"trace[600600868] transaction","detail":"{read_only:false; response_revision:6789; number_of_response:1; }","duration":"184.616466ms","start":"2026-06-30T18:00:16.695231Z","end":"2026-06-30T18:00:16.879848Z","steps":["trace[600600868] 'process raft request' (duration: 184.216119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:00:16.880369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.160214ms","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-06-30T18:00:16.884321Z","caller":"traceutil/trace.go:171","msg":"trace[1590594185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6789; }","duration":"108.187919ms","start":"2026-06-30T18:00:16.776116Z","end":"2026-06-30T18:00:16.884304Z","steps":["trace[1590594185] 'agreement among raft nodes before linearized reading' (duration: 104.139983ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:01:58.143721Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5395} {"level":"info","ts":"2026-06-30T18:01:58.22955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5395,"took":"83.914263ms","hash":828605968,"current-db-size-bytes":53698560,"current-db-size":"54 MB","current-db-size-in-use-bytes":18616320,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-06-30T18:01:58.229661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":828605968,"revision":5395,"compact-revision":2818} {"level":"warn","ts":"2026-06-30T18:02:56.233668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.637528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:02:56.234024Z","caller":"traceutil/trace.go:171","msg":"trace[1414835566] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:0; response_revision:7620; }","duration":"187.039475ms","start":"2026-06-30T18:02:56.04697Z","end":"2026-06-30T18:02:56.23401Z","steps":["trace[1414835566] 'range keys from in-memory index tree' (duration: 186.555006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:02:56.233706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.872808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/auth-system/keycloak\" ","response":"range_response_count:1 size:905"} {"level":"info","ts":"2026-06-30T18:02:56.234161Z","caller":"traceutil/trace.go:171","msg":"trace[328633269] range","detail":"{range_begin:/registry/serviceaccounts/auth-system/keycloak; range_end:; response_count:1; response_revision:7620; }","duration":"134.351187ms","start":"2026-06-30T18:02:56.09979Z","end":"2026-06-30T18:02:56.234141Z","steps":["trace[328633269] 'range keys from in-memory index tree' (duration: 133.767715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:02:56.233744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.349174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/auth-system/default\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-06-30T18:02:56.234326Z","caller":"traceutil/trace.go:171","msg":"trace[1560159588] range","detail":"{range_begin:/registry/serviceaccounts/auth-system/default; range_end:; response_count:1; response_revision:7620; }","duration":"157.929205ms","start":"2026-06-30T18:02:56.076377Z","end":"2026-06-30T18:02:56.234306Z","steps":["trace[1560159588] 'range keys from in-memory index tree' (duration: 157.175411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:02:56.233798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.841503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-30T18:02:56.234428Z","caller":"traceutil/trace.go:171","msg":"trace[1037497231] range","detail":"{range_begin:/registry/rolebindings/default/; range_end:/registry/rolebindings/default0; response_count:0; response_revision:7620; }","duration":"144.523925ms","start":"2026-06-30T18:02:56.089884Z","end":"2026-06-30T18:02:56.234408Z","steps":["trace[1037497231] 'range keys from in-memory index tree' (duration: 143.782742ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:02:56.233968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.899702ms","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-06-30T18:02:56.234579Z","caller":"traceutil/trace.go:171","msg":"trace[209704326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7620; }","duration":"131.523943ms","start":"2026-06-30T18:02:56.103045Z","end":"2026-06-30T18:02:56.234569Z","steps":["trace[209704326] 'range keys from in-memory index tree' (duration: 130.76991ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:02:58.348972Z","caller":"traceutil/trace.go:171","msg":"trace[1066262889] linearizableReadLoop","detail":"{readStateIndex:7916; appliedIndex:7914; }","duration":"101.106136ms","start":"2026-06-30T18:02:58.247831Z","end":"2026-06-30T18:02:58.348937Z","steps":["trace[1066262889] 'read index received' (duration: 20.699808ms)","trace[1066262889] 'applied index is now lower than readState.Index' (duration: 80.404548ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:02:58.349264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.410072ms","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-06-30T18:02:58.349327Z","caller":"traceutil/trace.go:171","msg":"trace[1331140895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7631; }","duration":"101.521353ms","start":"2026-06-30T18:02:58.247791Z","end":"2026-06-30T18:02:58.349312Z","steps":["trace[1331140895] 'agreement among raft nodes before linearized reading' (duration: 101.31868ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:02:58.348985Z","caller":"traceutil/trace.go:171","msg":"trace[1846010671] transaction","detail":"{read_only:false; response_revision:7631; number_of_response:1; }","duration":"102.733817ms","start":"2026-06-30T18:02:58.246227Z","end":"2026-06-30T18:02:58.348961Z","steps":["trace[1846010671] 'process raft request' (duration: 75.958674ms)","trace[1846010671] 'compare' (duration: 26.62831ms)"],"step_count":2}