{"level":"warn","ts":"2026-05-09T03:40:30.405225Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T03:40:30.405318Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-05-09T03:40:30.405404Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T03:40:30.405416Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-09T03:40:30.405433Z","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-05-09T03:40:30.406057Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-05-09T03:40:30.406149Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-05-09T03:40:30.410699Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.305526ms"} {"level":"info","ts":"2026-05-09T03:40:30.419068Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-05-09T03:40:30.419145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T03:40:30.419166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-05-09T03:40:30.419178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T03:40:30.419193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-05-09T03:40:30.41927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-05-09T03:40:30.427826Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T03:40:30.429364Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T03:40:30.430644Z","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-05-09T03:40:30.433181Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T03:40:30.43399Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T03:40:30.434011Z","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-05-09T03:40:30.434281Z","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-05-09T03:40:30.434388Z","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-05-09T03:40:30.434676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-05-09T03:40:30.435264Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-09T03:40:30.436984Z","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-05-09T03:40:30.437268Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T03:40:30.437329Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T03:40:30.437458Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-09T03:40:30.437495Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-09T03:40:30.919639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T03:40:30.919706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T03:40:30.919741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-05-09T03:40:30.919757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-05-09T03:40:30.919765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-09T03:40:30.919781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-05-09T03:40:30.919804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-09T03:40:30.970641Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T03:40:30.970673Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T03:40:30.970709Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T03:40:30.970713Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T03:40:30.97117Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T03:40:30.971218Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T03:40:30.975205Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T03:40:30.976424Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T03:40:30.976692Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T03:40:30.976805Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T03:40:30.997469Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-05-09T03:40:55.777354Z","caller":"traceutil/trace.go:171","msg":"trace[640164767] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"163.362114ms","start":"2026-05-09T03:40:55.613966Z","end":"2026-05-09T03:40:55.777328Z","steps":["trace[640164767] 'process raft request' (duration: 163.228257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:40:59.011656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.64318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T03:40:59.011949Z","caller":"traceutil/trace.go:171","msg":"trace[1289151409] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"277.000242ms","start":"2026-05-09T03:40:58.734916Z","end":"2026-05-09T03:40:59.011916Z","steps":["trace[1289151409] 'process raft request' (duration: 124.535086ms)","trace[1289151409] 'compare' (duration: 151.527362ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:40:59.012059Z","caller":"traceutil/trace.go:171","msg":"trace[887630300] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"254.252687ms","start":"2026-05-09T03:40:58.757735Z","end":"2026-05-09T03:40:59.011987Z","steps":["trace[887630300] 'process raft request' (duration: 254.209618ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:40:59.012223Z","caller":"traceutil/trace.go:171","msg":"trace[635314435] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"267.119301ms","start":"2026-05-09T03:40:58.745094Z","end":"2026-05-09T03:40:59.012214Z","steps":["trace[635314435] 'process raft request' (duration: 266.758526ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:40:59.012297Z","caller":"traceutil/trace.go:171","msg":"trace[1620602024] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"267.008363ms","start":"2026-05-09T03:40:58.745252Z","end":"2026-05-09T03:40:59.012261Z","steps":["trace[1620602024] 'process raft request' (duration: 266.659978ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:40:59.020237Z","caller":"traceutil/trace.go:171","msg":"trace[256840790] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"244.595435ms","start":"2026-05-09T03:40:58.775604Z","end":"2026-05-09T03:40:59.0202Z","steps":["trace[256840790] 'process raft request' (duration: 244.379969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:40:59.020308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.777661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:40:59.020345Z","caller":"traceutil/trace.go:171","msg":"trace[1821589040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"218.84462ms","start":"2026-05-09T03:40:58.801494Z","end":"2026-05-09T03:40:59.020338Z","steps":["trace[1821589040] 'agreement among raft nodes before linearized reading' (duration: 218.768761ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:40:59.020371Z","caller":"traceutil/trace.go:171","msg":"trace[1262597642] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"248.069559ms","start":"2026-05-09T03:40:58.772281Z","end":"2026-05-09T03:40:59.02035Z","steps":["trace[1262597642] 'process raft request' (duration: 247.632136ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:40:59.020478Z","caller":"traceutil/trace.go:171","msg":"trace[1935026166] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"242.136145ms","start":"2026-05-09T03:40:58.778334Z","end":"2026-05-09T03:40:59.02047Z","steps":["trace[1935026166] 'process raft request' (duration: 241.681992ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:40:59.020224Z","caller":"traceutil/trace.go:171","msg":"trace[182729351] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:485; }","duration":"218.694712ms","start":"2026-05-09T03:40:58.801515Z","end":"2026-05-09T03:40:59.02021Z","steps":["trace[182729351] 'read index received' (duration: 59.237576ms)","trace[182729351] 'applied index is now lower than readState.Index' (duration: 159.456566ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:40:59.020571Z","caller":"traceutil/trace.go:171","msg":"trace[883472638] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"223.942157ms","start":"2026-05-09T03:40:58.796616Z","end":"2026-05-09T03:40:59.020558Z","steps":["trace[883472638] 'process raft request' (duration: 223.502174ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:40:59.020629Z","caller":"traceutil/trace.go:171","msg":"trace[341555999] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"231.245399ms","start":"2026-05-09T03:40:58.789377Z","end":"2026-05-09T03:40:59.020623Z","steps":["trace[341555999] 'process raft request' (duration: 230.703218ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:40:59.020709Z","caller":"traceutil/trace.go:171","msg":"trace[216020457] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"206.722586ms","start":"2026-05-09T03:40:58.81398Z","end":"2026-05-09T03:40:59.020702Z","steps":["trace[216020457] 'process raft request' (duration: 206.198053ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:40:59.020602Z","caller":"traceutil/trace.go:171","msg":"trace[1064729930] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"235.155637ms","start":"2026-05-09T03:40:58.78544Z","end":"2026-05-09T03:40:59.020595Z","steps":["trace[1064729930] 'process raft request' (duration: 234.601516ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:40:59.025316Z","caller":"traceutil/trace.go:171","msg":"trace[747813167] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"125.274537ms","start":"2026-05-09T03:40:58.900027Z","end":"2026-05-09T03:40:59.025302Z","steps":["trace[747813167] 'process raft request' (duration: 124.984772ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:41:02.162625Z","caller":"traceutil/trace.go:171","msg":"trace[85978173] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"121.93705ms","start":"2026-05-09T03:41:02.040664Z","end":"2026-05-09T03:41:02.162601Z","steps":["trace[85978173] 'process raft request' (duration: 121.450658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:02.767761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.56073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:41:02.767847Z","caller":"traceutil/trace.go:171","msg":"trace[1943508207] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:552; }","duration":"545.688639ms","start":"2026-05-09T03:41:02.222137Z","end":"2026-05-09T03:41:02.767826Z","steps":["trace[1943508207] 'range keys from in-memory index tree' (duration: 545.511232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:02.767889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:41:02.222122Z","time spent":"545.757568ms","remote":"127.0.0.1:53028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-09T03:41:05.837148Z","caller":"traceutil/trace.go:171","msg":"trace[975199356] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"225.932064ms","start":"2026-05-09T03:41:05.611092Z","end":"2026-05-09T03:41:05.837024Z","steps":["trace[975199356] 'process raft request' (duration: 225.750377ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:41:06.022031Z","caller":"traceutil/trace.go:171","msg":"trace[456019886] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"216.800112ms","start":"2026-05-09T03:41:05.805205Z","end":"2026-05-09T03:41:06.022005Z","steps":["trace[456019886] 'read index received' (duration: 31.680526ms)","trace[456019886] 'applied index is now lower than readState.Index' (duration: 185.118566ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:41:06.022219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.992639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:41:06.022254Z","caller":"traceutil/trace.go:171","msg":"trace[615097592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"217.118916ms","start":"2026-05-09T03:41:05.805123Z","end":"2026-05-09T03:41:06.022242Z","steps":["trace[615097592] 'agreement among raft nodes before linearized reading' (duration: 217.041217ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:41:06.022442Z","caller":"traceutil/trace.go:171","msg":"trace[718766605] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"388.253094ms","start":"2026-05-09T03:41:05.634075Z","end":"2026-05-09T03:41:06.022328Z","steps":["trace[718766605] 'process raft request' (duration: 339.688714ms)","trace[718766605] 'compare' (duration: 47.85754ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:41:06.023158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:41:05.63404Z","time spent":"388.624489ms","remote":"127.0.0.1:52924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:41:06.361245Z","caller":"traceutil/trace.go:171","msg":"trace[1662619875] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"362.175818ms","start":"2026-05-09T03:41:05.999037Z","end":"2026-05-09T03:41:06.361212Z","steps":["trace[1662619875] 'process raft request' (duration: 336.374862ms)","trace[1662619875] 'compare' (duration: 25.599019ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:41:06.361268Z","caller":"traceutil/trace.go:171","msg":"trace[893147846] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"339.100264ms","start":"2026-05-09T03:41:06.022138Z","end":"2026-05-09T03:41:06.361239Z","steps":["trace[893147846] 'read index received' (duration: 313.384067ms)","trace[893147846] 'applied index is now lower than readState.Index' (duration: 25.714797ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:41:06.361443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.70938ms","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-05-09T03:41:06.361535Z","caller":"traceutil/trace.go:171","msg":"trace[513668469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"501.871818ms","start":"2026-05-09T03:41:05.85964Z","end":"2026-05-09T03:41:06.361512Z","steps":["trace[513668469] 'agreement among raft nodes before linearized reading' (duration: 501.617861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:06.361505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:41:05.999012Z","time spent":"362.322406ms","remote":"127.0.0.1:53014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:41:06.361548Z","caller":"traceutil/trace.go:171","msg":"trace[1513822289] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"241.483069ms","start":"2026-05-09T03:41:06.120039Z","end":"2026-05-09T03:41:06.361522Z","steps":["trace[1513822289] 'process raft request' (duration: 241.387261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:06.361589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:41:05.859606Z","time spent":"501.974037ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T03:41:06.361701Z","caller":"traceutil/trace.go:171","msg":"trace[2052561031] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"281.39689ms","start":"2026-05-09T03:41:06.080264Z","end":"2026-05-09T03:41:06.36166Z","steps":["trace[2052561031] 'process raft request' (duration: 280.951277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:06.36182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.202492ms","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-05-09T03:41:06.361894Z","caller":"traceutil/trace.go:171","msg":"trace[386568502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"547.395969ms","start":"2026-05-09T03:41:05.814482Z","end":"2026-05-09T03:41:06.361878Z","steps":["trace[386568502] 'agreement among raft nodes before linearized reading' (duration: 547.006335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:06.361984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:41:05.814462Z","time spent":"547.507108ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T03:41:07.460175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.771401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T03:41:07.460261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.01014ms","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-05-09T03:41:07.4603Z","caller":"traceutil/trace.go:171","msg":"trace[851160687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"654.949609ms","start":"2026-05-09T03:41:06.805323Z","end":"2026-05-09T03:41:07.460273Z","steps":["trace[851160687] 'range keys from in-memory index tree' (duration: 654.649433ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:41:07.460352Z","caller":"traceutil/trace.go:171","msg":"trace[816165947] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"650.154618ms","start":"2026-05-09T03:41:06.810178Z","end":"2026-05-09T03:41:07.460333Z","steps":["trace[816165947] 'range keys from in-memory index tree' (duration: 649.850082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:07.460362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:41:06.805294Z","time spent":"655.053597ms","remote":"127.0.0.1:52810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T03:41:07.460396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:41:06.810164Z","time spent":"650.222627ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T03:41:07.619423Z","caller":"traceutil/trace.go:171","msg":"trace[713747760] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"154.412227ms","start":"2026-05-09T03:41:07.464978Z","end":"2026-05-09T03:41:07.61939Z","steps":["trace[713747760] 'process raft request' (duration: 154.291808ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:41:07.619482Z","caller":"traceutil/trace.go:171","msg":"trace[1786813598] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"442.341151ms","start":"2026-05-09T03:41:07.177055Z","end":"2026-05-09T03:41:07.619396Z","steps":["trace[1786813598] 'read index received' (duration: 435.180124ms)","trace[1786813598] 'applied index is now lower than readState.Index' (duration: 7.155607ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:41:07.619309Z","caller":"traceutil/trace.go:171","msg":"trace[754890702] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"780.363658ms","start":"2026-05-09T03:41:06.838889Z","end":"2026-05-09T03:41:07.619252Z","steps":["trace[754890702] 'process raft request' (duration: 773.9466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:07.619766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.703215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T03:41:07.619858Z","caller":"traceutil/trace.go:171","msg":"trace[1515134065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"442.843463ms","start":"2026-05-09T03:41:07.176989Z","end":"2026-05-09T03:41:07.619832Z","steps":["trace[1515134065] 'agreement among raft nodes before linearized reading' (duration: 442.607287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:07.61988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:41:06.838863Z","time spent":"780.895649ms","remote":"127.0.0.1:52924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:41:07.619906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:41:07.176968Z","time spent":"442.927741ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T03:41:07.620127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.252536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:41:07.620222Z","caller":"traceutil/trace.go:171","msg":"trace[1409668722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"157.386174ms","start":"2026-05-09T03:41:07.462822Z","end":"2026-05-09T03:41:07.620208Z","steps":["trace[1409668722] 'agreement among raft nodes before linearized reading' (duration: 157.251326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:09.000451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.196767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:41:09.000638Z","caller":"traceutil/trace.go:171","msg":"trace[1554734833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"195.411754ms","start":"2026-05-09T03:41:08.805147Z","end":"2026-05-09T03:41:09.000559Z","steps":["trace[1554734833] 'range keys from in-memory index tree' (duration: 195.116767ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:41:09.741786Z","caller":"traceutil/trace.go:171","msg":"trace[579800062] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"115.676992ms","start":"2026-05-09T03:41:09.626083Z","end":"2026-05-09T03:41:09.74176Z","steps":["trace[579800062] 'read index received' (duration: 115.451385ms)","trace[579800062] 'applied index is now lower than readState.Index' (duration: 224.427µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:41:09.741935Z","caller":"traceutil/trace.go:171","msg":"trace[1562724308] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"121.454471ms","start":"2026-05-09T03:41:09.620433Z","end":"2026-05-09T03:41:09.741887Z","steps":["trace[1562724308] 'process raft request' (duration: 121.168605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:09.742183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.077686ms","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-05-09T03:41:09.742272Z","caller":"traceutil/trace.go:171","msg":"trace[1247374875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"116.203284ms","start":"2026-05-09T03:41:09.626053Z","end":"2026-05-09T03:41:09.742257Z","steps":["trace[1247374875] 'agreement among raft nodes before linearized reading' (duration: 115.923558ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:41:09.788145Z","caller":"traceutil/trace.go:171","msg":"trace[1844876243] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"151.249223ms","start":"2026-05-09T03:41:09.636876Z","end":"2026-05-09T03:41:09.788126Z","steps":["trace[1844876243] 'process raft request' (duration: 137.85453ms)","trace[1844876243] 'compare' (duration: 13.250225ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:41:09.788449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.072879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T03:41:09.788529Z","caller":"traceutil/trace.go:171","msg":"trace[944469790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"155.215367ms","start":"2026-05-09T03:41:09.633293Z","end":"2026-05-09T03:41:09.788508Z","steps":["trace[944469790] 'agreement among raft nodes before linearized reading' (duration: 154.871101ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:41:23.764447Z","caller":"traceutil/trace.go:171","msg":"trace[1034214132] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"289.662337ms","start":"2026-05-09T03:41:23.474746Z","end":"2026-05-09T03:41:23.764408Z","steps":["trace[1034214132] 'process raft request' (duration: 289.39122ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:41:24.1371Z","caller":"traceutil/trace.go:171","msg":"trace[1737904555] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"333.075861ms","start":"2026-05-09T03:41:23.803992Z","end":"2026-05-09T03:41:24.137068Z","steps":["trace[1737904555] 'read index received' (duration: 332.800734ms)","trace[1737904555] 'applied index is now lower than readState.Index' (duration: 273.427µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:41:24.137282Z","caller":"traceutil/trace.go:171","msg":"trace[990257145] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"363.894395ms","start":"2026-05-09T03:41:23.773369Z","end":"2026-05-09T03:41:24.137263Z","steps":["trace[990257145] 'process raft request' (duration: 363.528889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:24.137416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:41:23.773345Z","time spent":"363.973304ms","remote":"127.0.0.1:53018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:41:24.137558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.851518ms","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-05-09T03:41:24.137648Z","caller":"traceutil/trace.go:171","msg":"trace[1663928023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"262.971667ms","start":"2026-05-09T03:41:23.874656Z","end":"2026-05-09T03:41:24.137628Z","steps":["trace[1663928023] 'agreement among raft nodes before linearized reading' (duration: 262.76181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:24.137615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.623634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:41:24.137709Z","caller":"traceutil/trace.go:171","msg":"trace[1426006365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"333.752752ms","start":"2026-05-09T03:41:23.803942Z","end":"2026-05-09T03:41:24.137695Z","steps":["trace[1426006365] 'agreement among raft nodes before linearized reading' (duration: 333.625814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:24.137764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:41:23.803922Z","time spent":"333.834542ms","remote":"127.0.0.1:52810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T03:41:24.137775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.816063ms","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-05-09T03:41:24.137835Z","caller":"traceutil/trace.go:171","msg":"trace[1021524965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"251.916462ms","start":"2026-05-09T03:41:23.885902Z","end":"2026-05-09T03:41:24.137818Z","steps":["trace[1021524965] 'agreement among raft nodes before linearized reading' (duration: 251.733154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:24.138394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.309649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-09T03:41:24.138568Z","caller":"traceutil/trace.go:171","msg":"trace[1353078869] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:743; }","duration":"220.05044ms","start":"2026-05-09T03:41:23.918474Z","end":"2026-05-09T03:41:24.138525Z","steps":["trace[1353078869] 'agreement among raft nodes before linearized reading' (duration: 219.048123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:53.100291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.100687ms","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-05-09T03:41:53.100388Z","caller":"traceutil/trace.go:171","msg":"trace[176562262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1142; }","duration":"416.252675ms","start":"2026-05-09T03:41:52.684117Z","end":"2026-05-09T03:41:53.100369Z","steps":["trace[176562262] 'range keys from in-memory index tree' (duration: 415.912019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:41:53.100429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:41:52.684094Z","time spent":"416.325764ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T03:41:53.100622Z","caller":"traceutil/trace.go:171","msg":"trace[1735854082] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"227.371332ms","start":"2026-05-09T03:41:52.873236Z","end":"2026-05-09T03:41:53.100608Z","steps":["trace[1735854082] 'process raft request' (duration: 225.703728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:17.847827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.760278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T03:42:17.847909Z","caller":"traceutil/trace.go:171","msg":"trace[1945573370] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1411; }","duration":"373.891067ms","start":"2026-05-09T03:42:17.474Z","end":"2026-05-09T03:42:17.847891Z","steps":["trace[1945573370] 'range keys from in-memory index tree' (duration: 373.60656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:17.847945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:17.473974Z","time spent":"373.962226ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T03:42:18.269589Z","caller":"traceutil/trace.go:171","msg":"trace[1154254600] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"416.717904ms","start":"2026-05-09T03:42:17.85284Z","end":"2026-05-09T03:42:18.269558Z","steps":["trace[1154254600] 'process raft request' (duration: 416.512496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:18.269776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:17.852819Z","time spent":"416.856112ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:42:18.373044Z","caller":"traceutil/trace.go:171","msg":"trace[1740145201] linearizableReadLoop","detail":"{readStateIndex:1458; appliedIndex:1456; }","duration":"412.981062ms","start":"2026-05-09T03:42:17.960044Z","end":"2026-05-09T03:42:18.373025Z","steps":["trace[1740145201] 'read index received' (duration: 309.281601ms)","trace[1740145201] 'applied index is now lower than readState.Index' (duration: 103.698401ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:42:18.373229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.1658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:42:18.373273Z","caller":"traceutil/trace.go:171","msg":"trace[1525860516] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1413; }","duration":"413.256319ms","start":"2026-05-09T03:42:17.959996Z","end":"2026-05-09T03:42:18.373252Z","steps":["trace[1525860516] 'agreement among raft nodes before linearized reading' (duration: 413.1775ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:18.373245Z","caller":"traceutil/trace.go:171","msg":"trace[1743149471] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"496.420304ms","start":"2026-05-09T03:42:17.876782Z","end":"2026-05-09T03:42:18.373202Z","steps":["trace[1743149471] 'process raft request' (duration: 477.95415ms)","trace[1743149471] 'compare' (duration: 18.191547ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:42:18.373284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.379517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:42:18.373368Z","caller":"traceutil/trace.go:171","msg":"trace[962159726] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1413; }","duration":"262.507326ms","start":"2026-05-09T03:42:18.110841Z","end":"2026-05-09T03:42:18.373349Z","steps":["trace[962159726] 'agreement among raft nodes before linearized reading' (duration: 262.365008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:18.373318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:17.959973Z","time spent":"413.335578ms","remote":"127.0.0.1:53282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-09T03:42:18.373417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:17.876763Z","time spent":"496.568413ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:42:29.518663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.472453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T03:42:29.518963Z","caller":"traceutil/trace.go:171","msg":"trace[1939141525] linearizableReadLoop","detail":"{readStateIndex:1711; appliedIndex:1708; }","duration":"139.439394ms","start":"2026-05-09T03:42:29.37951Z","end":"2026-05-09T03:42:29.51895Z","steps":["trace[1939141525] 'read index received' (duration: 93.690202ms)","trace[1939141525] 'applied index is now lower than readState.Index' (duration: 45.748422ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:42:29.519132Z","caller":"traceutil/trace.go:171","msg":"trace[408049719] transaction","detail":"{read_only:false; response_revision:1644; number_of_response:1; }","duration":"171.78292ms","start":"2026-05-09T03:42:29.347317Z","end":"2026-05-09T03:42:29.5191Z","steps":["trace[408049719] 'process raft request' (duration: 14.814531ms)","trace[408049719] 'compare' (duration: 156.346384ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:42:29.519201Z","caller":"traceutil/trace.go:171","msg":"trace[1153371635] transaction","detail":"{read_only:false; response_revision:1645; number_of_response:1; }","duration":"169.957617ms","start":"2026-05-09T03:42:29.349234Z","end":"2026-05-09T03:42:29.519192Z","steps":["trace[1153371635] 'process raft request' (duration: 169.584611ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:29.519249Z","caller":"traceutil/trace.go:171","msg":"trace[1831369033] transaction","detail":"{read_only:false; response_revision:1646; number_of_response:1; }","duration":"164.860695ms","start":"2026-05-09T03:42:29.354381Z","end":"2026-05-09T03:42:29.519242Z","steps":["trace[1831369033] 'process raft request' (duration: 164.500958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:29.519296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.78613ms","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-05-09T03:42:29.519851Z","caller":"traceutil/trace.go:171","msg":"trace[566711533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1646; }","duration":"140.391764ms","start":"2026-05-09T03:42:29.379445Z","end":"2026-05-09T03:42:29.519837Z","steps":["trace[566711533] 'agreement among raft nodes before linearized reading' (duration: 139.715301ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:30.749415Z","caller":"traceutil/trace.go:171","msg":"trace[612005740] linearizableReadLoop","detail":"{readStateIndex:1724; appliedIndex:1722; }","duration":"120.660371ms","start":"2026-05-09T03:42:30.628706Z","end":"2026-05-09T03:42:30.749366Z","steps":["trace[612005740] 'read index received' (duration: 32.548175ms)","trace[612005740] 'applied index is now lower than readState.Index' (duration: 88.110776ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:42:30.749441Z","caller":"traceutil/trace.go:171","msg":"trace[610381459] transaction","detail":"{read_only:false; response_revision:1659; number_of_response:1; }","duration":"149.493812ms","start":"2026-05-09T03:42:30.599897Z","end":"2026-05-09T03:42:30.749391Z","steps":["trace[610381459] 'process raft request' (duration: 136.015848ms)","trace[610381459] 'compare' (duration: 13.222426ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:42:30.749625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.866289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T03:42:30.749635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.839989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:42:30.749689Z","caller":"traceutil/trace.go:171","msg":"trace[1837676433] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:1659; }","duration":"120.942868ms","start":"2026-05-09T03:42:30.628729Z","end":"2026-05-09T03:42:30.749672Z","steps":["trace[1837676433] 'agreement among raft nodes before linearized reading' (duration: 120.831139ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:30.749703Z","caller":"traceutil/trace.go:171","msg":"trace[609566441] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:1659; }","duration":"120.998988ms","start":"2026-05-09T03:42:30.628684Z","end":"2026-05-09T03:42:30.749683Z","steps":["trace[609566441] 'agreement among raft nodes before linearized reading' (duration: 120.7844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:30.749869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.482212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:42:30.74992Z","caller":"traceutil/trace.go:171","msg":"trace[983776611] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:1659; }","duration":"120.555682ms","start":"2026-05-09T03:42:30.629353Z","end":"2026-05-09T03:42:30.749908Z","steps":["trace[983776611] 'agreement among raft nodes before linearized reading' (duration: 120.456583ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:34.697884Z","caller":"traceutil/trace.go:171","msg":"trace[1776845764] linearizableReadLoop","detail":"{readStateIndex:1783; appliedIndex:1782; }","duration":"115.265996ms","start":"2026-05-09T03:42:34.582599Z","end":"2026-05-09T03:42:34.697865Z","steps":["trace[1776845764] 'read index received' (duration: 106.715226ms)","trace[1776845764] 'applied index is now lower than readState.Index' (duration: 8.54988ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:42:34.697977Z","caller":"traceutil/trace.go:171","msg":"trace[939806034] transaction","detail":"{read_only:false; response_revision:1713; number_of_response:1; }","duration":"180.32583ms","start":"2026-05-09T03:42:34.517631Z","end":"2026-05-09T03:42:34.697956Z","steps":["trace[939806034] 'process raft request' (duration: 171.72706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:34.698047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.435214ms","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-05-09T03:42:34.698073Z","caller":"traceutil/trace.go:171","msg":"trace[588716936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1713; }","duration":"115.499314ms","start":"2026-05-09T03:42:34.582564Z","end":"2026-05-09T03:42:34.698063Z","steps":["trace[588716936] 'agreement among raft nodes before linearized reading' (duration: 115.372815ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:34.841099Z","caller":"traceutil/trace.go:171","msg":"trace[1729576358] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"139.45076ms","start":"2026-05-09T03:42:34.701626Z","end":"2026-05-09T03:42:34.841077Z","steps":["trace[1729576358] 'process raft request' (duration: 139.367951ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:34.841239Z","caller":"traceutil/trace.go:171","msg":"trace[1848284722] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"236.475129ms","start":"2026-05-09T03:42:34.604749Z","end":"2026-05-09T03:42:34.841224Z","steps":["trace[1848284722] 'process raft request' (duration: 217.179068ms)","trace[1848284722] 'compare' (duration: 18.887254ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:42:34.841159Z","caller":"traceutil/trace.go:171","msg":"trace[848006910] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"168.58762ms","start":"2026-05-09T03:42:34.672501Z","end":"2026-05-09T03:42:34.841088Z","steps":["trace[848006910] 'process raft request' (duration: 168.448101ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:34.841488Z","caller":"traceutil/trace.go:171","msg":"trace[1860447799] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1716; }","duration":"100.019988ms","start":"2026-05-09T03:42:34.741447Z","end":"2026-05-09T03:42:34.841467Z","steps":["trace[1860447799] 'agreement among raft nodes before linearized reading' (duration: 99.992909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:41.069365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340570893869407,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T03:42:41.43701Z","caller":"traceutil/trace.go:171","msg":"trace[631809920] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"880.253827ms","start":"2026-05-09T03:42:40.556729Z","end":"2026-05-09T03:42:41.436982Z","steps":["trace[631809920] 'process raft request' (duration: 880.102828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:41.437196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:40.556712Z","time spent":"880.372016ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:42:41.45361Z","caller":"traceutil/trace.go:171","msg":"trace[1128883113] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"592.60868ms","start":"2026-05-09T03:42:40.860976Z","end":"2026-05-09T03:42:41.453585Z","steps":["trace[1128883113] 'process raft request' (duration: 592.414311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:41.45377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:40.860951Z","time spent":"592.732629ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:42:41.453824Z","caller":"traceutil/trace.go:171","msg":"trace[1068651456] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1883; }","duration":"884.959664ms","start":"2026-05-09T03:42:40.568839Z","end":"2026-05-09T03:42:41.453799Z","steps":["trace[1068651456] 'read index received' (duration: 868.028671ms)","trace[1068651456] 'applied index is now lower than readState.Index' (duration: 16.929324ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:42:41.454003Z","caller":"traceutil/trace.go:171","msg":"trace[1036973066] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"590.004373ms","start":"2026-05-09T03:42:40.863983Z","end":"2026-05-09T03:42:41.453987Z","steps":["trace[1036973066] 'process raft request' (duration: 589.548587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:41.454254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.977583ms","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-05-09T03:42:41.454312Z","caller":"traceutil/trace.go:171","msg":"trace[1247676793] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1814; }","duration":"217.072613ms","start":"2026-05-09T03:42:41.237226Z","end":"2026-05-09T03:42:41.454298Z","steps":["trace[1247676793] 'agreement among raft nodes before linearized reading' (duration: 216.966494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:41.454413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.740166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3d10d136-0b17-46ee-9b09-f87998c8fa70\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-09T03:42:41.454407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.117419ms","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-05-09T03:42:41.454457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.548809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-09T03:42:41.454596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.171149ms","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-05-09T03:42:41.454496Z","caller":"traceutil/trace.go:171","msg":"trace[1519991589] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3d10d136-0b17-46ee-9b09-f87998c8fa70; range_end:; response_count:1; response_revision:1814; }","duration":"376.868525ms","start":"2026-05-09T03:42:41.077608Z","end":"2026-05-09T03:42:41.454477Z","steps":["trace[1519991589] 'agreement among raft nodes before linearized reading' (duration: 376.593308ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:41.454594Z","caller":"traceutil/trace.go:171","msg":"trace[352823454] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1814; }","duration":"885.768246ms","start":"2026-05-09T03:42:40.568798Z","end":"2026-05-09T03:42:41.454567Z","steps":["trace[352823454] 'agreement among raft nodes before linearized reading' (duration: 885.35785ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:41.454646Z","caller":"traceutil/trace.go:171","msg":"trace[1311401497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1814; }","duration":"585.276948ms","start":"2026-05-09T03:42:40.869356Z","end":"2026-05-09T03:42:41.454633Z","steps":["trace[1311401497] 'agreement among raft nodes before linearized reading' (duration: 584.807682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:41.454678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:41.07759Z","time spent":"377.073323ms","remote":"127.0.0.1:53018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3d10d136-0b17-46ee-9b09-f87998c8fa70\" "} {"level":"warn","ts":"2026-05-09T03:42:41.454704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:40.86935Z","time spent":"585.343367ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T03:42:41.454688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:40.568785Z","time spent":"885.888595ms","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-09T03:42:41.454517Z","caller":"traceutil/trace.go:171","msg":"trace[140535034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1814; }","duration":"585.271498ms","start":"2026-05-09T03:42:40.869221Z","end":"2026-05-09T03:42:41.454493Z","steps":["trace[140535034] 'agreement among raft nodes before linearized reading' (duration: 584.983991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:41.454797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:40.869209Z","time spent":"585.570255ms","remote":"127.0.0.1:53086","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-05-09T03:42:41.454416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.306ms","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-05-09T03:42:41.454935Z","caller":"traceutil/trace.go:171","msg":"trace[288049460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1814; }","duration":"736.865795ms","start":"2026-05-09T03:42:40.718055Z","end":"2026-05-09T03:42:41.45492Z","steps":["trace[288049460] 'agreement among raft nodes before linearized reading' (duration: 736.073112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:41.45506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:40.718034Z","time spent":"736.949944ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T03:42:41.454521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.753292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3d10d136-0b17-46ee-9b09-f87998c8fa70\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-09T03:42:41.455472Z","caller":"traceutil/trace.go:171","msg":"trace[1570727949] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3d10d136-0b17-46ee-9b09-f87998c8fa70; range_end:; response_count:1; response_revision:1814; }","duration":"319.689343ms","start":"2026-05-09T03:42:41.135725Z","end":"2026-05-09T03:42:41.455414Z","steps":["trace[1570727949] 'agreement among raft nodes before linearized reading' (duration: 318.420504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:41.454597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:40.863967Z","time spent":"590.074683ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:42:41.455632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:41.1357Z","time spent":"319.913661ms","remote":"127.0.0.1:53018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3d10d136-0b17-46ee-9b09-f87998c8fa70\" "} {"level":"info","ts":"2026-05-09T03:42:41.603971Z","caller":"traceutil/trace.go:171","msg":"trace[464549130] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"142.105467ms","start":"2026-05-09T03:42:41.461838Z","end":"2026-05-09T03:42:41.603944Z","steps":["trace[464549130] 'process raft request' (duration: 141.833719ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:41.604119Z","caller":"traceutil/trace.go:171","msg":"trace[2070659596] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"140.61492ms","start":"2026-05-09T03:42:41.463477Z","end":"2026-05-09T03:42:41.604092Z","steps":["trace[2070659596] 'process raft request' (duration: 140.377133ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:41.604726Z","caller":"traceutil/trace.go:171","msg":"trace[1084480232] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"140.310212ms","start":"2026-05-09T03:42:41.464395Z","end":"2026-05-09T03:42:41.604705Z","steps":["trace[1084480232] 'process raft request' (duration: 139.50924ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:42.068245Z","caller":"traceutil/trace.go:171","msg":"trace[322976999] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1890; }","duration":"450.083728ms","start":"2026-05-09T03:42:41.618139Z","end":"2026-05-09T03:42:42.068222Z","steps":["trace[322976999] 'read index received' (duration: 449.86562ms)","trace[322976999] 'applied index is now lower than readState.Index' (duration: 216.928µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:42:42.068522Z","caller":"traceutil/trace.go:171","msg":"trace[816839285] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"509.809636ms","start":"2026-05-09T03:42:41.5587Z","end":"2026-05-09T03:42:42.06851Z","steps":["trace[816839285] 'process raft request' (duration: 509.37084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:42.068641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:41.558678Z","time spent":"509.882217ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:42:42.06888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.175289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T03:42:42.068895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.723853ms","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-05-09T03:42:42.06894Z","caller":"traceutil/trace.go:171","msg":"trace[1115116269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1819; }","duration":"264.290037ms","start":"2026-05-09T03:42:41.804637Z","end":"2026-05-09T03:42:42.068927Z","steps":["trace[1115116269] 'agreement among raft nodes before linearized reading' (duration: 264.160008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:42.068875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.117765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:42:42.069096Z","caller":"traceutil/trace.go:171","msg":"trace[831041742] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1819; }","duration":"392.433372ms","start":"2026-05-09T03:42:41.676606Z","end":"2026-05-09T03:42:42.069039Z","steps":["trace[831041742] 'agreement among raft nodes before linearized reading' (duration: 392.046816ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:42.068969Z","caller":"traceutil/trace.go:171","msg":"trace[845620208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1819; }","duration":"450.848282ms","start":"2026-05-09T03:42:41.618102Z","end":"2026-05-09T03:42:42.068951Z","steps":["trace[845620208] 'agreement among raft nodes before linearized reading' (duration: 450.561964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:42.069252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:41.676585Z","time spent":"392.64914ms","remote":"127.0.0.1:50512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T03:42:42.069295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:41.618089Z","time spent":"451.183599ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T03:42:42.257656Z","caller":"traceutil/trace.go:171","msg":"trace[1787350822] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"184.778974ms","start":"2026-05-09T03:42:42.072857Z","end":"2026-05-09T03:42:42.257636Z","steps":["trace[1787350822] 'process raft request' (duration: 184.440087ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:50.625888Z","caller":"traceutil/trace.go:171","msg":"trace[1972992809] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"108.92361ms","start":"2026-05-09T03:42:50.516948Z","end":"2026-05-09T03:42:50.625872Z","steps":["trace[1972992809] 'read index received' (duration: 108.728082ms)","trace[1972992809] 'applied index is now lower than readState.Index' (duration: 194.848µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:42:50.62605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.084169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-09T03:42:50.626072Z","caller":"traceutil/trace.go:171","msg":"trace[1004329171] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1905; }","duration":"109.160028ms","start":"2026-05-09T03:42:50.516905Z","end":"2026-05-09T03:42:50.626065Z","steps":["trace[1004329171] 'agreement among raft nodes before linearized reading' (duration: 109.03232ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:50.626101Z","caller":"traceutil/trace.go:171","msg":"trace[1325684302] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"129.321983ms","start":"2026-05-09T03:42:50.496746Z","end":"2026-05-09T03:42:50.626068Z","steps":["trace[1325684302] 'process raft request' (duration: 128.999746ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:55.946433Z","caller":"traceutil/trace.go:171","msg":"trace[1143430024] linearizableReadLoop","detail":"{readStateIndex:2009; appliedIndex:2008; }","duration":"377.420117ms","start":"2026-05-09T03:42:55.568997Z","end":"2026-05-09T03:42:55.946417Z","steps":["trace[1143430024] 'read index received' (duration: 377.215579ms)","trace[1143430024] 'applied index is now lower than readState.Index' (duration: 204.048µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:42:55.946588Z","caller":"traceutil/trace.go:171","msg":"trace[1173162760] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"403.404489ms","start":"2026-05-09T03:42:55.543145Z","end":"2026-05-09T03:42:55.946549Z","steps":["trace[1173162760] 'process raft request' (duration: 403.145402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:55.94665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.642785ms","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-05-09T03:42:55.946671Z","caller":"traceutil/trace.go:171","msg":"trace[1211535313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1933; }","duration":"377.714635ms","start":"2026-05-09T03:42:55.568949Z","end":"2026-05-09T03:42:55.946664Z","steps":["trace[1211535313] 'agreement among raft nodes before linearized reading' (duration: 377.552376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:55.94669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:55.568934Z","time spent":"377.751465ms","remote":"127.0.0.1:53086","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-05-09T03:42:55.946762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:42:55.543131Z","time spent":"403.535398ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:42:55.946774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.930806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-09T03:42:55.946827Z","caller":"traceutil/trace.go:171","msg":"trace[1262432807] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1933; }","duration":"276.028286ms","start":"2026-05-09T03:42:55.670784Z","end":"2026-05-09T03:42:55.946813Z","steps":["trace[1262432807] 'agreement among raft nodes before linearized reading' (duration: 275.835987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:55.946876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.613621ms","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-05-09T03:42:55.946943Z","caller":"traceutil/trace.go:171","msg":"trace[1995167714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1933; }","duration":"254.68778ms","start":"2026-05-09T03:42:55.692238Z","end":"2026-05-09T03:42:55.946926Z","steps":["trace[1995167714] 'agreement among raft nodes before linearized reading' (duration: 254.502982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:55.946967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.882732ms","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-05-09T03:42:55.946982Z","caller":"traceutil/trace.go:171","msg":"trace[646338796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1933; }","duration":"269.937101ms","start":"2026-05-09T03:42:55.677041Z","end":"2026-05-09T03:42:55.946978Z","steps":["trace[646338796] 'agreement among raft nodes before linearized reading' (duration: 269.892881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:42:55.946909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.697544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:42:55.947051Z","caller":"traceutil/trace.go:171","msg":"trace[349071222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1933; }","duration":"141.883402ms","start":"2026-05-09T03:42:55.80516Z","end":"2026-05-09T03:42:55.947043Z","steps":["trace[349071222] 'agreement among raft nodes before linearized reading' (duration: 141.733324ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:42:56.595825Z","caller":"traceutil/trace.go:171","msg":"trace[1789971571] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"248.22014ms","start":"2026-05-09T03:42:56.347571Z","end":"2026-05-09T03:42:56.595791Z","steps":["trace[1789971571] 'process raft request' (duration: 248.013872ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:43:11.733611Z","caller":"traceutil/trace.go:171","msg":"trace[1721888497] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"150.848911ms","start":"2026-05-09T03:43:11.582736Z","end":"2026-05-09T03:43:11.733585Z","steps":["trace[1721888497] 'process raft request' (duration: 81.271473ms)","trace[1721888497] 'compare' (duration: 69.432119ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:43:11.733805Z","caller":"traceutil/trace.go:171","msg":"trace[491312222] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"150.949819ms","start":"2026-05-09T03:43:11.582813Z","end":"2026-05-09T03:43:11.733762Z","steps":["trace[491312222] 'process raft request' (duration: 150.726681ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:43:12.217924Z","caller":"traceutil/trace.go:171","msg":"trace[387036621] linearizableReadLoop","detail":"{readStateIndex:2118; appliedIndex:2117; }","duration":"181.250035ms","start":"2026-05-09T03:43:12.036648Z","end":"2026-05-09T03:43:12.217898Z","steps":["trace[387036621] 'read index received' (duration: 181.038047ms)","trace[387036621] 'applied index is now lower than readState.Index' (duration: 210.748µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:43:12.218144Z","caller":"traceutil/trace.go:171","msg":"trace[926079986] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"181.687862ms","start":"2026-05-09T03:43:12.036394Z","end":"2026-05-09T03:43:12.218082Z","steps":["trace[926079986] 'process raft request' (duration: 181.352325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:12.218217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.544772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T03:43:12.218351Z","caller":"traceutil/trace.go:171","msg":"trace[1807887614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2039; }","duration":"181.697591ms","start":"2026-05-09T03:43:12.036635Z","end":"2026-05-09T03:43:12.218332Z","steps":["trace[1807887614] 'agreement among raft nodes before linearized reading' (duration: 181.459513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:12.505789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.534148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T03:43:12.50606Z","caller":"traceutil/trace.go:171","msg":"trace[808311235] linearizableReadLoop","detail":"{readStateIndex:2120; appliedIndex:2118; }","duration":"287.968476ms","start":"2026-05-09T03:43:12.218072Z","end":"2026-05-09T03:43:12.506041Z","steps":["trace[808311235] 'read index received' (duration: 99.849544ms)","trace[808311235] 'applied index is now lower than readState.Index' (duration: 188.117862ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:43:12.506156Z","caller":"traceutil/trace.go:171","msg":"trace[5169596] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"467.11287ms","start":"2026-05-09T03:43:12.039029Z","end":"2026-05-09T03:43:12.506142Z","steps":["trace[5169596] 'process raft request' (duration: 278.873919ms)","trace[5169596] 'compare' (duration: 187.373229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:43:12.506229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:12.038996Z","time spent":"467.19678ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:43:12.50618Z","caller":"traceutil/trace.go:171","msg":"trace[1492240530] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"330.068494ms","start":"2026-05-09T03:43:12.176018Z","end":"2026-05-09T03:43:12.506086Z","steps":["trace[1492240530] 'process raft request' (duration: 329.877306ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:43:12.506271Z","caller":"traceutil/trace.go:171","msg":"trace[1637222512] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"282.790553ms","start":"2026-05-09T03:43:12.22345Z","end":"2026-05-09T03:43:12.506241Z","steps":["trace[1637222512] 'process raft request' (duration: 282.553596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:12.506479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:12.175987Z","time spent":"330.305742ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:43:12.506635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.532512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-09T03:43:12.506677Z","caller":"traceutil/trace.go:171","msg":"trace[735040351] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2042; }","duration":"451.6432ms","start":"2026-05-09T03:43:12.055018Z","end":"2026-05-09T03:43:12.506661Z","steps":["trace[735040351] 'agreement among raft nodes before linearized reading' (duration: 451.086506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:12.506717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:12.054988Z","time spent":"451.71462ms","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-09T03:43:12.843172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.320091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T03:43:12.843277Z","caller":"traceutil/trace.go:171","msg":"trace[252044601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2042; }","duration":"102.472729ms","start":"2026-05-09T03:43:12.740774Z","end":"2026-05-09T03:43:12.843246Z","steps":["trace[252044601] 'range keys from in-memory index tree' (duration: 102.022724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:12.843437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.414802ms","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-05-09T03:43:12.843556Z","caller":"traceutil/trace.go:171","msg":"trace[721197767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2042; }","duration":"169.602521ms","start":"2026-05-09T03:43:12.673928Z","end":"2026-05-09T03:43:12.84353Z","steps":["trace[721197767] 'range keys from in-memory index tree' (duration: 169.201304ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:43:12.974119Z","caller":"traceutil/trace.go:171","msg":"trace[1143758152] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"125.702279ms","start":"2026-05-09T03:43:12.848387Z","end":"2026-05-09T03:43:12.97409Z","steps":["trace[1143758152] 'process raft request' (duration: 104.975307ms)","trace[1143758152] 'compare' (duration: 20.593613ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:43:13.873792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.820804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-09T03:43:13.873876Z","caller":"traceutil/trace.go:171","msg":"trace[720059984] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2045; }","duration":"358.987782ms","start":"2026-05-09T03:43:13.514866Z","end":"2026-05-09T03:43:13.873854Z","steps":["trace[720059984] 'range keys from in-memory index tree' (duration: 358.625206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:13.873918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:13.51484Z","time spent":"359.068142ms","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-09T03:43:14.029328Z","caller":"traceutil/trace.go:171","msg":"trace[1050210791] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"367.644813ms","start":"2026-05-09T03:43:13.661647Z","end":"2026-05-09T03:43:14.029292Z","steps":["trace[1050210791] 'process raft request' (duration: 367.345446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:14.029477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.432956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:43:14.029543Z","caller":"traceutil/trace.go:171","msg":"trace[683362101] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2046; }","duration":"356.550755ms","start":"2026-05-09T03:43:13.672974Z","end":"2026-05-09T03:43:14.029525Z","steps":["trace[683362101] 'agreement among raft nodes before linearized reading' (duration: 356.419946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:14.029559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:13.66162Z","time spent":"367.813412ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:43:14.029608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:13.672962Z","time spent":"356.630425ms","remote":"127.0.0.1:50588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-09T03:43:14.02926Z","caller":"traceutil/trace.go:171","msg":"trace[600720656] linearizableReadLoop","detail":"{readStateIndex:2125; appliedIndex:2124; }","duration":"356.214328ms","start":"2026-05-09T03:43:13.673017Z","end":"2026-05-09T03:43:14.029232Z","steps":["trace[600720656] 'read index received' (duration: 356.0287ms)","trace[600720656] 'applied index is now lower than readState.Index' (duration: 184.108µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:43:14.22206Z","caller":"traceutil/trace.go:171","msg":"trace[2146217854] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"481.167825ms","start":"2026-05-09T03:43:13.740866Z","end":"2026-05-09T03:43:14.222034Z","steps":["trace[2146217854] 'process raft request' (duration: 429.464013ms)","trace[2146217854] 'compare' (duration: 51.329875ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:43:14.222287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:13.740835Z","time spent":"481.374163ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:43:14.222274Z","caller":"traceutil/trace.go:171","msg":"trace[796527545] linearizableReadLoop","detail":"{readStateIndex:2127; appliedIndex:2125; }","duration":"192.178048ms","start":"2026-05-09T03:43:14.030071Z","end":"2026-05-09T03:43:14.222249Z","steps":["trace[796527545] 'read index received' (duration: 140.179679ms)","trace[796527545] 'applied index is now lower than readState.Index' (duration: 51.996999ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:43:14.222379Z","caller":"traceutil/trace.go:171","msg":"trace[1152623059] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"481.336594ms","start":"2026-05-09T03:43:13.741003Z","end":"2026-05-09T03:43:14.222339Z","steps":["trace[1152623059] 'process raft request' (duration: 480.989117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:14.222569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:13.740972Z","time spent":"481.493512ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:43:14.222575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.835848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-09T03:43:14.222643Z","caller":"traceutil/trace.go:171","msg":"trace[425217847] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:2048; }","duration":"296.955856ms","start":"2026-05-09T03:43:13.92567Z","end":"2026-05-09T03:43:14.222626Z","steps":["trace[425217847] 'agreement among raft nodes before linearized reading' (duration: 296.699299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:14.22261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.601492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:43:14.223505Z","caller":"traceutil/trace.go:171","msg":"trace[59099013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2048; }","duration":"417.79649ms","start":"2026-05-09T03:43:13.804909Z","end":"2026-05-09T03:43:14.222706Z","steps":["trace[59099013] 'agreement among raft nodes before linearized reading' (duration: 417.605772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:14.223599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:13.804888Z","time spent":"418.663772ms","remote":"127.0.0.1:52810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T03:43:14.390726Z","caller":"traceutil/trace.go:171","msg":"trace[563111755] linearizableReadLoop","detail":"{readStateIndex:2130; appliedIndex:2129; }","duration":"151.298287ms","start":"2026-05-09T03:43:14.239401Z","end":"2026-05-09T03:43:14.3907Z","steps":["trace[563111755] 'read index received' (duration: 102.343721ms)","trace[563111755] 'applied index is now lower than readState.Index' (duration: 48.953496ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:43:14.39093Z","caller":"traceutil/trace.go:171","msg":"trace[2046138914] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"154.030673ms","start":"2026-05-09T03:43:14.236872Z","end":"2026-05-09T03:43:14.390903Z","steps":["trace[2046138914] 'process raft request' (duration: 104.843819ms)","trace[2046138914] 'compare' (duration: 48.859667ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:43:14.390952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.529035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-09T03:43:14.390999Z","caller":"traceutil/trace.go:171","msg":"trace[886178702] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2050; }","duration":"151.608975ms","start":"2026-05-09T03:43:14.23938Z","end":"2026-05-09T03:43:14.390989Z","steps":["trace[886178702] 'agreement among raft nodes before linearized reading' (duration: 151.470326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:14.92933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.666202ms","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-05-09T03:43:14.929393Z","caller":"traceutil/trace.go:171","msg":"trace[2142859631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2050; }","duration":"418.775081ms","start":"2026-05-09T03:43:14.510605Z","end":"2026-05-09T03:43:14.92938Z","steps":["trace[2142859631] 'range keys from in-memory index tree' (duration: 418.520754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:14.929418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:14.510584Z","time spent":"418.828231ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T03:43:14.929733Z","caller":"traceutil/trace.go:171","msg":"trace[942843058] linearizableReadLoop","detail":"{readStateIndex:2131; appliedIndex:2130; }","duration":"412.659197ms","start":"2026-05-09T03:43:14.517063Z","end":"2026-05-09T03:43:14.929722Z","steps":["trace[942843058] 'read index received' (duration: 371.62966ms)","trace[942843058] 'applied index is now lower than readState.Index' (duration: 41.028767ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:43:14.929889Z","caller":"traceutil/trace.go:171","msg":"trace[1000003190] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"414.397981ms","start":"2026-05-09T03:43:14.515484Z","end":"2026-05-09T03:43:14.929882Z","steps":["trace[1000003190] 'process raft request' (duration: 373.316815ms)","trace[1000003190] 'compare' (duration: 40.420803ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:43:14.929951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:14.515456Z","time spent":"414.451011ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:43:14.930052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.995814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18adc8c80d28d0f7\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-09T03:43:14.930072Z","caller":"traceutil/trace.go:171","msg":"trace[126598715] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18adc8c80d28d0f7; range_end:; response_count:1; response_revision:2051; }","duration":"413.036393ms","start":"2026-05-09T03:43:14.517029Z","end":"2026-05-09T03:43:14.930066Z","steps":["trace[126598715] 'agreement among raft nodes before linearized reading' (duration: 412.984463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:14.930085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:14.517021Z","time spent":"413.061583ms","remote":"127.0.0.1:52910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18adc8c80d28d0f7\" "} {"level":"warn","ts":"2026-05-09T03:43:14.930056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.022758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T03:43:14.930344Z","caller":"traceutil/trace.go:171","msg":"trace[5243758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2050; }","duration":"418.506134ms","start":"2026-05-09T03:43:14.51178Z","end":"2026-05-09T03:43:14.930286Z","steps":["trace[5243758] 'range keys from in-memory index tree' (duration: 417.510154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:14.930228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.120624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:43:14.930406Z","caller":"traceutil/trace.go:171","msg":"trace[257535667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2051; }","duration":"125.368424ms","start":"2026-05-09T03:43:14.80503Z","end":"2026-05-09T03:43:14.930398Z","steps":["trace[257535667] 'agreement among raft nodes before linearized reading' (duration: 125.136305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:14.930456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:14.511772Z","time spent":"418.660233ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T03:43:15.382569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.521833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T03:43:15.382941Z","caller":"traceutil/trace.go:171","msg":"trace[995446370] linearizableReadLoop","detail":"{readStateIndex:2135; appliedIndex:2131; }","duration":"403.816189ms","start":"2026-05-09T03:43:14.979102Z","end":"2026-05-09T03:43:15.382918Z","steps":["trace[995446370] 'read index received' (duration: 254.8237ms)","trace[995446370] 'applied index is now lower than readState.Index' (duration: 148.991129ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:43:15.383007Z","caller":"traceutil/trace.go:171","msg":"trace[667926073] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"449.152077ms","start":"2026-05-09T03:43:14.933825Z","end":"2026-05-09T03:43:15.382977Z","steps":["trace[667926073] 'process raft request' (duration: 448.87418ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:43:15.383041Z","caller":"traceutil/trace.go:171","msg":"trace[92198944] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"448.655822ms","start":"2026-05-09T03:43:14.934365Z","end":"2026-05-09T03:43:15.383021Z","steps":["trace[92198944] 'process raft request' (duration: 448.446714ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:43:15.383107Z","caller":"traceutil/trace.go:171","msg":"trace[1546884329] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"446.722509ms","start":"2026-05-09T03:43:14.936367Z","end":"2026-05-09T03:43:15.38309Z","steps":["trace[1546884329] 'process raft request' (duration: 446.498801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:15.383154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:14.933811Z","time spent":"449.263106ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:43:15.383209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:14.936353Z","time spent":"446.805627ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:43:15.383219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:14.934347Z","time spent":"448.736191ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:43:15.38338Z","caller":"traceutil/trace.go:171","msg":"trace[1214528895] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"449.915959ms","start":"2026-05-09T03:43:14.933364Z","end":"2026-05-09T03:43:15.38328Z","steps":["trace[1214528895] 'process raft request' (duration: 300.553204ms)","trace[1214528895] 'compare' (duration: 148.259486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:43:15.383583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.471371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-09T03:43:15.383593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.059579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"warn","ts":"2026-05-09T03:43:15.3836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:14.933334Z","time spent":"450.143907ms","remote":"127.0.0.1:52910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:43:15.383641Z","caller":"traceutil/trace.go:171","msg":"trace[1212478571] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2055; }","duration":"404.558421ms","start":"2026-05-09T03:43:14.979068Z","end":"2026-05-09T03:43:15.383626Z","steps":["trace[1212478571] 'agreement among raft nodes before linearized reading' (duration: 404.364173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:15.383618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.151674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T03:43:15.383799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:14.979054Z","time spent":"404.729649ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T03:43:15.38365Z","caller":"traceutil/trace.go:171","msg":"trace[1434814459] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2055; }","duration":"365.159219ms","start":"2026-05-09T03:43:15.018478Z","end":"2026-05-09T03:43:15.383637Z","steps":["trace[1434814459] 'agreement among raft nodes before linearized reading' (duration: 364.747863ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:43:15.383895Z","caller":"traceutil/trace.go:171","msg":"trace[1790248284] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2055; }","duration":"329.395422ms","start":"2026-05-09T03:43:15.054391Z","end":"2026-05-09T03:43:15.383786Z","steps":["trace[1790248284] 'agreement among raft nodes before linearized reading' (duration: 329.065845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:15.383946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:15.018464Z","time spent":"365.457386ms","remote":"127.0.0.1:53018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12408,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-09T03:43:15.383955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:15.054373Z","time spent":"329.565651ms","remote":"127.0.0.1:41926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T03:43:15.894635Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340570893869938,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T03:43:16.009274Z","caller":"traceutil/trace.go:171","msg":"trace[379551670] linearizableReadLoop","detail":"{readStateIndex:2136; appliedIndex:2135; }","duration":"614.707247ms","start":"2026-05-09T03:43:15.394538Z","end":"2026-05-09T03:43:16.009245Z","steps":["trace[379551670] 'read index received' (duration: 614.24243ms)","trace[379551670] 'applied index is now lower than readState.Index' (duration: 463.197µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:43:16.009359Z","caller":"traceutil/trace.go:171","msg":"trace[876482267] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"621.359596ms","start":"2026-05-09T03:43:15.387977Z","end":"2026-05-09T03:43:16.009336Z","steps":["trace[876482267] 'process raft request' (duration: 620.897971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:16.009495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:15.387952Z","time spent":"621.465405ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:43:16.009536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.970588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-05-09T03:43:16.009526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.324927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:43:16.009571Z","caller":"traceutil/trace.go:171","msg":"trace[1631181630] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2056; }","duration":"544.059357ms","start":"2026-05-09T03:43:15.465501Z","end":"2026-05-09T03:43:16.00956Z","steps":["trace[1631181630] 'agreement among raft nodes before linearized reading' (duration: 543.898849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:16.009606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:15.465482Z","time spent":"544.116516ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-09T03:43:16.009605Z","caller":"traceutil/trace.go:171","msg":"trace[720569931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2056; }","duration":"204.446526ms","start":"2026-05-09T03:43:15.805137Z","end":"2026-05-09T03:43:16.009584Z","steps":["trace[720569931] 'agreement among raft nodes before linearized reading' (duration: 204.320147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:16.009848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.301902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:43:16.009957Z","caller":"traceutil/trace.go:171","msg":"trace[2054212466] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2056; }","duration":"615.439811ms","start":"2026-05-09T03:43:15.394502Z","end":"2026-05-09T03:43:16.009942Z","steps":["trace[2054212466] 'agreement among raft nodes before linearized reading' (duration: 614.863246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:43:16.01Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:43:15.39449Z","time spent":"615.497609ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-09T03:44:10.306454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340570893870636,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T03:44:10.734389Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050036888s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T03:44:10.735008Z","caller":"traceutil/trace.go:171","msg":"trace[1112974561] transaction","detail":"{read_only:false; response_revision:2336; number_of_response:1; }","duration":"1.050776453s","start":"2026-05-09T03:44:09.684199Z","end":"2026-05-09T03:44:10.734975Z","steps":["trace[1112974561] 'process raft request' (duration: 1.050428785s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:10.735339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:09.684174Z","time spent":"1.050927272s","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:44:10.749435Z","caller":"traceutil/trace.go:171","msg":"trace[296393982] transaction","detail":"{read_only:false; response_revision:2337; number_of_response:1; }","duration":"1.063125952s","start":"2026-05-09T03:44:09.686288Z","end":"2026-05-09T03:44:10.749414Z","steps":["trace[296393982] 'process raft request' (duration: 1.062703806s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:10.749547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:09.686264Z","time spent":"1.06322273s","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:44:10.749849Z","caller":"traceutil/trace.go:171","msg":"trace[1529803572] transaction","detail":"{read_only:false; response_revision:2338; number_of_response:1; }","duration":"1.062814804s","start":"2026-05-09T03:44:09.68702Z","end":"2026-05-09T03:44:10.749835Z","steps":["trace[1529803572] 'process raft request' (duration: 1.06218777s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:10.749927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:09.687Z","time spent":"1.062889453s","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:44:10.750105Z","caller":"traceutil/trace.go:171","msg":"trace[1054696623] transaction","detail":"{read_only:false; response_revision:2339; number_of_response:1; }","duration":"518.487778ms","start":"2026-05-09T03:44:10.231593Z","end":"2026-05-09T03:44:10.750081Z","steps":["trace[1054696623] 'process raft request' (duration: 518.097471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:10.750307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:10.231564Z","time spent":"518.648986ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:44:10.750391Z","caller":"traceutil/trace.go:171","msg":"trace[858766366] transaction","detail":"{read_only:false; response_revision:2340; number_of_response:1; }","duration":"325.357944ms","start":"2026-05-09T03:44:10.425005Z","end":"2026-05-09T03:44:10.750363Z","steps":["trace[858766366] 'process raft request' (duration: 324.965048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:10.750492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:10.424963Z","time spent":"325.479443ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:44:10.750733Z","caller":"traceutil/trace.go:171","msg":"trace[243356816] linearizableReadLoop","detail":"{readStateIndex:2431; appliedIndex:2427; }","duration":"944.903184ms","start":"2026-05-09T03:44:09.80579Z","end":"2026-05-09T03:44:10.750693Z","steps":["trace[243356816] 'read index received' (duration: 928.902828ms)","trace[243356816] 'applied index is now lower than readState.Index' (duration: 15.999086ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:44:10.750793Z","caller":"traceutil/trace.go:171","msg":"trace[1646031703] transaction","detail":"{read_only:false; response_revision:2341; number_of_response:1; }","duration":"324.636691ms","start":"2026-05-09T03:44:10.426128Z","end":"2026-05-09T03:44:10.750764Z","steps":["trace[1646031703] 'process raft request' (duration: 324.466093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:10.750881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.090662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:44:10.750937Z","caller":"traceutil/trace.go:171","msg":"trace[2047352089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2341; }","duration":"945.144712ms","start":"2026-05-09T03:44:09.80578Z","end":"2026-05-09T03:44:10.750924Z","steps":["trace[2047352089] 'agreement among raft nodes before linearized reading' (duration: 945.057293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:10.750952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:10.426107Z","time spent":"324.75902ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:44:10.750969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:09.805731Z","time spent":"945.23206ms","remote":"127.0.0.1:52826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T03:44:10.751107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.388885ms","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-05-09T03:44:10.751173Z","caller":"traceutil/trace.go:171","msg":"trace[1761380423] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2341; }","duration":"554.520694ms","start":"2026-05-09T03:44:10.196637Z","end":"2026-05-09T03:44:10.751158Z","steps":["trace[1761380423] 'agreement among raft nodes before linearized reading' (duration: 554.385385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:10.751248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.35913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-09T03:44:10.751286Z","caller":"traceutil/trace.go:171","msg":"trace[1195309780] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2341; }","duration":"371.44299ms","start":"2026-05-09T03:44:10.379828Z","end":"2026-05-09T03:44:10.751271Z","steps":["trace[1195309780] 'agreement among raft nodes before linearized reading' (duration: 371.279701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:10.751293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:10.196612Z","time spent":"554.641143ms","remote":"127.0.0.1:53204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-05-09T03:44:10.751366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:10.379809Z","time spent":"371.543479ms","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-09T03:44:10.751453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.289743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T03:44:10.751518Z","caller":"traceutil/trace.go:171","msg":"trace[1055994904] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2341; }","duration":"482.408052ms","start":"2026-05-09T03:44:10.269094Z","end":"2026-05-09T03:44:10.751502Z","steps":["trace[1055994904] 'agreement among raft nodes before linearized reading' (duration: 482.168655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:10.751803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:10.269074Z","time spent":"482.663731ms","remote":"127.0.0.1:50714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":17,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-05-09T03:44:10.751517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.449101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-09T03:44:10.752914Z","caller":"traceutil/trace.go:171","msg":"trace[1427836141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2341; }","duration":"512.295304ms","start":"2026-05-09T03:44:10.239999Z","end":"2026-05-09T03:44:10.752294Z","steps":["trace[1427836141] 'agreement among raft nodes before linearized reading' (duration: 511.266283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:10.752969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:10.239988Z","time spent":"512.965428ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T03:44:49.834125Z","caller":"traceutil/trace.go:171","msg":"trace[2034361940] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"151.064364ms","start":"2026-05-09T03:44:49.68304Z","end":"2026-05-09T03:44:49.834104Z","steps":["trace[2034361940] 'process raft request' (duration: 150.915665ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:44:49.857496Z","caller":"traceutil/trace.go:171","msg":"trace[1655541726] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"173.70317ms","start":"2026-05-09T03:44:49.683755Z","end":"2026-05-09T03:44:49.857458Z","steps":["trace[1655541726] 'process raft request' (duration: 173.426113ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:44:49.857963Z","caller":"traceutil/trace.go:171","msg":"trace[2054139171] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"173.161906ms","start":"2026-05-09T03:44:49.684785Z","end":"2026-05-09T03:44:49.857947Z","steps":["trace[2054139171] 'process raft request' (duration: 172.573ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:44:49.858068Z","caller":"traceutil/trace.go:171","msg":"trace[97775723] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"172.993798ms","start":"2026-05-09T03:44:49.685059Z","end":"2026-05-09T03:44:49.858053Z","steps":["trace[97775723] 'process raft request' (duration: 172.345003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:57.625859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.879002ms","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-05-09T03:44:57.625946Z","caller":"traceutil/trace.go:171","msg":"trace[1267530547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2690; }","duration":"581.010691ms","start":"2026-05-09T03:44:57.044916Z","end":"2026-05-09T03:44:57.625927Z","steps":["trace[1267530547] 'range keys from in-memory index tree' (duration: 580.717194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:57.625986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:57.044899Z","time spent":"581.0786ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T03:44:57.626174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.391724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:44:57.626212Z","caller":"traceutil/trace.go:171","msg":"trace[723493919] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2690; }","duration":"421.431893ms","start":"2026-05-09T03:44:57.204769Z","end":"2026-05-09T03:44:57.626201Z","steps":["trace[723493919] 'count revisions from in-memory index tree' (duration: 421.335144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:57.626247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:57.204735Z","time spent":"421.504023ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-09T03:44:57.626372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.336298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-09T03:44:57.626497Z","caller":"traceutil/trace.go:171","msg":"trace[1692967424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2690; }","duration":"581.478836ms","start":"2026-05-09T03:44:57.044989Z","end":"2026-05-09T03:44:57.626467Z","steps":["trace[1692967424] 'range keys from in-memory index tree' (duration: 581.193049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:57.62705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:57.044977Z","time spent":"582.063901ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T03:44:57.62652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.153124ms","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-05-09T03:44:57.627302Z","caller":"traceutil/trace.go:171","msg":"trace[876431629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2690; }","duration":"576.942546ms","start":"2026-05-09T03:44:57.050341Z","end":"2026-05-09T03:44:57.627283Z","steps":["trace[876431629] 'range keys from in-memory index tree' (duration: 576.042615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:57.62739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:57.050334Z","time spent":"577.041305ms","remote":"127.0.0.1:53086","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-05-09T03:44:57.626537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.161094ms","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-05-09T03:44:57.627552Z","caller":"traceutil/trace.go:171","msg":"trace[1190766628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2690; }","duration":"577.187105ms","start":"2026-05-09T03:44:57.050354Z","end":"2026-05-09T03:44:57.627541Z","steps":["trace[1190766628] 'range keys from in-memory index tree' (duration: 576.088115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:57.627592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:57.050347Z","time spent":"577.231784ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T03:44:57.628415Z","caller":"traceutil/trace.go:171","msg":"trace[389668022] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"334.212767ms","start":"2026-05-09T03:44:57.294174Z","end":"2026-05-09T03:44:57.628387Z","steps":["trace[389668022] 'process raft request' (duration: 334.063427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:57.630063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:57.294149Z","time spent":"334.335115ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:44:59.502797Z","caller":"traceutil/trace.go:171","msg":"trace[1670246526] linearizableReadLoop","detail":"{readStateIndex:2804; appliedIndex:2803; }","duration":"318.811708ms","start":"2026-05-09T03:44:59.183961Z","end":"2026-05-09T03:44:59.502773Z","steps":["trace[1670246526] 'read index received' (duration: 308.697158ms)","trace[1670246526] 'applied index is now lower than readState.Index' (duration: 10.11335ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:44:59.502923Z","caller":"traceutil/trace.go:171","msg":"trace[76909527] transaction","detail":"{read_only:false; response_revision:2701; number_of_response:1; }","duration":"466.978338ms","start":"2026-05-09T03:44:59.035923Z","end":"2026-05-09T03:44:59.502901Z","steps":["trace[76909527] 'process raft request' (duration: 456.72244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:59.502983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.998806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:44:59.503022Z","caller":"traceutil/trace.go:171","msg":"trace[319696464] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2701; }","duration":"319.090595ms","start":"2026-05-09T03:44:59.183917Z","end":"2026-05-09T03:44:59.503008Z","steps":["trace[319696464] 'agreement among raft nodes before linearized reading' (duration: 318.948356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:44:59.50304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:59.035895Z","time spent":"467.082947ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:44:59.503056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:44:59.183896Z","time spent":"319.150004ms","remote":"127.0.0.1:50732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-09T03:45:25.941283Z","caller":"traceutil/trace.go:171","msg":"trace[1493603504] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"201.185164ms","start":"2026-05-09T03:45:25.740053Z","end":"2026-05-09T03:45:25.941239Z","steps":["trace[1493603504] 'process raft request' (duration: 168.400079ms)","trace[1493603504] 'compare' (duration: 32.437079ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:45:25.941316Z","caller":"traceutil/trace.go:171","msg":"trace[649950351] linearizableReadLoop","detail":"{readStateIndex:2957; appliedIndex:2956; }","duration":"151.227842ms","start":"2026-05-09T03:45:25.789816Z","end":"2026-05-09T03:45:25.941044Z","steps":["trace[649950351] 'read index received' (duration: 118.637755ms)","trace[649950351] 'applied index is now lower than readState.Index' (duration: 32.589317ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:45:25.941541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.726748ms","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-05-09T03:45:25.941565Z","caller":"traceutil/trace.go:171","msg":"trace[1420821222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2848; }","duration":"151.809047ms","start":"2026-05-09T03:45:25.789747Z","end":"2026-05-09T03:45:25.941556Z","steps":["trace[1420821222] 'agreement among raft nodes before linearized reading' (duration: 151.680859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:45:25.941698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.643455ms","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-05-09T03:45:25.941728Z","caller":"traceutil/trace.go:171","msg":"trace[1309883773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2848; }","duration":"148.737535ms","start":"2026-05-09T03:45:25.792982Z","end":"2026-05-09T03:45:25.941719Z","steps":["trace[1309883773] 'agreement among raft nodes before linearized reading' (duration: 148.597236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:45:25.941882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.615885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-09T03:45:25.941897Z","caller":"traceutil/trace.go:171","msg":"trace[792103359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2848; }","duration":"147.652975ms","start":"2026-05-09T03:45:25.79424Z","end":"2026-05-09T03:45:25.941893Z","steps":["trace[792103359] 'agreement among raft nodes before linearized reading' (duration: 147.594726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:45:25.941991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.957422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T03:45:25.94202Z","caller":"traceutil/trace.go:171","msg":"trace[414040412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2848; }","duration":"149.038222ms","start":"2026-05-09T03:45:25.792975Z","end":"2026-05-09T03:45:25.942013Z","steps":["trace[414040412] 'agreement among raft nodes before linearized reading' (duration: 148.953273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:45:32.562429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.069669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T03:45:32.562563Z","caller":"traceutil/trace.go:171","msg":"trace[322742171] transaction","detail":"{read_only:false; response_revision:2881; number_of_response:1; }","duration":"689.103995ms","start":"2026-05-09T03:45:31.873438Z","end":"2026-05-09T03:45:32.562542Z","steps":["trace[322742171] 'process raft request' (duration: 523.834808ms)","trace[322742171] 'compare' (duration: 164.503764ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:45:32.562895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:45:31.87343Z","time spent":"689.334653ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:45:32.912133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.024292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T03:45:32.912644Z","caller":"traceutil/trace.go:171","msg":"trace[1692529677] linearizableReadLoop","detail":"{readStateIndex:2992; appliedIndex:2990; }","duration":"921.982364ms","start":"2026-05-09T03:45:31.990611Z","end":"2026-05-09T03:45:32.912594Z","steps":["trace[1692529677] 'read index received' (duration: 406.637659ms)","trace[1692529677] 'applied index is now lower than readState.Index' (duration: 515.341525ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:45:32.91271Z","caller":"traceutil/trace.go:171","msg":"trace[391526177] transaction","detail":"{read_only:false; response_revision:2882; number_of_response:1; }","duration":"951.004773ms","start":"2026-05-09T03:45:31.961646Z","end":"2026-05-09T03:45:32.912651Z","steps":["trace[391526177] 'process raft request' (duration: 762.376567ms)","trace[391526177] 'compare' (duration: 187.893393ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:45:32.912953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:45:31.961616Z","time spent":"951.206372ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T03:45:32.913087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.482119ms","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-05-09T03:45:32.913235Z","caller":"traceutil/trace.go:171","msg":"trace[1827007227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2882; }","duration":"922.683828ms","start":"2026-05-09T03:45:31.990535Z","end":"2026-05-09T03:45:32.913219Z","steps":["trace[1827007227] 'agreement among raft nodes before linearized reading' (duration: 922.364221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:45:32.913285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.392154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:45:32.913354Z","caller":"traceutil/trace.go:171","msg":"trace[1064496873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2882; }","duration":"144.479924ms","start":"2026-05-09T03:45:32.768852Z","end":"2026-05-09T03:45:32.913332Z","steps":["trace[1064496873] 'agreement among raft nodes before linearized reading' (duration: 144.414694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:45:32.913393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.498729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-05-09T03:45:32.913245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.034402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:45:32.913456Z","caller":"traceutil/trace.go:171","msg":"trace[571683778] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2882; }","duration":"756.58516ms","start":"2026-05-09T03:45:32.156856Z","end":"2026-05-09T03:45:32.913441Z","steps":["trace[571683778] 'agreement among raft nodes before linearized reading' (duration: 756.432491ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:45:32.913472Z","caller":"traceutil/trace.go:171","msg":"trace[1713677880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2882; }","duration":"109.338029ms","start":"2026-05-09T03:45:32.804117Z","end":"2026-05-09T03:45:32.913455Z","steps":["trace[1713677880] 'agreement among raft nodes before linearized reading' (duration: 109.0971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:45:32.913381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:45:31.990506Z","time spent":"922.814687ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T03:45:32.913589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.779437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T03:45:32.913626Z","caller":"traceutil/trace.go:171","msg":"trace[349845387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2882; }","duration":"863.835296ms","start":"2026-05-09T03:45:32.049782Z","end":"2026-05-09T03:45:32.913617Z","steps":["trace[349845387] 'agreement among raft nodes before linearized reading' (duration: 863.731487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:45:32.913236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.248686ms","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-05-09T03:45:32.913696Z","caller":"traceutil/trace.go:171","msg":"trace[1673692063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2882; }","duration":"868.768092ms","start":"2026-05-09T03:45:32.044916Z","end":"2026-05-09T03:45:32.913684Z","steps":["trace[1673692063] 'agreement among raft nodes before linearized reading' (duration: 868.043707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:45:32.913494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:45:32.156837Z","time spent":"756.649359ms","remote":"127.0.0.1:53020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-09T03:45:32.91374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.843837ms","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-05-09T03:45:32.913753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:45:32.044891Z","time spent":"868.840661ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T03:45:32.913767Z","caller":"traceutil/trace.go:171","msg":"trace[1286120451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2882; }","duration":"864.891567ms","start":"2026-05-09T03:45:32.048867Z","end":"2026-05-09T03:45:32.913758Z","steps":["trace[1286120451] 'agreement among raft nodes before linearized reading' (duration: 864.807328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:45:32.913795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:45:32.048856Z","time spent":"864.932487ms","remote":"127.0.0.1:53086","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-05-09T03:45:32.913149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.739709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:45:32.91391Z","caller":"traceutil/trace.go:171","msg":"trace[1214940362] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2882; }","duration":"652.690532ms","start":"2026-05-09T03:45:32.261203Z","end":"2026-05-09T03:45:32.913894Z","steps":["trace[1214940362] 'agreement among raft nodes before linearized reading' (duration: 651.769819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:45:32.913657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:45:32.049751Z","time spent":"863.898086ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T03:45:32.913954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:45:32.261171Z","time spent":"652.76998ms","remote":"127.0.0.1:50698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-05-09T03:49:21.088844Z","caller":"traceutil/trace.go:171","msg":"trace[1600992084] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4601; }","duration":"123.894355ms","start":"2026-05-09T03:49:20.964919Z","end":"2026-05-09T03:49:21.088813Z","steps":["trace[1600992084] 'process raft request' (duration: 119.839861ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:23.392434Z","caller":"traceutil/trace.go:171","msg":"trace[1656679511] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"210.215009ms","start":"2026-05-09T03:49:23.182176Z","end":"2026-05-09T03:49:23.392391Z","steps":["trace[1656679511] 'process raft request' (duration: 202.630609ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:26.538784Z","caller":"traceutil/trace.go:171","msg":"trace[1507855005] linearizableReadLoop","detail":"{readStateIndex:4930; appliedIndex:4929; }","duration":"206.058254ms","start":"2026-05-09T03:49:26.332701Z","end":"2026-05-09T03:49:26.538759Z","steps":["trace[1507855005] 'read index received' (duration: 205.671603ms)","trace[1507855005] 'applied index is now lower than readState.Index' (duration: 385.251µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:49:26.540659Z","caller":"traceutil/trace.go:171","msg":"trace[532768081] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"254.101949ms","start":"2026-05-09T03:49:26.286526Z","end":"2026-05-09T03:49:26.540628Z","steps":["trace[532768081] 'process raft request' (duration: 251.979657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:26.541179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.391695ms","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-05-09T03:49:26.541446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.888828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-09T03:49:26.541422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.189071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T03:49:26.541555Z","caller":"traceutil/trace.go:171","msg":"trace[34887875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4748; }","duration":"169.392057ms","start":"2026-05-09T03:49:26.372136Z","end":"2026-05-09T03:49:26.541528Z","steps":["trace[34887875] 'agreement among raft nodes before linearized reading' (duration: 168.81772ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:26.541388Z","caller":"traceutil/trace.go:171","msg":"trace[805769454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4748; }","duration":"208.58368ms","start":"2026-05-09T03:49:26.332692Z","end":"2026-05-09T03:49:26.541276Z","steps":["trace[805769454] 'agreement among raft nodes before linearized reading' (duration: 208.181558ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:26.541495Z","caller":"traceutil/trace.go:171","msg":"trace[1370551943] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4748; }","duration":"182.96958ms","start":"2026-05-09T03:49:26.358514Z","end":"2026-05-09T03:49:26.541484Z","steps":["trace[1370551943] 'agreement among raft nodes before linearized reading' (duration: 182.140835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:26.546348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.681676ms","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-05-09T03:49:26.546458Z","caller":"traceutil/trace.go:171","msg":"trace[17794209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4748; }","duration":"174.092917ms","start":"2026-05-09T03:49:26.372336Z","end":"2026-05-09T03:49:26.546428Z","steps":["trace[17794209] 'agreement among raft nodes before linearized reading' (duration: 168.47043ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:26.700247Z","caller":"traceutil/trace.go:171","msg":"trace[332470102] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"150.024921ms","start":"2026-05-09T03:49:26.550197Z","end":"2026-05-09T03:49:26.700222Z","steps":["trace[332470102] 'process raft request' (duration: 86.018473ms)","trace[332470102] 'compare' (duration: 63.159333ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:49:26.701768Z","caller":"traceutil/trace.go:171","msg":"trace[1800856564] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"151.049603ms","start":"2026-05-09T03:49:26.550686Z","end":"2026-05-09T03:49:26.701735Z","steps":["trace[1800856564] 'process raft request' (duration: 150.479536ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:26.702041Z","caller":"traceutil/trace.go:171","msg":"trace[1334893510] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"150.788815ms","start":"2026-05-09T03:49:26.551241Z","end":"2026-05-09T03:49:26.70203Z","steps":["trace[1334893510] 'process raft request' (duration: 150.100965ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:29.852106Z","caller":"traceutil/trace.go:171","msg":"trace[1469993646] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"125.39023ms","start":"2026-05-09T03:49:29.72666Z","end":"2026-05-09T03:49:29.852051Z","steps":["trace[1469993646] 'process raft request' (duration: 125.152883ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:30.456849Z","caller":"traceutil/trace.go:171","msg":"trace[2134819733] linearizableReadLoop","detail":"{readStateIndex:5016; appliedIndex:5015; }","duration":"199.187822ms","start":"2026-05-09T03:49:30.257627Z","end":"2026-05-09T03:49:30.456815Z","steps":["trace[2134819733] 'read index received' (duration: 172.718947ms)","trace[2134819733] 'applied index is now lower than readState.Index' (duration: 26.467575ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:49:30.457167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.437459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-09T03:49:30.457205Z","caller":"traceutil/trace.go:171","msg":"trace[481213193] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4828; }","duration":"199.601285ms","start":"2026-05-09T03:49:30.257595Z","end":"2026-05-09T03:49:30.457196Z","steps":["trace[481213193] 'agreement among raft nodes before linearized reading' (duration: 199.331386ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:30.457093Z","caller":"traceutil/trace.go:171","msg":"trace[492094556] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"235.942234ms","start":"2026-05-09T03:49:30.221097Z","end":"2026-05-09T03:49:30.457039Z","steps":["trace[492094556] 'process raft request' (duration: 209.302784ms)","trace[492094556] 'compare' (duration: 26.076614ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:49:36.289063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.386944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:49:36.289178Z","caller":"traceutil/trace.go:171","msg":"trace[1122065802] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4930; }","duration":"649.56167ms","start":"2026-05-09T03:49:35.63959Z","end":"2026-05-09T03:49:36.289152Z","steps":["trace[1122065802] 'range keys from in-memory index tree' (duration: 649.288751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:36.28922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:35.639558Z","time spent":"649.650622ms","remote":"127.0.0.1:53278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-09T03:49:36.289213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.218614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c4b0de3e-7428-44fa-bf91-fc704ae1f810\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-09T03:49:36.289293Z","caller":"traceutil/trace.go:171","msg":"trace[1658148722] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c4b0de3e-7428-44fa-bf91-fc704ae1f810; range_end:; response_count:1; response_revision:4930; }","duration":"581.369498ms","start":"2026-05-09T03:49:35.707904Z","end":"2026-05-09T03:49:36.289274Z","steps":["trace[1658148722] 'range keys from in-memory index tree' (duration: 581.010188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:36.289334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:35.707872Z","time spent":"581.45241ms","remote":"127.0.0.1:53018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c4b0de3e-7428-44fa-bf91-fc704ae1f810\" "} {"level":"info","ts":"2026-05-09T03:49:36.29279Z","caller":"traceutil/trace.go:171","msg":"trace[461274443] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"229.835709ms","start":"2026-05-09T03:49:36.062937Z","end":"2026-05-09T03:49:36.292773Z","steps":["trace[461274443] 'process raft request' (duration: 229.657844ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:36.376014Z","caller":"traceutil/trace.go:171","msg":"trace[1983836490] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"142.704813ms","start":"2026-05-09T03:49:36.233278Z","end":"2026-05-09T03:49:36.375983Z","steps":["trace[1983836490] 'process raft request' (duration: 142.386034ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:36.375806Z","caller":"traceutil/trace.go:171","msg":"trace[1329998557] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"156.196678ms","start":"2026-05-09T03:49:36.219582Z","end":"2026-05-09T03:49:36.375779Z","steps":["trace[1329998557] 'process raft request' (duration: 155.909671ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:36.376047Z","caller":"traceutil/trace.go:171","msg":"trace[751745465] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"142.641761ms","start":"2026-05-09T03:49:36.233379Z","end":"2026-05-09T03:49:36.37602Z","steps":["trace[751745465] 'process raft request' (duration: 142.542838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:36.384454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.963506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-09T03:49:36.384538Z","caller":"traceutil/trace.go:171","msg":"trace[674772386] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4935; }","duration":"103.089579ms","start":"2026-05-09T03:49:36.281425Z","end":"2026-05-09T03:49:36.384515Z","steps":["trace[674772386] 'agreement among raft nodes before linearized reading' (duration: 102.869403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:36.385208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.864964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5d76cb5987-bj556.18adc921e365cd30\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-05-09T03:49:36.385252Z","caller":"traceutil/trace.go:171","msg":"trace[1723666851] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5d76cb5987-bj556.18adc921e365cd30; range_end:; response_count:1; response_revision:4935; }","duration":"102.923085ms","start":"2026-05-09T03:49:36.282318Z","end":"2026-05-09T03:49:36.385241Z","steps":["trace[1723666851] 'agreement among raft nodes before linearized reading' (duration: 102.772081ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:36.494096Z","caller":"traceutil/trace.go:171","msg":"trace[851421212] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"100.461347ms","start":"2026-05-09T03:49:36.393608Z","end":"2026-05-09T03:49:36.494069Z","steps":["trace[851421212] 'process raft request' (duration: 100.19607ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:36.494125Z","caller":"traceutil/trace.go:171","msg":"trace[636265208] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4937; }","duration":"100.002373ms","start":"2026-05-09T03:49:36.394089Z","end":"2026-05-09T03:49:36.494092Z","steps":["trace[636265208] 'agreement among raft nodes before linearized reading' (duration: 99.87339ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:36.63505Z","caller":"traceutil/trace.go:171","msg":"trace[750162033] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"131.011678ms","start":"2026-05-09T03:49:36.503973Z","end":"2026-05-09T03:49:36.634984Z","steps":["trace[750162033] 'process raft request' (duration: 71.869811ms)","trace[750162033] 'compare' (duration: 58.729245ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:49:36.63538Z","caller":"traceutil/trace.go:171","msg":"trace[190934593] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"131.015848ms","start":"2026-05-09T03:49:36.504095Z","end":"2026-05-09T03:49:36.635111Z","steps":["trace[190934593] 'process raft request' (duration: 130.7219ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:36.635442Z","caller":"traceutil/trace.go:171","msg":"trace[1270469457] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"131.260075ms","start":"2026-05-09T03:49:36.50414Z","end":"2026-05-09T03:49:36.6354Z","steps":["trace[1270469457] 'process raft request' (duration: 130.790292ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:36.635559Z","caller":"traceutil/trace.go:171","msg":"trace[519377795] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"126.545133ms","start":"2026-05-09T03:49:36.509001Z","end":"2026-05-09T03:49:36.635546Z","steps":["trace[519377795] 'process raft request' (duration: 126.003068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:36.636592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.171123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-09T03:49:36.636649Z","caller":"traceutil/trace.go:171","msg":"trace[617963975] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4942; }","duration":"126.295006ms","start":"2026-05-09T03:49:36.510337Z","end":"2026-05-09T03:49:36.636632Z","steps":["trace[617963975] 'agreement among raft nodes before linearized reading' (duration: 126.022888ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:36.637235Z","caller":"traceutil/trace.go:171","msg":"trace[1332950409] linearizableReadLoop","detail":"{readStateIndex:5132; appliedIndex:5128; }","duration":"124.98105ms","start":"2026-05-09T03:49:36.510401Z","end":"2026-05-09T03:49:36.635382Z","steps":["trace[1332950409] 'read index received' (duration: 23.913616ms)","trace[1332950409] 'applied index is now lower than readState.Index' (duration: 101.064783ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:49:37.36644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.89526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T03:49:37.366653Z","caller":"traceutil/trace.go:171","msg":"trace[839168221] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"271.623414ms","start":"2026-05-09T03:49:37.09501Z","end":"2026-05-09T03:49:37.366633Z","steps":["trace[839168221] 'process raft request' (duration: 271.552132ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:37.366907Z","caller":"traceutil/trace.go:171","msg":"trace[1858575327] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"724.664273ms","start":"2026-05-09T03:49:36.642145Z","end":"2026-05-09T03:49:37.366809Z","steps":["trace[1858575327] 'process raft request' (duration: 395.300511ms)","trace[1858575327] 'compare' (duration: 328.772736ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:49:37.367136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:36.642117Z","time spent":"724.896339ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T03:49:37.367363Z","caller":"traceutil/trace.go:171","msg":"trace[194086692] linearizableReadLoop","detail":"{readStateIndex:5133; appliedIndex:5132; }","duration":"671.954645ms","start":"2026-05-09T03:49:36.695319Z","end":"2026-05-09T03:49:37.367274Z","steps":["trace[194086692] 'read index received' (duration: 341.998076ms)","trace[194086692] 'applied index is now lower than readState.Index' (duration: 329.515047ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:49:37.367421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.071058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:49:37.367592Z","caller":"traceutil/trace.go:171","msg":"trace[937283285] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:4944; }","duration":"672.302844ms","start":"2026-05-09T03:49:36.695269Z","end":"2026-05-09T03:49:37.367572Z","steps":["trace[937283285] 'agreement among raft nodes before linearized reading' (duration: 672.057547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:37.367645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:36.695255Z","time spent":"672.381847ms","remote":"127.0.0.1:58374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-05-09T03:49:37.367842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.443028ms","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-05-09T03:49:37.3679Z","caller":"traceutil/trace.go:171","msg":"trace[1400532540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4944; }","duration":"182.631942ms","start":"2026-05-09T03:49:37.185255Z","end":"2026-05-09T03:49:37.367887Z","steps":["trace[1400532540] 'agreement among raft nodes before linearized reading' (duration: 182.468398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:37.368245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.795784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:49:37.368276Z","caller":"traceutil/trace.go:171","msg":"trace[1157527800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4944; }","duration":"563.881546ms","start":"2026-05-09T03:49:36.804387Z","end":"2026-05-09T03:49:37.368269Z","steps":["trace[1157527800] 'agreement among raft nodes before linearized reading' (duration: 563.828624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:37.368297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:36.804362Z","time spent":"563.930368ms","remote":"127.0.0.1:52810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T03:49:37.368428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.390261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:49:37.368447Z","caller":"traceutil/trace.go:171","msg":"trace[366338765] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4944; }","duration":"494.415462ms","start":"2026-05-09T03:49:36.874025Z","end":"2026-05-09T03:49:37.368441Z","steps":["trace[366338765] 'agreement among raft nodes before linearized reading' (duration: 494.376621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:37.368467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:36.873941Z","time spent":"494.521185ms","remote":"127.0.0.1:57760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"warn","ts":"2026-05-09T03:49:37.368851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.221175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-09T03:49:37.368664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.763452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-09T03:49:37.368902Z","caller":"traceutil/trace.go:171","msg":"trace[497154354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4944; }","duration":"606.05126ms","start":"2026-05-09T03:49:36.762835Z","end":"2026-05-09T03:49:37.368887Z","steps":["trace[497154354] 'agreement among raft nodes before linearized reading' (duration: 605.621458ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:37.368907Z","caller":"traceutil/trace.go:171","msg":"trace[646372764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4944; }","duration":"606.491542ms","start":"2026-05-09T03:49:36.762385Z","end":"2026-05-09T03:49:37.368876Z","steps":["trace[646372764] 'agreement among raft nodes before linearized reading' (duration: 606.208135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:37.368938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:36.76281Z","time spent":"606.120002ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T03:49:37.368941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:36.762359Z","time spent":"606.573995ms","remote":"127.0.0.1:53086","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-05-09T03:49:37.37272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.850606ms","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-05-09T03:49:37.372803Z","caller":"traceutil/trace.go:171","msg":"trace[1250498817] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4944; }","duration":"613.994551ms","start":"2026-05-09T03:49:36.75879Z","end":"2026-05-09T03:49:37.372785Z","steps":["trace[1250498817] 'agreement among raft nodes before linearized reading' (duration: 608.895719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:37.372846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:36.758767Z","time spent":"614.068212ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T03:49:44.481419Z","caller":"traceutil/trace.go:171","msg":"trace[1437000068] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"119.101003ms","start":"2026-05-09T03:49:44.362295Z","end":"2026-05-09T03:49:44.481396Z","steps":["trace[1437000068] 'process raft request' (duration: 118.924829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:50.44562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02085465s","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-05-09T03:49:50.445733Z","caller":"traceutil/trace.go:171","msg":"trace[1031908816] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5102; }","duration":"1.020990933s","start":"2026-05-09T03:49:49.42472Z","end":"2026-05-09T03:49:50.445711Z","steps":["trace[1031908816] 'range keys from in-memory index tree' (duration: 1.020586003s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:50.445791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:49.424703Z","time spent":"1.021075975s","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-05-09T03:49:50.446202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.023263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-09T03:49:50.446238Z","caller":"traceutil/trace.go:171","msg":"trace[190850835] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5102; }","duration":"938.115345ms","start":"2026-05-09T03:49:49.508111Z","end":"2026-05-09T03:49:50.446227Z","steps":["trace[190850835] 'range keys from in-memory index tree' (duration: 937.878369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:50.446274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:49.508079Z","time spent":"938.188757ms","remote":"127.0.0.1:58366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3338,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-05-09T03:49:50.446562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.757904ms","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-05-09T03:49:50.4466Z","caller":"traceutil/trace.go:171","msg":"trace[937486357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5102; }","duration":"919.839037ms","start":"2026-05-09T03:49:49.526752Z","end":"2026-05-09T03:49:50.446591Z","steps":["trace[937486357] 'range keys from in-memory index tree' (duration: 919.638931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:50.446627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:49.526729Z","time spent":"919.891357ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T03:49:50.447205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.318525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-09T03:49:50.447544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.928638ms","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-05-09T03:49:50.447598Z","caller":"traceutil/trace.go:171","msg":"trace[1798103576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5102; }","duration":"913.007ms","start":"2026-05-09T03:49:49.534576Z","end":"2026-05-09T03:49:50.447583Z","steps":["trace[1798103576] 'range keys from in-memory index tree' (duration: 912.818375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:50.447649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:49.534562Z","time spent":"913.075562ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T03:49:50.447888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.217453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-09T03:49:50.447927Z","caller":"traceutil/trace.go:171","msg":"trace[2072211804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5102; }","duration":"914.302985ms","start":"2026-05-09T03:49:49.533613Z","end":"2026-05-09T03:49:50.447916Z","steps":["trace[2072211804] 'range keys from in-memory index tree' (duration: 914.0889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:50.447961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:49.533589Z","time spent":"914.363977ms","remote":"127.0.0.1:53086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T03:49:50.448081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.894695ms","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-05-09T03:49:50.448115Z","caller":"traceutil/trace.go:171","msg":"trace[543030506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5102; }","duration":"913.947656ms","start":"2026-05-09T03:49:49.534158Z","end":"2026-05-09T03:49:50.448106Z","steps":["trace[543030506] 'range keys from in-memory index tree' (duration: 913.799332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:50.448395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.524755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T03:49:50.448436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:49.534145Z","time spent":"914.280155ms","remote":"127.0.0.1:53086","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":"info","ts":"2026-05-09T03:49:50.448444Z","caller":"traceutil/trace.go:171","msg":"trace[440943122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5102; }","duration":"643.823143ms","start":"2026-05-09T03:49:49.804607Z","end":"2026-05-09T03:49:50.44843Z","steps":["trace[440943122] 'range keys from in-memory index tree' (duration: 643.468464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:50.448475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:49.804591Z","time spent":"643.876794ms","remote":"127.0.0.1:52810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T03:49:50.4487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.695536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5d76cb5987-bj556\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-05-09T03:49:50.448737Z","caller":"traceutil/trace.go:171","msg":"trace[2089662687] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-5d76cb5987-bj556; range_end:; response_count:1; response_revision:5102; }","duration":"713.791668ms","start":"2026-05-09T03:49:49.734937Z","end":"2026-05-09T03:49:50.448728Z","steps":["trace[2089662687] 'range keys from in-memory index tree' (duration: 713.548563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:50.448771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:49.734906Z","time spent":"713.85892ms","remote":"127.0.0.1:53018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11870,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5d76cb5987-bj556\" "} {"level":"info","ts":"2026-05-09T03:49:50.448791Z","caller":"traceutil/trace.go:171","msg":"trace[451682401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5102; }","duration":"914.501099ms","start":"2026-05-09T03:49:49.532785Z","end":"2026-05-09T03:49:50.447286Z","steps":["trace[451682401] 'range keys from in-memory index tree' (duration: 914.088139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:50.448836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:49.532739Z","time spent":"916.08553ms","remote":"127.0.0.1:53086","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-05-09T03:49:50.449219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.191278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-09T03:49:50.449741Z","caller":"traceutil/trace.go:171","msg":"trace[1810614912] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5102; }","duration":"774.814124ms","start":"2026-05-09T03:49:49.67469Z","end":"2026-05-09T03:49:50.449504Z","steps":["trace[1810614912] 'range keys from in-memory index tree' (duration: 773.659674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:50.450025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T03:49:49.674663Z","time spent":"775.342986ms","remote":"127.0.0.1:58366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3338,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-05-09T03:49:50.655792Z","caller":"traceutil/trace.go:171","msg":"trace[498183069] transaction","detail":"{read_only:false; response_revision:5104; number_of_response:1; }","duration":"196.659556ms","start":"2026-05-09T03:49:50.459095Z","end":"2026-05-09T03:49:50.655755Z","steps":["trace[498183069] 'process raft request' (duration: 196.386339ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:50.656489Z","caller":"traceutil/trace.go:171","msg":"trace[693923034] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"196.502292ms","start":"2026-05-09T03:49:50.45997Z","end":"2026-05-09T03:49:50.656473Z","steps":["trace[693923034] 'process raft request' (duration: 196.280176ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:50.656637Z","caller":"traceutil/trace.go:171","msg":"trace[75196047] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"197.119437ms","start":"2026-05-09T03:49:50.459491Z","end":"2026-05-09T03:49:50.65661Z","steps":["trace[75196047] 'process raft request' (duration: 196.207464ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:50.656637Z","caller":"traceutil/trace.go:171","msg":"trace[331960800] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"196.015419ms","start":"2026-05-09T03:49:50.460591Z","end":"2026-05-09T03:49:50.656606Z","steps":["trace[331960800] 'process raft request' (duration: 195.790613ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:50.656921Z","caller":"traceutil/trace.go:171","msg":"trace[1766747378] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"195.153157ms","start":"2026-05-09T03:49:50.461752Z","end":"2026-05-09T03:49:50.656906Z","steps":["trace[1766747378] 'process raft request' (duration: 194.707366ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:50.657196Z","caller":"traceutil/trace.go:171","msg":"trace[214563873] transaction","detail":"{read_only:false; response_revision:5111; number_of_response:1; }","duration":"145.032029ms","start":"2026-05-09T03:49:50.512141Z","end":"2026-05-09T03:49:50.657173Z","steps":["trace[214563873] 'process raft request' (duration: 144.942187ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:50.657213Z","caller":"traceutil/trace.go:171","msg":"trace[375205982] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"194.341626ms","start":"2026-05-09T03:49:50.462833Z","end":"2026-05-09T03:49:50.657175Z","steps":["trace[375205982] 'process raft request' (duration: 194.004218ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:50.657326Z","caller":"traceutil/trace.go:171","msg":"trace[1641692640] linearizableReadLoop","detail":"{readStateIndex:5310; appliedIndex:5300; }","duration":"119.528738ms","start":"2026-05-09T03:49:50.537783Z","end":"2026-05-09T03:49:50.657312Z","steps":["trace[1641692640] 'read index received' (duration: 117.532948ms)","trace[1641692640] 'applied index is now lower than readState.Index' (duration: 1.99474ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:49:50.657356Z","caller":"traceutil/trace.go:171","msg":"trace[19402466] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"146.117607ms","start":"2026-05-09T03:49:50.511232Z","end":"2026-05-09T03:49:50.65735Z","steps":["trace[19402466] 'process raft request' (duration: 145.771378ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:50.657676Z","caller":"traceutil/trace.go:171","msg":"trace[70558788] transaction","detail":"{read_only:false; response_revision:5112; number_of_response:1; }","duration":"144.030863ms","start":"2026-05-09T03:49:50.513629Z","end":"2026-05-09T03:49:50.657659Z","steps":["trace[70558788] 'process raft request' (duration: 143.51164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:50.657791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.802201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1321"} {"level":"info","ts":"2026-05-09T03:49:50.657917Z","caller":"traceutil/trace.go:171","msg":"trace[1997740474] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:5113; }","duration":"192.027488ms","start":"2026-05-09T03:49:50.465868Z","end":"2026-05-09T03:49:50.657896Z","steps":["trace[1997740474] 'agreement among raft nodes before linearized reading' (duration: 191.665968ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:50.657946Z","caller":"traceutil/trace.go:171","msg":"trace[1997605439] transaction","detail":"{read_only:false; response_revision:5113; number_of_response:1; }","duration":"142.475553ms","start":"2026-05-09T03:49:50.515411Z","end":"2026-05-09T03:49:50.657886Z","steps":["trace[1997605439] 'process raft request' (duration: 141.804626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:50.659564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.508629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-09T03:49:50.660997Z","caller":"traceutil/trace.go:171","msg":"trace[1007853309] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5113; }","duration":"102.937866ms","start":"2026-05-09T03:49:50.558034Z","end":"2026-05-09T03:49:50.660971Z","steps":["trace[1007853309] 'agreement among raft nodes before linearized reading' (duration: 101.410366ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:50.882776Z","caller":"traceutil/trace.go:171","msg":"trace[1523055884] linearizableReadLoop","detail":"{readStateIndex:5318; appliedIndex:5317; }","duration":"155.339082ms","start":"2026-05-09T03:49:50.7274Z","end":"2026-05-09T03:49:50.882739Z","steps":["trace[1523055884] 'read index received' (duration: 59.004714ms)","trace[1523055884] 'applied index is now lower than readState.Index' (duration: 96.332378ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:49:50.882802Z","caller":"traceutil/trace.go:171","msg":"trace[612497641] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"161.204151ms","start":"2026-05-09T03:49:50.72156Z","end":"2026-05-09T03:49:50.882764Z","steps":["trace[612497641] 'process raft request' (duration: 64.858733ms)","trace[612497641] 'compare' (duration: 96.057561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T03:49:50.883165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.624223ms","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":"warn","ts":"2026-05-09T03:49:50.883227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.796834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18adc921c31fb05c\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-05-09T03:49:50.883251Z","caller":"traceutil/trace.go:171","msg":"trace[1302181538] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5121; }","duration":"154.778277ms","start":"2026-05-09T03:49:50.728446Z","end":"2026-05-09T03:49:50.883224Z","steps":["trace[1302181538] 'agreement among raft nodes before linearized reading' (duration: 154.48854ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:50.88328Z","caller":"traceutil/trace.go:171","msg":"trace[703656841] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18adc921c31fb05c; range_end:; response_count:1; response_revision:5121; }","duration":"155.875216ms","start":"2026-05-09T03:49:50.727386Z","end":"2026-05-09T03:49:50.883261Z","steps":["trace[703656841] 'agreement among raft nodes before linearized reading' (duration: 155.534887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:49:51.611642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.030609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16403"} {"level":"info","ts":"2026-05-09T03:49:51.611725Z","caller":"traceutil/trace.go:171","msg":"trace[699263941] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5124; }","duration":"136.171472ms","start":"2026-05-09T03:49:51.475533Z","end":"2026-05-09T03:49:51.611704Z","steps":["trace[699263941] 'range keys from in-memory index tree' (duration: 135.814413ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:49:52.619809Z","caller":"traceutil/trace.go:171","msg":"trace[9278912] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"125.41753ms","start":"2026-05-09T03:49:52.494353Z","end":"2026-05-09T03:49:52.61977Z","steps":["trace[9278912] 'process raft request' (duration: 125.198925ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:50:31.042648Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2874} {"level":"warn","ts":"2026-05-09T03:50:31.493533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.336252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-09T03:50:31.493627Z","caller":"traceutil/trace.go:171","msg":"trace[2128824782] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5460; }","duration":"131.470135ms","start":"2026-05-09T03:50:31.362134Z","end":"2026-05-09T03:50:31.493604Z","steps":["trace[2128824782] 'range keys from in-memory index tree' (duration: 131.025496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T03:50:31.493722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.567374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T03:50:31.493845Z","caller":"traceutil/trace.go:171","msg":"trace[1562656370] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:5460; }","duration":"283.747657ms","start":"2026-05-09T03:50:31.210075Z","end":"2026-05-09T03:50:31.493823Z","steps":["trace[1562656370] 'count revisions from in-memory index tree' (duration: 283.477262ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:50:31.58212Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2874,"took":"535.106857ms","hash":616586700,"current-db-size-bytes":51933184,"current-db-size":"52 MB","current-db-size-in-use-bytes":27947008,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-09T03:50:31.582202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":616586700,"revision":2874,"compact-revision":-1} {"level":"info","ts":"2026-05-09T03:53:39.858314Z","caller":"traceutil/trace.go:171","msg":"trace[1459734253] transaction","detail":"{read_only:false; response_revision:6760; number_of_response:1; }","duration":"154.426046ms","start":"2026-05-09T03:53:39.703846Z","end":"2026-05-09T03:53:39.858272Z","steps":["trace[1459734253] 'process raft request' (duration: 154.058883ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T03:53:52.333982Z","caller":"traceutil/trace.go:171","msg":"trace[806418983] transaction","detail":"{read_only:false; response_revision:6882; number_of_response:1; }","duration":"120.927115ms","start":"2026-05-09T03:53:52.21301Z","end":"2026-05-09T03:53:52.333937Z","steps":["trace[806418983] 'process raft request' (duration: 97.368936ms)","trace[806418983] 'compare' (duration: 23.290957ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:54:10.124306Z","caller":"traceutil/trace.go:171","msg":"trace[685941286] transaction","detail":"{read_only:false; response_revision:6969; number_of_response:1; }","duration":"100.195901ms","start":"2026-05-09T03:54:10.024085Z","end":"2026-05-09T03:54:10.12428Z","steps":["trace[685941286] 'process raft request' (duration: 82.54662ms)","trace[685941286] 'compare' (duration: 17.54617ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T03:55:31.050281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5460} {"level":"info","ts":"2026-05-09T03:55:31.136729Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5460,"took":"84.420962ms","hash":3831359562,"current-db-size-bytes":51933184,"current-db-size":"52 MB","current-db-size-in-use-bytes":18526208,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-09T03:55:31.13683Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3831359562,"revision":5460,"compact-revision":2874}