{"level":"warn","ts":"2026-06-08T03:28:10.334371Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-08T03:28:10.334502Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-08T03:28:10.334602Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-08T03:28:10.334618Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-06-08T03:28:10.334644Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-08T03:28:10.335514Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-06-08T03:28:10.335619Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-08T03:28:10.340013Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.113535ms"} {"level":"info","ts":"2026-06-08T03:28:10.348599Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-06-08T03:28:10.348762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-06-08T03:28:10.348933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-06-08T03:28:10.34902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-08T03:28:10.349053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-06-08T03:28:10.349197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-06-08T03:28:10.35591Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-08T03:28:10.357803Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-08T03:28:10.360038Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-08T03:28:10.364017Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-08T03:28:10.364521Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-08T03:28:10.36469Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-08T03:28:10.364745Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-08T03:28:10.364318Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-08T03:28:10.366092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-06-08T03:28:10.366596Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-06-08T03:28:10.369509Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-08T03:28:10.369655Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-06-08T03:28:10.369679Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-06-08T03:28:10.369886Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-08T03:28:10.369961Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-08T03:28:11.149901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-06-08T03:28:11.149988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-06-08T03:28:11.150073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-06-08T03:28:11.150125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-06-08T03:28:11.150145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-06-08T03:28:11.150175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-06-08T03:28:11.150196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-06-08T03:28:11.151415Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-06-08T03:28:11.151589Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T03:28:11.15235Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T03:28:11.15263Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T03:28:11.153934Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-08T03:28:11.154007Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-08T03:28:11.156342Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-08T03:28:11.156471Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-06-08T03:28:11.160684Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T03:28:11.160946Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T03:28:11.161046Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-06-08T03:28:16.401157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.775593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-08T03:28:16.401343Z","caller":"traceutil/trace.go:171","msg":"trace[2031308041] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"104.417275ms","start":"2026-06-08T03:28:16.29691Z","end":"2026-06-08T03:28:16.401327Z","steps":["trace[2031308041] 'process raft request' (duration: 104.377015ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:28:16.401443Z","caller":"traceutil/trace.go:171","msg":"trace[475632889] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"192.2765ms","start":"2026-06-08T03:28:16.20912Z","end":"2026-06-08T03:28:16.401397Z","steps":["trace[475632889] 'process raft request' (duration: 79.758391ms)","trace[475632889] 'compare' (duration: 111.604572ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:28:16.563848Z","caller":"traceutil/trace.go:171","msg":"trace[1381473655] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"112.383139ms","start":"2026-06-08T03:28:16.451432Z","end":"2026-06-08T03:28:16.563815Z","steps":["trace[1381473655] 'process raft request' (duration: 65.607929ms)","trace[1381473655] 'compare' (duration: 46.643509ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:28:19.849454Z","caller":"traceutil/trace.go:171","msg":"trace[1073346516] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"141.302173ms","start":"2026-06-08T03:28:19.708113Z","end":"2026-06-08T03:28:19.849415Z","steps":["trace[1073346516] 'process raft request' (duration: 64.582489ms)","trace[1073346516] 'compare' (duration: 76.554322ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T03:28:20.062068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.235651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-08T03:28:20.062175Z","caller":"traceutil/trace.go:171","msg":"trace[1451041772] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:336; }","duration":"105.467023ms","start":"2026-06-08T03:28:19.956684Z","end":"2026-06-08T03:28:20.062151Z","steps":["trace[1451041772] 'range keys from in-memory index tree' (duration: 105.11197ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:28:20.204946Z","caller":"traceutil/trace.go:171","msg":"trace[80113883] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"138.430823ms","start":"2026-06-08T03:28:20.066464Z","end":"2026-06-08T03:28:20.204894Z","steps":["trace[80113883] 'process raft request' (duration: 132.934171ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:28:22.161247Z","caller":"traceutil/trace.go:171","msg":"trace[353200060] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"132.381634ms","start":"2026-06-08T03:28:22.028837Z","end":"2026-06-08T03:28:22.161219Z","steps":["trace[353200060] 'process raft request' (duration: 111.071092ms)","trace[353200060] 'compare' (duration: 21.05634ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:28:51.088881Z","caller":"traceutil/trace.go:171","msg":"trace[452355916] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"130.147472ms","start":"2026-06-08T03:28:50.958704Z","end":"2026-06-08T03:28:51.088852Z","steps":["trace[452355916] 'process raft request' (duration: 99.995498ms)","trace[452355916] 'compare' (duration: 30.005887ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:28:51.096368Z","caller":"traceutil/trace.go:171","msg":"trace[1848543153] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:580; }","duration":"119.57008ms","start":"2026-06-08T03:28:50.976779Z","end":"2026-06-08T03:28:51.096349Z","steps":["trace[1848543153] 'read index received' (duration: 81.936773ms)","trace[1848543153] 'applied index is now lower than readState.Index' (duration: 37.632387ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:28:51.096364Z","caller":"traceutil/trace.go:171","msg":"trace[1972647203] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"135.960019ms","start":"2026-06-08T03:28:50.960363Z","end":"2026-06-08T03:28:51.096323Z","steps":["trace[1972647203] 'process raft request' (duration: 135.767961ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:28:51.096449Z","caller":"traceutil/trace.go:171","msg":"trace[66262282] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"131.863005ms","start":"2026-06-08T03:28:50.964563Z","end":"2026-06-08T03:28:51.096426Z","steps":["trace[66262282] 'process raft request' (duration: 131.653495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:28:51.096564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.770389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-08T03:28:51.096593Z","caller":"traceutil/trace.go:171","msg":"trace[374960629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"119.845603ms","start":"2026-06-08T03:28:50.97674Z","end":"2026-06-08T03:28:51.096586Z","steps":["trace[374960629] 'agreement among raft nodes before linearized reading' (duration: 119.78193ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:28:57.340888Z","caller":"traceutil/trace.go:171","msg":"trace[1409920153] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"349.064191ms","start":"2026-06-08T03:28:56.991811Z","end":"2026-06-08T03:28:57.340875Z","steps":["trace[1409920153] 'process raft request' (duration: 348.426754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:28:57.341755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:28:56.991787Z","time spent":"349.156355ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T03:28:57.340808Z","caller":"traceutil/trace.go:171","msg":"trace[1969872364] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"320.857204ms","start":"2026-06-08T03:28:57.019926Z","end":"2026-06-08T03:28:57.340783Z","steps":["trace[1969872364] 'read index received' (duration: 320.093052ms)","trace[1969872364] 'applied index is now lower than readState.Index' (duration: 763.052µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T03:28:57.342673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.46876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-08T03:28:57.34275Z","caller":"traceutil/trace.go:171","msg":"trace[1824767990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"204.618607ms","start":"2026-06-08T03:28:57.13809Z","end":"2026-06-08T03:28:57.342709Z","steps":["trace[1824767990] 'agreement among raft nodes before linearized reading' (duration: 204.394798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:28:57.343237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.219719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mrp89\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-08T03:28:57.343396Z","caller":"traceutil/trace.go:171","msg":"trace[1374135758] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mrp89; range_end:; response_count:1; response_revision:639; }","duration":"323.432711ms","start":"2026-06-08T03:28:57.01986Z","end":"2026-06-08T03:28:57.343293Z","steps":["trace[1374135758] 'agreement among raft nodes before linearized reading' (duration: 321.094624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:28:57.343454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:28:57.019841Z","time spent":"323.595808ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-mrp89\" "} {"level":"info","ts":"2026-06-08T03:28:57.585056Z","caller":"traceutil/trace.go:171","msg":"trace[602952755] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"234.167495ms","start":"2026-06-08T03:28:57.350854Z","end":"2026-06-08T03:28:57.585022Z","steps":["trace[602952755] 'process raft request' (duration: 225.191512ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:28:57.597084Z","caller":"traceutil/trace.go:171","msg":"trace[466788896] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"192.965383ms","start":"2026-06-08T03:28:57.404094Z","end":"2026-06-08T03:28:57.59706Z","steps":["trace[466788896] 'process raft request' (duration: 192.916361ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:28:57.597151Z","caller":"traceutil/trace.go:171","msg":"trace[596053078] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"236.255631ms","start":"2026-06-08T03:28:57.36086Z","end":"2026-06-08T03:28:57.597115Z","steps":["trace[596053078] 'process raft request' (duration: 236.028921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:28:58.498031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.845475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-08T03:28:58.498239Z","caller":"traceutil/trace.go:171","msg":"trace[2113015241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"621.053073ms","start":"2026-06-08T03:28:57.877086Z","end":"2026-06-08T03:28:58.498139Z","steps":["trace[2113015241] 'range keys from in-memory index tree' (duration: 620.694228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:28:58.498799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:28:57.877065Z","time spent":"621.714571ms","remote":"127.0.0.1:43748","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-06-08T03:28:58.500064Z","caller":"traceutil/trace.go:171","msg":"trace[1187694384] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"483.671704ms","start":"2026-06-08T03:28:58.016366Z","end":"2026-06-08T03:28:58.500038Z","steps":["trace[1187694384] 'process raft request' (duration: 482.502886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:28:58.500178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:28:58.016346Z","time spent":"483.772799ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T03:28:58.867174Z","caller":"traceutil/trace.go:171","msg":"trace[517895274] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"283.284288ms","start":"2026-06-08T03:28:58.583861Z","end":"2026-06-08T03:28:58.867145Z","steps":["trace[517895274] 'process raft request' (duration: 280.927391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:28:58.86745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.436668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T03:28:58.867281Z","caller":"traceutil/trace.go:171","msg":"trace[1875135591] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"239.25874ms","start":"2026-06-08T03:28:58.627994Z","end":"2026-06-08T03:28:58.867253Z","steps":["trace[1875135591] 'read index received' (duration: 236.80475ms)","trace[1875135591] 'applied index is now lower than readState.Index' (duration: 2.45201ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:28:58.867533Z","caller":"traceutil/trace.go:171","msg":"trace[1165263990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"239.557853ms","start":"2026-06-08T03:28:58.627954Z","end":"2026-06-08T03:28:58.867512Z","steps":["trace[1165263990] 'agreement among raft nodes before linearized reading' (duration: 239.412147ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:29:00.322755Z","caller":"traceutil/trace.go:171","msg":"trace[1523470322] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"140.288857ms","start":"2026-06-08T03:29:00.182422Z","end":"2026-06-08T03:29:00.322711Z","steps":["trace[1523470322] 'process raft request' (duration: 140.048948ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:29:00.327609Z","caller":"traceutil/trace.go:171","msg":"trace[2419322] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"117.730433ms","start":"2026-06-08T03:29:00.209862Z","end":"2026-06-08T03:29:00.327593Z","steps":["trace[2419322] 'process raft request' (duration: 117.599108ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:29:12.791518Z","caller":"traceutil/trace.go:171","msg":"trace[640439584] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"203.699079ms","start":"2026-06-08T03:29:12.587797Z","end":"2026-06-08T03:29:12.791496Z","steps":["trace[640439584] 'read index received' (duration: 203.567994ms)","trace[640439584] 'applied index is now lower than readState.Index' (duration: 129.965µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:29:12.791577Z","caller":"traceutil/trace.go:171","msg":"trace[1143787846] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"204.113914ms","start":"2026-06-08T03:29:12.587418Z","end":"2026-06-08T03:29:12.791532Z","steps":["trace[1143787846] 'process raft request' (duration: 203.915247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:29:12.791735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.918407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-08T03:29:12.791785Z","caller":"traceutil/trace.go:171","msg":"trace[824933883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"204.001679ms","start":"2026-06-08T03:29:12.587771Z","end":"2026-06-08T03:29:12.791772Z","steps":["trace[824933883] 'agreement among raft nodes before linearized reading' (duration: 203.850034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:29:12.791899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.14699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-08T03:29:12.79196Z","caller":"traceutil/trace.go:171","msg":"trace[791153372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"203.227633ms","start":"2026-06-08T03:29:12.588718Z","end":"2026-06-08T03:29:12.791945Z","steps":["trace[791153372] 'agreement among raft nodes before linearized reading' (duration: 203.062907ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:29:12.791972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.094969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T03:29:12.792029Z","caller":"traceutil/trace.go:171","msg":"trace[1124262761] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:802; }","duration":"113.176502ms","start":"2026-06-08T03:29:12.678839Z","end":"2026-06-08T03:29:12.792015Z","steps":["trace[1124262761] 'agreement among raft nodes before linearized reading' (duration: 113.093579ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:29:12.792067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.706696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T03:29:12.792097Z","caller":"traceutil/trace.go:171","msg":"trace[1219651676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"161.763667ms","start":"2026-06-08T03:29:12.630316Z","end":"2026-06-08T03:29:12.79208Z","steps":["trace[1219651676] 'agreement among raft nodes before linearized reading' (duration: 161.733056ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:29:13.016469Z","caller":"traceutil/trace.go:171","msg":"trace[1607711380] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"220.598527ms","start":"2026-06-08T03:29:12.79584Z","end":"2026-06-08T03:29:13.016439Z","steps":["trace[1607711380] 'process raft request' (duration: 197.874293ms)","trace[1607711380] 'compare' (duration: 22.590279ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:29:13.022297Z","caller":"traceutil/trace.go:171","msg":"trace[241200991] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"226.093648ms","start":"2026-06-08T03:29:12.79618Z","end":"2026-06-08T03:29:13.022274Z","steps":["trace[241200991] 'process raft request' (duration: 225.945423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:29:41.356579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.40506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-08T03:29:41.356674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-08T03:29:41.356726Z","caller":"traceutil/trace.go:171","msg":"trace[82888227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1196; }","duration":"139.618216ms","start":"2026-06-08T03:29:41.217074Z","end":"2026-06-08T03:29:41.356692Z","steps":["trace[82888227] 'range keys from in-memory index tree' (duration: 139.153593ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:29:41.356782Z","caller":"traceutil/trace.go:171","msg":"trace[1790020336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1196; }","duration":"139.562994ms","start":"2026-06-08T03:29:41.217191Z","end":"2026-06-08T03:29:41.356754Z","steps":["trace[1790020336] 'range keys from in-memory index tree' (duration: 139.141683ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:30:02.08489Z","caller":"traceutil/trace.go:171","msg":"trace[1419522337] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"110.489331ms","start":"2026-06-08T03:30:01.97436Z","end":"2026-06-08T03:30:02.08485Z","steps":["trace[1419522337] 'process raft request' (duration: 110.288986ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:02.084933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.988821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/ingress-nginx/ingress-nginx-admission-patch\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T03:30:02.084983Z","caller":"traceutil/trace.go:171","msg":"trace[744177261] range","detail":"{range_begin:/registry/jobs/ingress-nginx/ingress-nginx-admission-patch; range_end:; response_count:0; response_revision:1432; }","duration":"134.085003ms","start":"2026-06-08T03:30:01.950887Z","end":"2026-06-08T03:30:02.084972Z","steps":["trace[744177261] 'agreement among raft nodes before linearized reading' (duration: 133.877869ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:30:02.08476Z","caller":"traceutil/trace.go:171","msg":"trace[1686298525] linearizableReadLoop","detail":"{readStateIndex:1476; appliedIndex:1475; }","duration":"133.807387ms","start":"2026-06-08T03:30:01.950926Z","end":"2026-06-08T03:30:02.084733Z","steps":["trace[1686298525] 'read index received' (duration: 70.286522ms)","trace[1686298525] 'applied index is now lower than readState.Index' (duration: 63.516635ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:30:02.084698Z","caller":"traceutil/trace.go:171","msg":"trace[440142051] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"133.886979ms","start":"2026-06-08T03:30:01.950781Z","end":"2026-06-08T03:30:02.084668Z","steps":["trace[440142051] 'process raft request' (duration: 70.413115ms)","trace[440142051] 'compare' (duration: 63.30393ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:30:02.253687Z","caller":"traceutil/trace.go:171","msg":"trace[1532443682] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"129.977723ms","start":"2026-06-08T03:30:02.123679Z","end":"2026-06-08T03:30:02.253656Z","steps":["trace[1532443682] 'process raft request' (duration: 115.394413ms)","trace[1532443682] 'compare' (duration: 14.407076ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:30:02.43071Z","caller":"traceutil/trace.go:171","msg":"trace[1817667018] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"160.036123ms","start":"2026-06-08T03:30:02.270643Z","end":"2026-06-08T03:30:02.430679Z","steps":["trace[1817667018] 'process raft request' (duration: 159.315638ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:30:09.0614Z","caller":"traceutil/trace.go:171","msg":"trace[283470667] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"112.530591ms","start":"2026-06-08T03:30:08.948843Z","end":"2026-06-08T03:30:09.061373Z","steps":["trace[283470667] 'process raft request' (duration: 68.31396ms)","trace[283470667] 'compare' (duration: 43.903464ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:30:24.517884Z","caller":"traceutil/trace.go:171","msg":"trace[777457694] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"134.925688ms","start":"2026-06-08T03:30:24.38292Z","end":"2026-06-08T03:30:24.517846Z","steps":["trace[777457694] 'process raft request' (duration: 132.294059ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:32.724672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.733203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T03:30:32.724764Z","caller":"traceutil/trace.go:171","msg":"trace[29074138] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1891; }","duration":"513.847425ms","start":"2026-06-08T03:30:32.210899Z","end":"2026-06-08T03:30:32.724746Z","steps":["trace[29074138] 'count revisions from in-memory index tree' (duration: 513.647182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:32.724806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:30:32.210839Z","time spent":"513.956028ms","remote":"127.0.0.1:43596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-06-08T03:30:32.724891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.786319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-08T03:30:32.724965Z","caller":"traceutil/trace.go:171","msg":"trace[289303889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1891; }","duration":"474.891002ms","start":"2026-06-08T03:30:32.250054Z","end":"2026-06-08T03:30:32.724945Z","steps":["trace[289303889] 'range keys from in-memory index tree' (duration: 474.636158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:32.725005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:30:32.250043Z","time spent":"474.951443ms","remote":"127.0.0.1:43748","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-06-08T03:30:32.851478Z","caller":"traceutil/trace.go:171","msg":"trace[1417627671] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1964; }","duration":"362.820185ms","start":"2026-06-08T03:30:32.488633Z","end":"2026-06-08T03:30:32.851453Z","steps":["trace[1417627671] 'read index received' (duration: 362.685323ms)","trace[1417627671] 'applied index is now lower than readState.Index' (duration: 133.502µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:30:32.851613Z","caller":"traceutil/trace.go:171","msg":"trace[1521361332] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"440.626172ms","start":"2026-06-08T03:30:32.410973Z","end":"2026-06-08T03:30:32.851599Z","steps":["trace[1521361332] 'process raft request' (duration: 440.318396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:32.851746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:30:32.410939Z","time spent":"440.702783ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T03:30:32.852049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.403206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8844dbd9-8bee-4928-9ae1-717e387ee23b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-08T03:30:32.852121Z","caller":"traceutil/trace.go:171","msg":"trace[1181990888] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8844dbd9-8bee-4928-9ae1-717e387ee23b; range_end:; response_count:1; response_revision:1892; }","duration":"363.483877ms","start":"2026-06-08T03:30:32.488621Z","end":"2026-06-08T03:30:32.852105Z","steps":["trace[1181990888] 'agreement among raft nodes before linearized reading' (duration: 363.195942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:32.85216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:30:32.488553Z","time spent":"363.598409ms","remote":"127.0.0.1:43652","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-8844dbd9-8bee-4928-9ae1-717e387ee23b\" "} {"level":"info","ts":"2026-06-08T03:30:32.871904Z","caller":"traceutil/trace.go:171","msg":"trace[1356162094] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"326.742492ms","start":"2026-06-08T03:30:32.545144Z","end":"2026-06-08T03:30:32.871887Z","steps":["trace[1356162094] 'process raft request' (duration: 326.60788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:32.872007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:30:32.545118Z","time spent":"326.840794ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T03:30:32.872143Z","caller":"traceutil/trace.go:171","msg":"trace[155709186] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"325.945628ms","start":"2026-06-08T03:30:32.546181Z","end":"2026-06-08T03:30:32.872127Z","steps":["trace[155709186] 'process raft request' (duration: 325.665553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:32.872207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:30:32.546172Z","time spent":"326.001498ms","remote":"127.0.0.1:43748","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-06-08T03:30:32.872183Z","caller":"traceutil/trace.go:171","msg":"trace[752973727] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"142.777494ms","start":"2026-06-08T03:30:32.729382Z","end":"2026-06-08T03:30:32.872135Z","steps":["trace[752973727] 'process raft request' (duration: 142.641502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:32.872328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.45737ms","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-06-08T03:30:32.8724Z","caller":"traceutil/trace.go:171","msg":"trace[135392053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1895; }","duration":"109.557912ms","start":"2026-06-08T03:30:32.762824Z","end":"2026-06-08T03:30:32.872382Z","steps":["trace[135392053] 'agreement among raft nodes before linearized reading' (duration: 109.42994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:32.872544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.249603ms","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-06-08T03:30:32.872622Z","caller":"traceutil/trace.go:171","msg":"trace[1751917964] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1895; }","duration":"271.66311ms","start":"2026-06-08T03:30:32.600945Z","end":"2026-06-08T03:30:32.872608Z","steps":["trace[1751917964] 'agreement among raft nodes before linearized reading' (duration: 271.190892ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:33.240479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.454213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T03:30:33.241176Z","caller":"traceutil/trace.go:171","msg":"trace[1369942180] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1968; }","duration":"246.529348ms","start":"2026-06-08T03:30:32.994519Z","end":"2026-06-08T03:30:33.241048Z","steps":["trace[1369942180] 'read index received' (duration: 72.448235ms)","trace[1369942180] 'applied index is now lower than readState.Index' (duration: 174.079343ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:30:33.241308Z","caller":"traceutil/trace.go:171","msg":"trace[1721961309] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"379.943065ms","start":"2026-06-08T03:30:32.86135Z","end":"2026-06-08T03:30:33.241293Z","steps":["trace[1721961309] 'process raft request' (duration: 205.596396ms)","trace[1721961309] 'compare' (duration: 173.340431ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T03:30:33.241443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:30:32.861327Z","time spent":"380.046747ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T03:30:33.241504Z","caller":"traceutil/trace.go:171","msg":"trace[47766405] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"364.139154ms","start":"2026-06-08T03:30:32.877331Z","end":"2026-06-08T03:30:33.24147Z","steps":["trace[47766405] 'process raft request' (duration: 363.560543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:33.241602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:30:32.877301Z","time spent":"364.245485ms","remote":"127.0.0.1:43748","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-06-08T03:30:33.241604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.055487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8844dbd9-8bee-4928-9ae1-717e387ee23b\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-06-08T03:30:33.24175Z","caller":"traceutil/trace.go:171","msg":"trace[1835182554] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8844dbd9-8bee-4928-9ae1-717e387ee23b; range_end:; response_count:1; response_revision:1897; }","duration":"247.24743ms","start":"2026-06-08T03:30:32.99448Z","end":"2026-06-08T03:30:33.241727Z","steps":["trace[1835182554] 'agreement among raft nodes before linearized reading' (duration: 247.013556ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:30:33.514361Z","caller":"traceutil/trace.go:171","msg":"trace[496391310] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1970; }","duration":"273.122097ms","start":"2026-06-08T03:30:33.241215Z","end":"2026-06-08T03:30:33.514337Z","steps":["trace[496391310] 'read index received' (duration: 199.165528ms)","trace[496391310] 'applied index is now lower than readState.Index' (duration: 73.954969ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:30:33.514701Z","caller":"traceutil/trace.go:171","msg":"trace[1273939631] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"308.233749ms","start":"2026-06-08T03:30:33.206422Z","end":"2026-06-08T03:30:33.514656Z","steps":["trace[1273939631] 'process raft request' (duration: 234.009395ms)","trace[1273939631] 'compare' (duration: 73.620873ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T03:30:33.514882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:30:33.206391Z","time spent":"308.397692ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T03:30:33.514985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.865012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-08T03:30:33.515218Z","caller":"traceutil/trace.go:171","msg":"trace[880470405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1898; }","duration":"291.031874ms","start":"2026-06-08T03:30:33.224094Z","end":"2026-06-08T03:30:33.515126Z","steps":["trace[880470405] 'agreement among raft nodes before linearized reading' (duration: 290.331692ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:30:44.830284Z","caller":"traceutil/trace.go:171","msg":"trace[88894620] linearizableReadLoop","detail":"{readStateIndex:2071; appliedIndex:2070; }","duration":"201.238659ms","start":"2026-06-08T03:30:44.629022Z","end":"2026-06-08T03:30:44.830261Z","steps":["trace[88894620] 'read index received' (duration: 200.953094ms)","trace[88894620] 'applied index is now lower than readState.Index' (duration: 284.315µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:30:44.830351Z","caller":"traceutil/trace.go:171","msg":"trace[783776132] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"208.480709ms","start":"2026-06-08T03:30:44.621837Z","end":"2026-06-08T03:30:44.830318Z","steps":["trace[783776132] 'process raft request' (duration: 208.243075ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:44.830441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.395861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T03:30:44.830538Z","caller":"traceutil/trace.go:171","msg":"trace[1276915925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1995; }","duration":"201.535473ms","start":"2026-06-08T03:30:44.628987Z","end":"2026-06-08T03:30:44.830523Z","steps":["trace[1276915925] 'agreement among raft nodes before linearized reading' (duration: 201.398611ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:44.83066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.033543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-06-08T03:30:44.830708Z","caller":"traceutil/trace.go:171","msg":"trace[1569843169] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1995; }","duration":"166.130864ms","start":"2026-06-08T03:30:44.664564Z","end":"2026-06-08T03:30:44.830694Z","steps":["trace[1569843169] 'agreement among raft nodes before linearized reading' (duration: 165.962711ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:30:45.093811Z","caller":"traceutil/trace.go:171","msg":"trace[984654798] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"255.698737ms","start":"2026-06-08T03:30:44.838079Z","end":"2026-06-08T03:30:45.093778Z","steps":["trace[984654798] 'process raft request' (duration: 246.649927ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:30:45.113319Z","caller":"traceutil/trace.go:171","msg":"trace[1886230437] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"274.452697ms","start":"2026-06-08T03:30:44.838843Z","end":"2026-06-08T03:30:45.113295Z","steps":["trace[1886230437] 'process raft request' (duration: 274.274274ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:30:45.113365Z","caller":"traceutil/trace.go:171","msg":"trace[1339433477] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"134.276634ms","start":"2026-06-08T03:30:44.979066Z","end":"2026-06-08T03:30:45.113343Z","steps":["trace[1339433477] 'process raft request' (duration: 134.195683ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:30:45.113585Z","caller":"traceutil/trace.go:171","msg":"trace[438817610] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"209.925653ms","start":"2026-06-08T03:30:44.903647Z","end":"2026-06-08T03:30:45.113573Z","steps":["trace[438817610] 'process raft request' (duration: 209.577147ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:30:45.113664Z","caller":"traceutil/trace.go:171","msg":"trace[2052829285] linearizableReadLoop","detail":"{readStateIndex:2076; appliedIndex:2071; }","duration":"119.868514ms","start":"2026-06-08T03:30:44.993784Z","end":"2026-06-08T03:30:45.113653Z","steps":["trace[2052829285] 'read index received' (duration: 91.038937ms)","trace[2052829285] 'applied index is now lower than readState.Index' (duration: 28.827797ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:30:45.113843Z","caller":"traceutil/trace.go:171","msg":"trace[2070456553] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"132.587426ms","start":"2026-06-08T03:30:44.981244Z","end":"2026-06-08T03:30:45.113832Z","steps":["trace[2070456553] 'process raft request' (duration: 132.066688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:45.113858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.632362ms","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-06-08T03:30:45.113862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.073478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-08T03:30:45.11391Z","caller":"traceutil/trace.go:171","msg":"trace[517479475] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2000; }","duration":"107.716893ms","start":"2026-06-08T03:30:45.00618Z","end":"2026-06-08T03:30:45.113897Z","steps":["trace[517479475] 'agreement among raft nodes before linearized reading' (duration: 107.54991ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:30:45.11393Z","caller":"traceutil/trace.go:171","msg":"trace[337816332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2000; }","duration":"120.141479ms","start":"2026-06-08T03:30:44.993768Z","end":"2026-06-08T03:30:45.11391Z","steps":["trace[337816332] 'agreement among raft nodes before linearized reading' (duration: 119.968686ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:30:47.960797Z","caller":"traceutil/trace.go:171","msg":"trace[251535494] linearizableReadLoop","detail":"{readStateIndex:2101; appliedIndex:2100; }","duration":"166.226592ms","start":"2026-06-08T03:30:47.794547Z","end":"2026-06-08T03:30:47.960774Z","steps":["trace[251535494] 'read index received' (duration: 166.025059ms)","trace[251535494] 'applied index is now lower than readState.Index' (duration: 200.663µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:30:47.960862Z","caller":"traceutil/trace.go:171","msg":"trace[1341298273] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"169.767671ms","start":"2026-06-08T03:30:47.791063Z","end":"2026-06-08T03:30:47.960831Z","steps":["trace[1341298273] 'process raft request' (duration: 169.567668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:30:47.960984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.417646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-08T03:30:47.961018Z","caller":"traceutil/trace.go:171","msg":"trace[322355043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2025; }","duration":"166.495087ms","start":"2026-06-08T03:30:47.794512Z","end":"2026-06-08T03:30:47.961007Z","steps":["trace[322355043] 'agreement among raft nodes before linearized reading' (duration: 166.352994ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:30:48.218426Z","caller":"traceutil/trace.go:171","msg":"trace[1868053891] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"253.789028ms","start":"2026-06-08T03:30:47.964604Z","end":"2026-06-08T03:30:48.218393Z","steps":["trace[1868053891] 'process raft request' (duration: 209.807842ms)","trace[1868053891] 'compare' (duration: 43.815813ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T03:31:07.735715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410932943322647,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-08T03:31:07.799355Z","caller":"traceutil/trace.go:171","msg":"trace[917293610] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"614.905233ms","start":"2026-06-08T03:31:07.18443Z","end":"2026-06-08T03:31:07.799336Z","steps":["trace[917293610] 'process raft request' (duration: 614.787781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:31:07.799467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:31:07.184395Z","time spent":"615.003884ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T03:31:07.983006Z","caller":"traceutil/trace.go:171","msg":"trace[90022559] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"796.815037ms","start":"2026-06-08T03:31:07.186165Z","end":"2026-06-08T03:31:07.98298Z","steps":["trace[90022559] 'process raft request' (duration: 701.026258ms)","trace[90022559] 'compare' (duration: 95.374283ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T03:31:07.983333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:31:07.18615Z","time spent":"796.941238ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T03:31:07.9833Z","caller":"traceutil/trace.go:171","msg":"trace[884196726] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"794.234318ms","start":"2026-06-08T03:31:07.189037Z","end":"2026-06-08T03:31:07.983271Z","steps":["trace[884196726] 'process raft request' (duration: 793.889453ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:31:07.983431Z","caller":"traceutil/trace.go:171","msg":"trace[1344493726] linearizableReadLoop","detail":"{readStateIndex:2215; appliedIndex:2211; }","duration":"747.787125ms","start":"2026-06-08T03:31:07.235617Z","end":"2026-06-08T03:31:07.983404Z","steps":["trace[1344493726] 'read index received' (duration: 563.600937ms)","trace[1344493726] 'applied index is now lower than readState.Index' (duration: 184.185148ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:31:07.9836Z","caller":"traceutil/trace.go:171","msg":"trace[2136069998] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"336.004233ms","start":"2026-06-08T03:31:07.647584Z","end":"2026-06-08T03:31:07.983588Z","steps":["trace[2136069998] 'process raft request' (duration: 335.576436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:31:07.983706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:31:07.647561Z","time spent":"336.070353ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T03:31:07.983897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.423359ms","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-06-08T03:31:07.983957Z","caller":"traceutil/trace.go:171","msg":"trace[138150058] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2135; }","duration":"633.53145ms","start":"2026-06-08T03:31:07.350412Z","end":"2026-06-08T03:31:07.983943Z","steps":["trace[138150058] 'agreement among raft nodes before linearized reading' (duration: 633.352527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:31:07.98399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:31:07.350387Z","time spent":"633.595962ms","remote":"127.0.0.1:59828","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-06-08T03:31:07.984004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.692395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-08T03:31:07.984043Z","caller":"traceutil/trace.go:171","msg":"trace[1561863314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2135; }","duration":"255.763725ms","start":"2026-06-08T03:31:07.728267Z","end":"2026-06-08T03:31:07.984031Z","steps":["trace[1561863314] 'agreement among raft nodes before linearized reading' (duration: 255.642344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:31:07.984055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.083328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T03:31:07.984078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.468725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-08T03:31:07.984098Z","caller":"traceutil/trace.go:171","msg":"trace[1079935127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2135; }","duration":"355.133718ms","start":"2026-06-08T03:31:07.628951Z","end":"2026-06-08T03:31:07.984085Z","steps":["trace[1079935127] 'agreement among raft nodes before linearized reading' (duration: 355.051267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:31:07.984135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:31:07.628889Z","time spent":"355.24022ms","remote":"127.0.0.1:43492","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-06-08T03:31:07.984139Z","caller":"traceutil/trace.go:171","msg":"trace[723796680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2135; }","duration":"748.582187ms","start":"2026-06-08T03:31:07.23554Z","end":"2026-06-08T03:31:07.984122Z","steps":["trace[723796680] 'agreement among raft nodes before linearized reading' (duration: 748.443785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:31:07.984178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:31:07.235509Z","time spent":"748.661459ms","remote":"127.0.0.1:43748","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":"warn","ts":"2026-06-08T03:31:07.984342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.151815ms","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-06-08T03:31:07.984442Z","caller":"traceutil/trace.go:171","msg":"trace[1298025476] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2135; }","duration":"576.290306ms","start":"2026-06-08T03:31:07.408136Z","end":"2026-06-08T03:31:07.984426Z","steps":["trace[1298025476] 'agreement among raft nodes before linearized reading' (duration: 575.86962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:31:07.984521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:31:07.408113Z","time spent":"576.396079ms","remote":"127.0.0.1:43748","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-06-08T03:31:07.984497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:31:07.189005Z","time spent":"794.41141ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T03:31:08.260308Z","caller":"traceutil/trace.go:171","msg":"trace[1739672474] linearizableReadLoop","detail":"{readStateIndex:2218; appliedIndex:2216; }","duration":"213.795547ms","start":"2026-06-08T03:31:08.046492Z","end":"2026-06-08T03:31:08.260288Z","steps":["trace[1739672474] 'read index received' (duration: 155.388049ms)","trace[1739672474] 'applied index is now lower than readState.Index' (duration: 58.406378ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:31:08.260339Z","caller":"traceutil/trace.go:171","msg":"trace[725964833] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"269.715488ms","start":"2026-06-08T03:31:07.990586Z","end":"2026-06-08T03:31:08.260301Z","steps":["trace[725964833] 'process raft request' (duration: 211.388511ms)","trace[725964833] 'compare' (duration: 58.141674ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:31:08.260501Z","caller":"traceutil/trace.go:171","msg":"trace[989340419] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"269.812199ms","start":"2026-06-08T03:31:07.990669Z","end":"2026-06-08T03:31:08.260481Z","steps":["trace[989340419] 'process raft request' (duration: 269.571856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:31:08.260599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.07025ms","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-06-08T03:31:08.260681Z","caller":"traceutil/trace.go:171","msg":"trace[1919017590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2138; }","duration":"214.230983ms","start":"2026-06-08T03:31:08.046438Z","end":"2026-06-08T03:31:08.260669Z","steps":["trace[1919017590] 'agreement among raft nodes before linearized reading' (duration: 213.940529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:32:45.197751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.179285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b6fd99e14f383a\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-06-08T03:32:45.197841Z","caller":"traceutil/trace.go:171","msg":"trace[1110076139] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b6fd99e14f383a; range_end:; response_count:1; response_revision:2768; }","duration":"172.315377ms","start":"2026-06-08T03:32:45.025504Z","end":"2026-06-08T03:32:45.197819Z","steps":["trace[1110076139] 'range keys from in-memory index tree' (duration: 171.892172ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:32:45.395939Z","caller":"traceutil/trace.go:171","msg":"trace[1750655565] linearizableReadLoop","detail":"{readStateIndex:2872; appliedIndex:2871; }","duration":"180.594254ms","start":"2026-06-08T03:32:45.215322Z","end":"2026-06-08T03:32:45.395916Z","steps":["trace[1750655565] 'read index received' (duration: 180.400692ms)","trace[1750655565] 'applied index is now lower than readState.Index' (duration: 192.752µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:32:45.395998Z","caller":"traceutil/trace.go:171","msg":"trace[1255160517] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"195.246978ms","start":"2026-06-08T03:32:45.200719Z","end":"2026-06-08T03:32:45.395966Z","steps":["trace[1255160517] 'process raft request' (duration: 195.052795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:32:45.396093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.744366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T03:32:45.396139Z","caller":"traceutil/trace.go:171","msg":"trace[1011079359] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2769; }","duration":"180.835728ms","start":"2026-06-08T03:32:45.215291Z","end":"2026-06-08T03:32:45.396127Z","steps":["trace[1011079359] 'agreement among raft nodes before linearized reading' (duration: 180.726716ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:33:40.547307Z","caller":"traceutil/trace.go:171","msg":"trace[529108710] transaction","detail":"{read_only:false; response_revision:3056; number_of_response:1; }","duration":"218.814319ms","start":"2026-06-08T03:33:40.32846Z","end":"2026-06-08T03:33:40.547275Z","steps":["trace[529108710] 'process raft request' (duration: 218.664264ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:33:41.039128Z","caller":"traceutil/trace.go:171","msg":"trace[2076594346] linearizableReadLoop","detail":"{readStateIndex:3174; appliedIndex:3171; }","duration":"408.222227ms","start":"2026-06-08T03:33:40.630881Z","end":"2026-06-08T03:33:41.039103Z","steps":["trace[2076594346] 'read index received' (duration: 364.091892ms)","trace[2076594346] 'applied index is now lower than readState.Index' (duration: 44.129295ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:33:41.039125Z","caller":"traceutil/trace.go:171","msg":"trace[672787904] transaction","detail":"{read_only:false; response_revision:3057; number_of_response:1; }","duration":"529.220502ms","start":"2026-06-08T03:33:40.509868Z","end":"2026-06-08T03:33:41.039089Z","steps":["trace[672787904] 'process raft request' (duration: 485.083037ms)","trace[672787904] 'compare' (duration: 43.819695ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T03:33:41.039817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:33:40.509843Z","time spent":"529.885964ms","remote":"127.0.0.1:43748","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-06-08T03:33:41.039206Z","caller":"traceutil/trace.go:171","msg":"trace[1787285563] transaction","detail":"{read_only:false; response_revision:3058; number_of_response:1; }","duration":"499.954681ms","start":"2026-06-08T03:33:40.539193Z","end":"2026-06-08T03:33:41.039148Z","steps":["trace[1787285563] 'process raft request' (duration: 499.770115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:33:41.040037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:33:40.539158Z","time spent":"500.811429ms","remote":"127.0.0.1:43748","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-06-08T03:33:41.04Z","caller":"traceutil/trace.go:171","msg":"trace[19312613] transaction","detail":"{read_only:false; response_revision:3060; number_of_response:1; }","duration":"351.903647ms","start":"2026-06-08T03:33:40.688077Z","end":"2026-06-08T03:33:41.03998Z","steps":["trace[19312613] 'process raft request' (duration: 351.632388ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:33:41.039222Z","caller":"traceutil/trace.go:171","msg":"trace[166689304] transaction","detail":"{read_only:false; response_revision:3059; number_of_response:1; }","duration":"495.579557ms","start":"2026-06-08T03:33:40.543614Z","end":"2026-06-08T03:33:41.039193Z","steps":["trace[166689304] 'process raft request' (duration: 495.419732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:33:41.040235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:33:40.688049Z","time spent":"352.105423ms","remote":"127.0.0.1:43748","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-06-08T03:33:41.040247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:33:40.543596Z","time spent":"496.619241ms","remote":"127.0.0.1:43748","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-06-08T03:33:41.039336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.445485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T03:33:41.040337Z","caller":"traceutil/trace.go:171","msg":"trace[1756373276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3059; }","duration":"409.495159ms","start":"2026-06-08T03:33:40.630829Z","end":"2026-06-08T03:33:41.040324Z","steps":["trace[1756373276] 'agreement among raft nodes before linearized reading' (duration: 408.445944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:33:41.040376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:33:40.630813Z","time spent":"409.55536ms","remote":"127.0.0.1:43492","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-06-08T03:33:41.040058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.799936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-08T03:33:41.04076Z","caller":"traceutil/trace.go:171","msg":"trace[733650166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3060; }","duration":"339.53073ms","start":"2026-06-08T03:33:40.701211Z","end":"2026-06-08T03:33:41.040741Z","steps":["trace[733650166] 'agreement among raft nodes before linearized reading' (duration: 338.683453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:33:41.041698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:33:40.701202Z","time spent":"340.476791ms","remote":"127.0.0.1:43748","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-06-08T03:34:23.994055Z","caller":"traceutil/trace.go:171","msg":"trace[2103454967] transaction","detail":"{read_only:false; response_revision:3283; number_of_response:1; }","duration":"131.531651ms","start":"2026-06-08T03:34:23.862494Z","end":"2026-06-08T03:34:23.994026Z","steps":["trace[2103454967] 'process raft request' (duration: 131.360826ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:34:25.725933Z","caller":"traceutil/trace.go:171","msg":"trace[1089207357] linearizableReadLoop","detail":"{readStateIndex:3412; appliedIndex:3409; }","duration":"524.937241ms","start":"2026-06-08T03:34:25.200976Z","end":"2026-06-08T03:34:25.725913Z","steps":["trace[1089207357] 'read index received' (duration: 30.256287ms)","trace[1089207357] 'applied index is now lower than readState.Index' (duration: 494.680074ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:34:25.726067Z","caller":"traceutil/trace.go:171","msg":"trace[2075444840] transaction","detail":"{read_only:false; response_revision:3287; number_of_response:1; }","duration":"528.385645ms","start":"2026-06-08T03:34:25.197671Z","end":"2026-06-08T03:34:25.726057Z","steps":["trace[2075444840] 'process raft request' (duration: 527.140781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:34:25.726422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.426674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-08T03:34:25.726504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:34:25.197648Z","time spent":"528.449776ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T03:34:25.726508Z","caller":"traceutil/trace.go:171","msg":"trace[1758857473] transaction","detail":"{read_only:false; response_revision:3288; number_of_response:1; }","duration":"527.266084ms","start":"2026-06-08T03:34:25.199212Z","end":"2026-06-08T03:34:25.726478Z","steps":["trace[1758857473] 'process raft request' (duration: 526.639257ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:34:25.72649Z","caller":"traceutil/trace.go:171","msg":"trace[2059160538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3288; }","duration":"525.534557ms","start":"2026-06-08T03:34:25.200941Z","end":"2026-06-08T03:34:25.726475Z","steps":["trace[2059160538] 'agreement among raft nodes before linearized reading' (duration: 525.344982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:34:25.726674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:34:25.20093Z","time spent":"525.730152ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-08T03:34:25.726782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.860655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-08T03:34:25.726867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:34:25.199199Z","time spent":"527.428808ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T03:34:25.727124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.974461ms","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-06-08T03:34:25.727375Z","caller":"traceutil/trace.go:171","msg":"trace[1269275883] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3288; }","duration":"484.261859ms","start":"2026-06-08T03:34:25.243096Z","end":"2026-06-08T03:34:25.727358Z","steps":["trace[1269275883] 'agreement among raft nodes before linearized reading' (duration: 483.90759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:34:25.727435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:34:25.243078Z","time spent":"484.341511ms","remote":"127.0.0.1:43748","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-06-08T03:34:25.727156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.122711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-08T03:34:25.727639Z","caller":"traceutil/trace.go:171","msg":"trace[1164388797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3288; }","duration":"504.753219ms","start":"2026-06-08T03:34:25.222864Z","end":"2026-06-08T03:34:25.727618Z","steps":["trace[1164388797] 'agreement among raft nodes before linearized reading' (duration: 503.67641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:34:25.727705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:34:25.222851Z","time spent":"504.836701ms","remote":"127.0.0.1:43748","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-06-08T03:34:25.727531Z","caller":"traceutil/trace.go:171","msg":"trace[384939233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3288; }","duration":"452.490691ms","start":"2026-06-08T03:34:25.27502Z","end":"2026-06-08T03:34:25.727511Z","steps":["trace[384939233] 'agreement among raft nodes before linearized reading' (duration: 452.049669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:34:25.727831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:34:25.274962Z","time spent":"452.854071ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-08T03:34:25.727227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.926371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-08T03:34:25.727977Z","caller":"traceutil/trace.go:171","msg":"trace[1448514689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3288; }","duration":"491.670212ms","start":"2026-06-08T03:34:25.236288Z","end":"2026-06-08T03:34:25.727958Z","steps":["trace[1448514689] 'agreement among raft nodes before linearized reading' (duration: 490.721016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:34:25.728098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:34:25.236254Z","time spent":"491.823216ms","remote":"127.0.0.1:43748","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-06-08T03:36:23.19341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.909382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:46299"} {"level":"info","ts":"2026-06-08T03:36:23.193484Z","caller":"traceutil/trace.go:171","msg":"trace[678218397] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4483; }","duration":"113.003814ms","start":"2026-06-08T03:36:23.080464Z","end":"2026-06-08T03:36:23.193468Z","steps":["trace[678218397] 'agreement among raft nodes before linearized reading' (duration: 112.734829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:36:23.1934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.155197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:46299"} {"level":"info","ts":"2026-06-08T03:36:23.193583Z","caller":"traceutil/trace.go:171","msg":"trace[1265946794] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4483; }","duration":"114.388351ms","start":"2026-06-08T03:36:23.079176Z","end":"2026-06-08T03:36:23.193564Z","steps":["trace[1265946794] 'agreement among raft nodes before linearized reading' (duration: 114.000513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:36:23.193412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.28808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-06-08T03:36:23.193728Z","caller":"traceutil/trace.go:171","msg":"trace[1351508819] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4483; }","duration":"113.659747ms","start":"2026-06-08T03:36:23.08006Z","end":"2026-06-08T03:36:23.19372Z","steps":["trace[1351508819] 'agreement among raft nodes before linearized reading' (duration: 113.084116ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:36:35.369408Z","caller":"traceutil/trace.go:171","msg":"trace[869872775] linearizableReadLoop","detail":"{readStateIndex:4917; appliedIndex:4916; }","duration":"181.842826ms","start":"2026-06-08T03:36:35.187535Z","end":"2026-06-08T03:36:35.369378Z","steps":["trace[869872775] 'read index received' (duration: 171.437269ms)","trace[869872775] 'applied index is now lower than readState.Index' (duration: 10.403977ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:36:35.369544Z","caller":"traceutil/trace.go:171","msg":"trace[2129023063] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"186.351822ms","start":"2026-06-08T03:36:35.183173Z","end":"2026-06-08T03:36:35.369525Z","steps":["trace[2129023063] 'process raft request' (duration: 175.796812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:36:35.370496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.934887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-337abbea-c1d3-4383-a871-a7659d70f4a1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-08T03:36:35.37057Z","caller":"traceutil/trace.go:171","msg":"trace[275119305] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-337abbea-c1d3-4383-a871-a7659d70f4a1; range_end:; response_count:1; response_revision:4738; }","duration":"183.022289ms","start":"2026-06-08T03:36:35.187529Z","end":"2026-06-08T03:36:35.370551Z","steps":["trace[275119305] 'agreement among raft nodes before linearized reading' (duration: 182.04672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:36:35.440755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.292546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-337abbea-c1d3-4383-a871-a7659d70f4a1\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-06-08T03:36:35.440756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.126185ms","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-06-08T03:36:35.440849Z","caller":"traceutil/trace.go:171","msg":"trace[1406983595] 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:4739; }","duration":"237.259487ms","start":"2026-06-08T03:36:35.203572Z","end":"2026-06-08T03:36:35.440832Z","steps":["trace[1406983595] 'agreement among raft nodes before linearized reading' (duration: 237.033623ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:36:35.440831Z","caller":"traceutil/trace.go:171","msg":"trace[241140857] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-337abbea-c1d3-4383-a871-a7659d70f4a1; range_end:; response_count:1; response_revision:4739; }","duration":"248.396899ms","start":"2026-06-08T03:36:35.192418Z","end":"2026-06-08T03:36:35.440815Z","steps":["trace[241140857] 'agreement among raft nodes before linearized reading' (duration: 248.179125ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:36:35.532146Z","caller":"traceutil/trace.go:171","msg":"trace[346719855] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"153.336686ms","start":"2026-06-08T03:36:35.378775Z","end":"2026-06-08T03:36:35.532111Z","steps":["trace[346719855] 'process raft request' (duration: 119.012756ms)","trace[346719855] 'compare' (duration: 34.143557ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T03:36:35.53259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-08T03:36:35.532652Z","caller":"traceutil/trace.go:171","msg":"trace[839194289] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4741; }","duration":"108.239581ms","start":"2026-06-08T03:36:35.424397Z","end":"2026-06-08T03:36:35.532637Z","steps":["trace[839194289] 'agreement among raft nodes before linearized reading' (duration: 108.078498ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:36:35.53265Z","caller":"traceutil/trace.go:171","msg":"trace[1966365273] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"150.250608ms","start":"2026-06-08T03:36:35.382387Z","end":"2026-06-08T03:36:35.532637Z","steps":["trace[1966365273] 'process raft request' (duration: 149.659057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:36:35.532598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.90896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T03:36:35.532794Z","caller":"traceutil/trace.go:171","msg":"trace[871847925] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:4741; }","duration":"113.125074ms","start":"2026-06-08T03:36:35.419643Z","end":"2026-06-08T03:36:35.532769Z","steps":["trace[871847925] 'agreement among raft nodes before linearized reading' (duration: 112.91737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:36:35.532479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.383833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6976ddb7b7-t4hb9.18b6fdd50e47be87\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-06-08T03:36:35.53545Z","caller":"traceutil/trace.go:171","msg":"trace[1919572035] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-6976ddb7b7-t4hb9.18b6fdd50e47be87; range_end:; response_count:1; response_revision:4741; }","duration":"161.159425ms","start":"2026-06-08T03:36:35.374028Z","end":"2026-06-08T03:36:35.535187Z","steps":["trace[1919572035] 'agreement among raft nodes before linearized reading' (duration: 158.226409ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:36:35.964192Z","caller":"traceutil/trace.go:171","msg":"trace[350533239] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"169.054725ms","start":"2026-06-08T03:36:35.795114Z","end":"2026-06-08T03:36:35.964169Z","steps":["trace[350533239] 'process raft request' (duration: 151.674315ms)","trace[350533239] 'compare' (duration: 17.071464ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T03:36:49.852168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.731415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-08T03:36:49.85265Z","caller":"traceutil/trace.go:171","msg":"trace[791062148] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5110; }","duration":"280.898157ms","start":"2026-06-08T03:36:49.57171Z","end":"2026-06-08T03:36:49.852609Z","steps":["trace[791062148] 'read index received' (duration: 22.701µs)","trace[791062148] 'applied index is now lower than readState.Index' (duration: 280.870036ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:36:49.852671Z","caller":"traceutil/trace.go:171","msg":"trace[1388339688] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"254.53749ms","start":"2026-06-08T03:36:49.598103Z","end":"2026-06-08T03:36:49.852641Z","steps":["trace[1388339688] 'process raft request' (duration: 254.177124ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:36:49.852672Z","caller":"traceutil/trace.go:171","msg":"trace[741210657] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"474.750126ms","start":"2026-06-08T03:36:49.377883Z","end":"2026-06-08T03:36:49.852633Z","steps":["trace[741210657] 'process raft request' (duration: 155.465071ms)","trace[741210657] 'compare' (duration: 318.571752ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T03:36:49.852848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:36:49.377865Z","time spent":"474.886548ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T03:36:49.931048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.517461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T03:36:49.931201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.428247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T03:36:49.931285Z","caller":"traceutil/trace.go:171","msg":"trace[1587212493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4928; }","duration":"359.582419ms","start":"2026-06-08T03:36:49.571679Z","end":"2026-06-08T03:36:49.931261Z","steps":["trace[1587212493] 'agreement among raft nodes before linearized reading' (duration: 281.073979ms)","trace[1587212493] 'range keys from in-memory index tree' (duration: 78.346086ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T03:36:49.931339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T03:36:49.571664Z","time spent":"359.666261ms","remote":"127.0.0.1:43492","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-06-08T03:36:49.931207Z","caller":"traceutil/trace.go:171","msg":"trace[1347839365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4928; }","duration":"299.699333ms","start":"2026-06-08T03:36:49.631472Z","end":"2026-06-08T03:36:49.931172Z","steps":["trace[1347839365] 'agreement among raft nodes before linearized reading' (duration: 299.51382ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:38:11.182826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2908} {"level":"info","ts":"2026-06-08T03:38:11.272693Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2908,"took":"88.542878ms","hash":1583000665,"current-db-size-bytes":53714944,"current-db-size":"54 MB","current-db-size-in-use-bytes":29044736,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-08T03:38:11.272748Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1583000665,"revision":2908,"compact-revision":-1} {"level":"info","ts":"2026-06-08T03:40:29.973024Z","caller":"traceutil/trace.go:171","msg":"trace[321068575] linearizableReadLoop","detail":"{readStateIndex:6760; appliedIndex:6759; }","duration":"150.359934ms","start":"2026-06-08T03:40:29.822638Z","end":"2026-06-08T03:40:29.972998Z","steps":["trace[321068575] 'read index received' (duration: 75.857987ms)","trace[321068575] 'applied index is now lower than readState.Index' (duration: 74.500667ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T03:40:29.973122Z","caller":"traceutil/trace.go:171","msg":"trace[208703570] transaction","detail":"{read_only:false; response_revision:6523; number_of_response:1; }","duration":"185.805835ms","start":"2026-06-08T03:40:29.787278Z","end":"2026-06-08T03:40:29.973084Z","steps":["trace[208703570] 'process raft request' (duration: 111.207747ms)","trace[208703570] 'compare' (duration: 74.249293ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T03:40:29.973323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.43192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-08T03:40:29.973395Z","caller":"traceutil/trace.go:171","msg":"trace[1868336181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6524; }","duration":"147.541492ms","start":"2026-06-08T03:40:29.825838Z","end":"2026-06-08T03:40:29.97338Z","steps":["trace[1868336181] 'agreement among raft nodes before linearized reading' (duration: 147.423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:40:29.973475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.828851ms","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-06-08T03:40:29.973513Z","caller":"traceutil/trace.go:171","msg":"trace[897323618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6524; }","duration":"150.919462ms","start":"2026-06-08T03:40:29.822583Z","end":"2026-06-08T03:40:29.973503Z","steps":["trace[897323618] 'agreement among raft nodes before linearized reading' (duration: 150.614737ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:40:29.973344Z","caller":"traceutil/trace.go:171","msg":"trace[1467573504] transaction","detail":"{read_only:false; response_revision:6524; number_of_response:1; }","duration":"140.561147ms","start":"2026-06-08T03:40:29.832769Z","end":"2026-06-08T03:40:29.97333Z","steps":["trace[1467573504] 'process raft request' (duration: 140.175781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:40:29.97358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.767309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-08T03:40:29.973688Z","caller":"traceutil/trace.go:171","msg":"trace[1956084962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6524; }","duration":"146.845281ms","start":"2026-06-08T03:40:29.826763Z","end":"2026-06-08T03:40:29.973608Z","steps":["trace[1956084962] 'agreement among raft nodes before linearized reading' (duration: 146.738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T03:40:59.596764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.454203ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-08T03:40:59.605359Z","caller":"traceutil/trace.go:171","msg":"trace[817090297] transaction","detail":"{read_only:false; response_revision:6731; number_of_response:1; }","duration":"126.267252ms","start":"2026-06-08T03:40:59.479075Z","end":"2026-06-08T03:40:59.605342Z","steps":["trace[817090297] 'process raft request' (duration: 126.1123ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T03:43:11.187951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5579} {"level":"info","ts":"2026-06-08T03:43:11.282352Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5579,"took":"92.688421ms","hash":3624678764,"current-db-size-bytes":53714944,"current-db-size":"54 MB","current-db-size-in-use-bytes":18288640,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-08T03:43:11.282452Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3624678764,"revision":5579,"compact-revision":2908}