{"level":"warn","ts":"2026-10-01T06:20:46.113622Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-01T06:20:46.113717Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.85: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://162.253.55.85:2380","--initial-cluster=instance=https://162.253.55.85:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.85:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.85: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-10-01T06:20:46.113784Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-01T06:20:46.113792Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-10-01T06:20:46.113812Z","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-10-01T06:20:46.115032Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"]} {"level":"info","ts":"2026-10-01T06:20:46.115135Z","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://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.85: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-10-01T06:20:46.119146Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.82679ms"} {"level":"info","ts":"2026-10-01T06:20:46.124691Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"427150911cb3975a","cluster-id":"f76f6804bd8187de"} {"level":"info","ts":"2026-10-01T06:20:46.124732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=()"} {"level":"info","ts":"2026-10-01T06:20:46.124745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 0"} {"level":"info","ts":"2026-10-01T06:20:46.124756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 427150911cb3975a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-01T06:20:46.124765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 1"} {"level":"info","ts":"2026-10-01T06:20:46.124794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"warn","ts":"2026-10-01T06:20:46.135387Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-01T06:20:46.13863Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-01T06:20:46.140151Z","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-10-01T06:20:46.143163Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"427150911cb3975a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-01T06:20:46.143311Z","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-10-01T06:20:46.143386Z","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-10-01T06:20:46.143398Z","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-10-01T06:20:46.143651Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"427150911cb3975a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-01T06:20:46.144128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"info","ts":"2026-10-01T06:20:46.144232Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","added-peer-id":"427150911cb3975a","added-peer-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-10-01T06:20:46.146945Z","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-10-01T06:20:46.147234Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"427150911cb3975a","initial-advertise-peer-urls":["https://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-01T06:20:46.147284Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-01T06:20:46.147317Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-10-01T06:20:46.147354Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-10-01T06:20:46.325561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a is starting a new election at term 1"} {"level":"info","ts":"2026-10-01T06:20:46.325599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became pre-candidate at term 1"} {"level":"info","ts":"2026-10-01T06:20:46.325625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgPreVoteResp from 427150911cb3975a at term 1"} {"level":"info","ts":"2026-10-01T06:20:46.325642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became candidate at term 2"} {"level":"info","ts":"2026-10-01T06:20:46.325653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgVoteResp from 427150911cb3975a at term 2"} {"level":"info","ts":"2026-10-01T06:20:46.325668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became leader at term 2"} {"level":"info","ts":"2026-10-01T06:20:46.325685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 427150911cb3975a elected leader 427150911cb3975a at term 2"} {"level":"info","ts":"2026-10-01T06:20:46.327484Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"427150911cb3975a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.85:2379]}","request-path":"/0/members/427150911cb3975a/attributes","cluster-id":"f76f6804bd8187de","publish-timeout":"7s"} {"level":"info","ts":"2026-10-01T06:20:46.327567Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-01T06:20:46.327675Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-01T06:20:46.327995Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-01T06:20:46.32801Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-01T06:20:46.329673Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-01T06:20:46.329832Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T06:20:46.331375Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T06:20:46.331521Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T06:20:46.331553Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-01T06:20:46.33291Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.85:2379"} {"level":"info","ts":"2026-10-01T06:21:10.636374Z","caller":"traceutil/trace.go:171","msg":"trace[1079986018] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"102.245048ms","start":"2026-10-01T06:21:10.534103Z","end":"2026-10-01T06:21:10.636348Z","steps":["trace[1079986018] 'read index received' (duration: 102.029392ms)","trace[1079986018] 'applied index is now lower than readState.Index' (duration: 214.106µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:21:10.636459Z","caller":"traceutil/trace.go:171","msg":"trace[1022251186] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"114.406248ms","start":"2026-10-01T06:21:10.522005Z","end":"2026-10-01T06:21:10.636411Z","steps":["trace[1022251186] 'process raft request' (duration: 114.156511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:10.636585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.448463ms","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-10-01T06:21:10.637172Z","caller":"traceutil/trace.go:171","msg":"trace[1835594709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:402; }","duration":"103.062573ms","start":"2026-10-01T06:21:10.534076Z","end":"2026-10-01T06:21:10.637138Z","steps":["trace[1835594709] 'agreement among raft nodes before linearized reading' (duration: 102.400193ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:20.876145Z","caller":"traceutil/trace.go:171","msg":"trace[1585690613] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"113.881743ms","start":"2026-10-01T06:21:20.762228Z","end":"2026-10-01T06:21:20.87611Z","steps":["trace[1585690613] 'process raft request' (duration: 113.751649ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:25.686946Z","caller":"traceutil/trace.go:171","msg":"trace[1393582977] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"243.755486ms","start":"2026-10-01T06:21:25.44315Z","end":"2026-10-01T06:21:25.686905Z","steps":["trace[1393582977] 'process raft request' (duration: 243.614613ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:25.686958Z","caller":"traceutil/trace.go:171","msg":"trace[1242817620] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"243.781527ms","start":"2026-10-01T06:21:25.443135Z","end":"2026-10-01T06:21:25.686917Z","steps":["trace[1242817620] 'process raft request' (duration: 243.444328ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:25.702596Z","caller":"traceutil/trace.go:171","msg":"trace[830079373] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"255.115767ms","start":"2026-10-01T06:21:25.447456Z","end":"2026-10-01T06:21:25.702572Z","steps":["trace[830079373] 'process raft request' (duration: 254.908732ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:25.702694Z","caller":"traceutil/trace.go:171","msg":"trace[162968112] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"252.95656ms","start":"2026-10-01T06:21:25.449707Z","end":"2026-10-01T06:21:25.702664Z","steps":["trace[162968112] 'process raft request' (duration: 252.765644ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:25.702714Z","caller":"traceutil/trace.go:171","msg":"trace[161569621] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"251.621464ms","start":"2026-10-01T06:21:25.451067Z","end":"2026-10-01T06:21:25.702688Z","steps":["trace[161569621] 'process raft request' (duration: 251.47683ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:25.702709Z","caller":"traceutil/trace.go:171","msg":"trace[1140118945] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"249.439156ms","start":"2026-10-01T06:21:25.453251Z","end":"2026-10-01T06:21:25.702691Z","steps":["trace[1140118945] 'process raft request' (duration: 249.336594ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:25.702958Z","caller":"traceutil/trace.go:171","msg":"trace[713845425] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"249.55874ms","start":"2026-10-01T06:21:25.453382Z","end":"2026-10-01T06:21:25.70294Z","steps":["trace[713845425] 'process raft request' (duration: 249.250752ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:25.703612Z","caller":"traceutil/trace.go:171","msg":"trace[798288775] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"244.067815ms","start":"2026-10-01T06:21:25.459529Z","end":"2026-10-01T06:21:25.703597Z","steps":["trace[798288775] 'process raft request' (duration: 243.349075ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:25.703827Z","caller":"traceutil/trace.go:171","msg":"trace[2059664400] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"170.28184ms","start":"2026-10-01T06:21:25.53353Z","end":"2026-10-01T06:21:25.703812Z","steps":["trace[2059664400] 'process raft request' (duration: 170.196059ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:25.703833Z","caller":"traceutil/trace.go:171","msg":"trace[1554077020] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"238.614079ms","start":"2026-10-01T06:21:25.465193Z","end":"2026-10-01T06:21:25.703807Z","steps":["trace[1554077020] 'process raft request' (duration: 238.215039ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.54536Z","caller":"traceutil/trace.go:171","msg":"trace[1050956712] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"287.854663ms","start":"2026-10-01T06:21:32.257433Z","end":"2026-10-01T06:21:32.545287Z","steps":["trace[1050956712] 'process raft request' (duration: 280.900519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:32.545452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.817673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-01T06:21:32.54554Z","caller":"traceutil/trace.go:171","msg":"trace[1391788730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"263.916826ms","start":"2026-10-01T06:21:32.281605Z","end":"2026-10-01T06:21:32.545522Z","steps":["trace[1391788730] 'agreement among raft nodes before linearized reading' (duration: 263.741061ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:32.545991Z","caller":"traceutil/trace.go:171","msg":"trace[1466111249] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"263.559697ms","start":"2026-10-01T06:21:32.281618Z","end":"2026-10-01T06:21:32.545178Z","steps":["trace[1466111249] 'read index received' (duration: 256.221653ms)","trace[1466111249] 'applied index is now lower than readState.Index' (duration: 7.336674ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:21:32.546589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.660214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-10-01T06:21:32.546637Z","caller":"traceutil/trace.go:171","msg":"trace[1944454585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"248.771167ms","start":"2026-10-01T06:21:32.297853Z","end":"2026-10-01T06:21:32.546624Z","steps":["trace[1944454585] 'agreement among raft nodes before linearized reading' (duration: 248.624683ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:33.194154Z","caller":"traceutil/trace.go:171","msg":"trace[1653858588] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"416.082052ms","start":"2026-10-01T06:21:32.778032Z","end":"2026-10-01T06:21:33.194114Z","steps":["trace[1653858588] 'read index received' (duration: 415.816826ms)","trace[1653858588] 'applied index is now lower than readState.Index' (duration: 263.656µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:21:33.194376Z","caller":"traceutil/trace.go:171","msg":"trace[1973363872] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"566.630019ms","start":"2026-10-01T06:21:32.627546Z","end":"2026-10-01T06:21:33.194176Z","steps":["trace[1973363872] 'process raft request' (duration: 566.381743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:33.194543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.191206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fmcbl\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-01T06:21:33.194634Z","caller":"traceutil/trace.go:171","msg":"trace[1961608614] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fmcbl; range_end:; response_count:1; response_revision:610; }","duration":"388.427982ms","start":"2026-10-01T06:21:32.806184Z","end":"2026-10-01T06:21:33.194612Z","steps":["trace[1961608614] 'agreement among raft nodes before linearized reading' (duration: 388.109794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:33.194705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.649186ms","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-10-01T06:21:33.19472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.235154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-01T06:21:33.19472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.806156Z","time spent":"388.551295ms","remote":"127.0.0.1:45140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-fmcbl\" "} {"level":"info","ts":"2026-10-01T06:21:33.194814Z","caller":"traceutil/trace.go:171","msg":"trace[1571034880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"416.843591ms","start":"2026-10-01T06:21:32.777951Z","end":"2026-10-01T06:21:33.194794Z","steps":["trace[1571034880] 'agreement among raft nodes before linearized reading' (duration: 416.318488ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:33.194832Z","caller":"traceutil/trace.go:171","msg":"trace[625168768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"260.399628ms","start":"2026-10-01T06:21:32.934409Z","end":"2026-10-01T06:21:33.194809Z","steps":["trace[625168768] 'agreement among raft nodes before linearized reading' (duration: 260.044069ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:33.194901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.777918Z","time spent":"416.940594ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-01T06:21:33.195376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:21:32.627515Z","time spent":"566.998398ms","remote":"127.0.0.1:45196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-01T06:21:35.241819Z","caller":"traceutil/trace.go:171","msg":"trace[1279452558] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"179.349137ms","start":"2026-10-01T06:21:35.062413Z","end":"2026-10-01T06:21:35.241762Z","steps":["trace[1279452558] 'process raft request' (duration: 166.425171ms)","trace[1279452558] 'compare' (duration: 12.744011ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:21:50.8176Z","caller":"traceutil/trace.go:171","msg":"trace[1153029221] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"124.230876ms","start":"2026-10-01T06:21:50.693342Z","end":"2026-10-01T06:21:50.817573Z","steps":["trace[1153029221] 'read index received' (duration: 123.94685ms)","trace[1153029221] 'applied index is now lower than readState.Index' (duration: 283.306µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:21:50.817719Z","caller":"traceutil/trace.go:171","msg":"trace[647308187] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"127.199591ms","start":"2026-10-01T06:21:50.690506Z","end":"2026-10-01T06:21:50.817705Z","steps":["trace[647308187] 'process raft request' (duration: 126.851504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:21:50.818234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.874471ms","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-10-01T06:21:50.818308Z","caller":"traceutil/trace.go:171","msg":"trace[731461512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:776; }","duration":"124.992813ms","start":"2026-10-01T06:21:50.693303Z","end":"2026-10-01T06:21:50.818296Z","steps":["trace[731461512] 'agreement among raft nodes before linearized reading' (duration: 124.561364ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:21:50.983377Z","caller":"traceutil/trace.go:171","msg":"trace[1295555004] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"161.066366ms","start":"2026-10-01T06:21:50.822263Z","end":"2026-10-01T06:21:50.98333Z","steps":["trace[1295555004] 'process raft request' (duration: 137.496244ms)","trace[1295555004] 'compare' (duration: 23.390278ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:21:50.984922Z","caller":"traceutil/trace.go:171","msg":"trace[637313320] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"161.231229ms","start":"2026-10-01T06:21:50.823628Z","end":"2026-10-01T06:21:50.98486Z","steps":["trace[637313320] 'process raft request' (duration: 161.073045ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:05.208282Z","caller":"traceutil/trace.go:171","msg":"trace[1246897574] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"131.48674ms","start":"2026-10-01T06:22:05.076773Z","end":"2026-10-01T06:22:05.20826Z","steps":["trace[1246897574] 'process raft request' (duration: 131.328838ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:05.209356Z","caller":"traceutil/trace.go:171","msg":"trace[1860128589] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"131.269855ms","start":"2026-10-01T06:22:05.078066Z","end":"2026-10-01T06:22:05.209336Z","steps":["trace[1860128589] 'process raft request' (duration: 131.172263ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:22:11.060877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.620694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-cainjector-579c9dd7cd-m58z2\" ","response":"range_response_count:1 size:2733"} {"level":"info","ts":"2026-10-01T06:22:11.060958Z","caller":"traceutil/trace.go:171","msg":"trace[609630802] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-cainjector-579c9dd7cd-m58z2; range_end:; response_count:1; response_revision:1073; }","duration":"104.709556ms","start":"2026-10-01T06:22:10.956229Z","end":"2026-10-01T06:22:11.060938Z","steps":["trace[609630802] 'agreement among raft nodes before linearized reading' (duration: 104.536972ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:11.164264Z","caller":"traceutil/trace.go:171","msg":"trace[1017793169] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"147.601181ms","start":"2026-10-01T06:22:11.01663Z","end":"2026-10-01T06:22:11.164231Z","steps":["trace[1017793169] 'process raft request' (duration: 120.179773ms)","trace[1017793169] 'compare' (duration: 27.178843ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:22:11.16442Z","caller":"traceutil/trace.go:171","msg":"trace[1938982740] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1110; }","duration":"103.747932ms","start":"2026-10-01T06:22:11.060641Z","end":"2026-10-01T06:22:11.164389Z","steps":["trace[1938982740] 'read index received' (duration: 76.179651ms)","trace[1938982740] 'applied index is now lower than readState.Index' (duration: 27.567381ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:22:11.16459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.060011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:4953"} {"level":"info","ts":"2026-10-01T06:22:11.164624Z","caller":"traceutil/trace.go:171","msg":"trace[11174771] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:1075; }","duration":"166.135093ms","start":"2026-10-01T06:22:10.998478Z","end":"2026-10-01T06:22:11.164613Z","steps":["trace[11174771] 'agreement among raft nodes before linearized reading' (duration: 165.98414ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:11.164628Z","caller":"traceutil/trace.go:171","msg":"trace[319405511] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"146.310098ms","start":"2026-10-01T06:22:11.018277Z","end":"2026-10-01T06:22:11.164587Z","steps":["trace[319405511] 'process raft request' (duration: 145.936391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:22:11.164833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.547461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager\" ","response":"range_response_count:1 size:5136"} {"level":"info","ts":"2026-10-01T06:22:11.164869Z","caller":"traceutil/trace.go:171","msg":"trace[1030357157] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager; range_end:; response_count:1; response_revision:1075; }","duration":"165.606133ms","start":"2026-10-01T06:22:10.999253Z","end":"2026-10-01T06:22:11.164859Z","steps":["trace[1030357157] 'agreement among raft nodes before linearized reading' (duration: 165.519031ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:11.174875Z","caller":"traceutil/trace.go:171","msg":"trace[1343099200] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"107.731187ms","start":"2026-10-01T06:22:11.067127Z","end":"2026-10-01T06:22:11.174858Z","steps":["trace[1343099200] 'process raft request' (duration: 107.690746ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:11.175204Z","caller":"traceutil/trace.go:171","msg":"trace[1541027988] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"110.318667ms","start":"2026-10-01T06:22:11.064874Z","end":"2026-10-01T06:22:11.175192Z","steps":["trace[1541027988] 'process raft request' (duration: 109.784527ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:11.175509Z","caller":"traceutil/trace.go:171","msg":"trace[226560525] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"108.694965ms","start":"2026-10-01T06:22:11.066804Z","end":"2026-10-01T06:22:11.175499Z","steps":["trace[226560525] 'process raft request' (duration: 107.942181ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:11.349294Z","caller":"traceutil/trace.go:171","msg":"trace[1581140886] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"104.968485ms","start":"2026-10-01T06:22:11.244303Z","end":"2026-10-01T06:22:11.349271Z","steps":["trace[1581140886] 'process raft request' (duration: 41.538416ms)","trace[1581140886] 'compare' (duration: 63.251856ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:22:11.349547Z","caller":"traceutil/trace.go:171","msg":"trace[2021595843] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1120; }","duration":"104.560628ms","start":"2026-10-01T06:22:11.244926Z","end":"2026-10-01T06:22:11.349487Z","steps":["trace[2021595843] 'read index received' (duration: 16.732786ms)","trace[2021595843] 'applied index is now lower than readState.Index' (duration: 87.826732ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:22:11.349719Z","caller":"traceutil/trace.go:171","msg":"trace[813369937] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"105.142919ms","start":"2026-10-01T06:22:11.244565Z","end":"2026-10-01T06:22:11.349707Z","steps":["trace[813369937] 'process raft request' (duration: 104.67438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:22:11.349904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.962645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/cert-manager/cert-manager-startupapicheck:create-cert\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:22:11.349954Z","caller":"traceutil/trace.go:171","msg":"trace[203768664] range","detail":"{range_begin:/registry/roles/cert-manager/cert-manager-startupapicheck:create-cert; range_end:; response_count:0; response_revision:1085; }","duration":"105.024726ms","start":"2026-10-01T06:22:11.244913Z","end":"2026-10-01T06:22:11.349938Z","steps":["trace[203768664] 'agreement among raft nodes before linearized reading' (duration: 104.877794ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:49.791564Z","caller":"traceutil/trace.go:171","msg":"trace[461659685] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"168.064499ms","start":"2026-10-01T06:22:49.623463Z","end":"2026-10-01T06:22:49.791528Z","steps":["trace[461659685] 'process raft request' (duration: 128.074202ms)","trace[461659685] 'compare' (duration: 39.746743ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:22:49.791785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.196492ms","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-10-01T06:22:49.79186Z","caller":"traceutil/trace.go:171","msg":"trace[2052963680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1495; }","duration":"168.329834ms","start":"2026-10-01T06:22:49.623502Z","end":"2026-10-01T06:22:49.791832Z","steps":["trace[2052963680] 'agreement among raft nodes before linearized reading' (duration: 168.09112ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:49.791572Z","caller":"traceutil/trace.go:171","msg":"trace[1859573104] linearizableReadLoop","detail":"{readStateIndex:1546; appliedIndex:1545; }","duration":"167.970798ms","start":"2026-10-01T06:22:49.62357Z","end":"2026-10-01T06:22:49.791541Z","steps":["trace[1859573104] 'read index received' (duration: 127.89694ms)","trace[1859573104] 'applied index is now lower than readState.Index' (duration: 40.070688ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:22:49.792313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.071067ms","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-10-01T06:22:49.792362Z","caller":"traceutil/trace.go:171","msg":"trace[2012527928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1495; }","duration":"150.187978ms","start":"2026-10-01T06:22:49.642159Z","end":"2026-10-01T06:22:49.792347Z","steps":["trace[2012527928] 'agreement among raft nodes before linearized reading' (duration: 149.979065ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:49.949181Z","caller":"traceutil/trace.go:171","msg":"trace[1143531478] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"152.435974ms","start":"2026-10-01T06:22:49.796715Z","end":"2026-10-01T06:22:49.949151Z","steps":["trace[1143531478] 'process raft request' (duration: 141.598804ms)","trace[1143531478] 'compare' (duration: 10.628807ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:22:49.949434Z","caller":"traceutil/trace.go:171","msg":"trace[1792857519] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"152.572317ms","start":"2026-10-01T06:22:49.796795Z","end":"2026-10-01T06:22:49.949367Z","steps":["trace[1792857519] 'process raft request' (duration: 152.296012ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:49.949532Z","caller":"traceutil/trace.go:171","msg":"trace[2124499136] linearizableReadLoop","detail":"{readStateIndex:1550; appliedIndex:1548; }","duration":"150.715697ms","start":"2026-10-01T06:22:49.798779Z","end":"2026-10-01T06:22:49.949495Z","steps":["trace[2124499136] 'read index received' (duration: 139.520031ms)","trace[2124499136] 'applied index is now lower than readState.Index' (duration: 11.192056ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:22:49.949718Z","caller":"traceutil/trace.go:171","msg":"trace[1245239963] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"150.323762ms","start":"2026-10-01T06:22:49.799365Z","end":"2026-10-01T06:22:49.949689Z","steps":["trace[1245239963] 'process raft request' (duration: 149.933556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:22:49.949799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.991181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-10-01T06:22:49.949871Z","caller":"traceutil/trace.go:171","msg":"trace[1284108393] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:1498; }","duration":"151.078073ms","start":"2026-10-01T06:22:49.798753Z","end":"2026-10-01T06:22:49.949831Z","steps":["trace[1284108393] 'agreement among raft nodes before linearized reading' (duration: 150.86215ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:49.950333Z","caller":"traceutil/trace.go:171","msg":"trace[1424387747] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"150.404662ms","start":"2026-10-01T06:22:49.79991Z","end":"2026-10-01T06:22:49.950315Z","steps":["trace[1424387747] 'process raft request' (duration: 149.655591ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:22:58.353365Z","caller":"traceutil/trace.go:171","msg":"trace[800910266] transaction","detail":"{read_only:false; response_revision:1683; number_of_response:1; }","duration":"100.69137ms","start":"2026-10-01T06:22:58.252653Z","end":"2026-10-01T06:22:58.353344Z","steps":["trace[800910266] 'process raft request' (duration: 100.561248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:11.284247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.207549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T06:23:11.284373Z","caller":"traceutil/trace.go:171","msg":"trace[1774123989] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"130.401429ms","start":"2026-10-01T06:23:11.153952Z","end":"2026-10-01T06:23:11.284354Z","steps":["trace[1774123989] 'read index received' (duration: 30.73µs)","trace[1774123989] 'applied index is now lower than readState.Index' (duration: 130.369069ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:23:11.28455Z","caller":"traceutil/trace.go:171","msg":"trace[1968057931] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"534.048256ms","start":"2026-10-01T06:23:10.750482Z","end":"2026-10-01T06:23:11.284531Z","steps":["trace[1968057931] 'process raft request' (duration: 74.909454ms)","trace[1968057931] 'compare' (duration: 458.085537ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:23:11.284669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:10.750461Z","time spent":"534.172768ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:23:11.284776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.822305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-52218588-fe61-4686-8d5b-c44a2a65aa6a\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-01T06:23:11.284866Z","caller":"traceutil/trace.go:171","msg":"trace[1808369265] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-52218588-fe61-4686-8d5b-c44a2a65aa6a; range_end:; response_count:1; response_revision:1861; }","duration":"130.950636ms","start":"2026-10-01T06:23:11.153903Z","end":"2026-10-01T06:23:11.284853Z","steps":["trace[1808369265] 'agreement among raft nodes before linearized reading' (duration: 130.50652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:11.845265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.292861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T06:23:11.845506Z","caller":"traceutil/trace.go:171","msg":"trace[287392585] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"525.541541ms","start":"2026-10-01T06:23:11.31993Z","end":"2026-10-01T06:23:11.845472Z","steps":["trace[287392585] 'process raft request' (duration: 310.967947ms)","trace[287392585] 'compare' (duration: 213.982546ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:23:11.845677Z","caller":"traceutil/trace.go:171","msg":"trace[674025830] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"391.791854ms","start":"2026-10-01T06:23:11.453871Z","end":"2026-10-01T06:23:11.845663Z","steps":["trace[674025830] 'read index received' (duration: 177.019697ms)","trace[674025830] 'applied index is now lower than readState.Index' (duration: 214.771007ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:23:11.845748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:11.31988Z","time spent":"525.695894ms","remote":"127.0.0.1:45140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:23:11.845938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.073049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-01T06:23:11.84596Z","caller":"traceutil/trace.go:171","msg":"trace[1544708150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1862; }","duration":"392.133689ms","start":"2026-10-01T06:23:11.45382Z","end":"2026-10-01T06:23:11.845954Z","steps":["trace[1544708150] 'agreement among raft nodes before linearized reading' (duration: 391.915056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:11.84598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:11.453792Z","time spent":"392.18285ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-01T06:23:11.846217Z","caller":"traceutil/trace.go:171","msg":"trace[2068684255] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"284.552772ms","start":"2026-10-01T06:23:11.561657Z","end":"2026-10-01T06:23:11.84621Z","steps":["trace[2068684255] 'process raft request' (duration: 283.735091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:11.846946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.118213ms","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-10-01T06:23:11.847096Z","caller":"traceutil/trace.go:171","msg":"trace[864755592] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1865; }","duration":"327.323575ms","start":"2026-10-01T06:23:11.519718Z","end":"2026-10-01T06:23:11.847042Z","steps":["trace[864755592] 'agreement among raft nodes before linearized reading' (duration: 326.999011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:11.847144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:11.519704Z","time spent":"327.428007ms","remote":"127.0.0.1:56794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-01T06:23:11.8474Z","caller":"traceutil/trace.go:171","msg":"trace[191724910] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"199.547595ms","start":"2026-10-01T06:23:11.647823Z","end":"2026-10-01T06:23:11.847371Z","steps":["trace[191724910] 'process raft request' (duration: 198.729753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:11.847535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.200092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-01T06:23:11.847569Z","caller":"traceutil/trace.go:171","msg":"trace[1038804228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1865; }","duration":"175.266022ms","start":"2026-10-01T06:23:11.672293Z","end":"2026-10-01T06:23:11.84756Z","steps":["trace[1038804228] 'agreement among raft nodes before linearized reading' (duration: 175.159661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:11.847434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.68702ms","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-10-01T06:23:11.847608Z","caller":"traceutil/trace.go:171","msg":"trace[1523137200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1865; }","duration":"149.892613ms","start":"2026-10-01T06:23:11.697703Z","end":"2026-10-01T06:23:11.847596Z","steps":["trace[1523137200] 'agreement among raft nodes before linearized reading' (duration: 149.649189ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:42.166672Z","caller":"traceutil/trace.go:171","msg":"trace[239122299] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"175.59682ms","start":"2026-10-01T06:23:41.991044Z","end":"2026-10-01T06:23:42.166641Z","steps":["trace[239122299] 'process raft request' (duration: 175.411507ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:42.390357Z","caller":"traceutil/trace.go:171","msg":"trace[187600484] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"394.580454ms","start":"2026-10-01T06:23:41.995758Z","end":"2026-10-01T06:23:42.390338Z","steps":["trace[187600484] 'process raft request' (duration: 394.276249ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:42.390525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:41.995736Z","time spent":"394.691385ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:23:42.390238Z","caller":"traceutil/trace.go:171","msg":"trace[200639451] linearizableReadLoop","detail":"{readStateIndex:2173; appliedIndex:2172; }","duration":"379.187546ms","start":"2026-10-01T06:23:42.011025Z","end":"2026-10-01T06:23:42.390213Z","steps":["trace[200639451] 'read index received' (duration: 156.39492ms)","trace[200639451] 'applied index is now lower than readState.Index' (duration: 222.791316ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:23:42.390854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.798774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-01T06:23:42.390941Z","caller":"traceutil/trace.go:171","msg":"trace[1286952287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2087; }","duration":"379.933887ms","start":"2026-10-01T06:23:42.010989Z","end":"2026-10-01T06:23:42.390923Z","steps":["trace[1286952287] 'agreement among raft nodes before linearized reading' (duration: 379.656993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:42.390984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:42.010974Z","time spent":"380.000647ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-01T06:23:42.391354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.450918ms","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-10-01T06:23:42.391518Z","caller":"traceutil/trace.go:171","msg":"trace[1605891142] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2087; }","duration":"356.60597ms","start":"2026-10-01T06:23:42.034868Z","end":"2026-10-01T06:23:42.391474Z","steps":["trace[1605891142] 'agreement among raft nodes before linearized reading' (duration: 356.316656ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:42.391613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:42.034858Z","time spent":"356.712502ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-01T06:23:42.391619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.567778ms","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-10-01T06:23:42.391813Z","caller":"traceutil/trace.go:171","msg":"trace[1879297178] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2087; }","duration":"331.200768ms","start":"2026-10-01T06:23:42.060593Z","end":"2026-10-01T06:23:42.391794Z","steps":["trace[1879297178] 'agreement among raft nodes before linearized reading' (duration: 330.415367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:42.391872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:42.060575Z","time spent":"331.280429ms","remote":"127.0.0.1:56794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-01T06:23:42.391709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.438814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-01T06:23:42.392075Z","caller":"traceutil/trace.go:171","msg":"trace[437801449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2087; }","duration":"369.979161ms","start":"2026-10-01T06:23:42.022073Z","end":"2026-10-01T06:23:42.392053Z","steps":["trace[437801449] 'agreement among raft nodes before linearized reading' (duration: 368.887127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:42.39224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:42.022038Z","time spent":"370.185204ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-01T06:23:42.625Z","caller":"traceutil/trace.go:171","msg":"trace[131581415] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"228.522843ms","start":"2026-10-01T06:23:42.396449Z","end":"2026-10-01T06:23:42.624971Z","steps":["trace[131581415] 'process raft request' (duration: 132.766541ms)","trace[131581415] 'compare' (duration: 95.61816ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:23:42.695817Z","caller":"traceutil/trace.go:171","msg":"trace[300421831] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"299.308337ms","start":"2026-10-01T06:23:42.396482Z","end":"2026-10-01T06:23:42.695791Z","steps":["trace[300421831] 'process raft request' (duration: 299.110654ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:42.696206Z","caller":"traceutil/trace.go:171","msg":"trace[143195019] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"299.642852ms","start":"2026-10-01T06:23:42.396531Z","end":"2026-10-01T06:23:42.696173Z","steps":["trace[143195019] 'process raft request' (duration: 299.205876ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:42.696651Z","caller":"traceutil/trace.go:171","msg":"trace[517444235] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"299.584422ms","start":"2026-10-01T06:23:42.397049Z","end":"2026-10-01T06:23:42.696634Z","steps":["trace[517444235] 'process raft request' (duration: 299.380279ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:42.696973Z","caller":"traceutil/trace.go:171","msg":"trace[2035098699] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"183.837849ms","start":"2026-10-01T06:23:42.51311Z","end":"2026-10-01T06:23:42.696947Z","steps":["trace[2035098699] 'process raft request' (duration: 183.741728ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:42.69703Z","caller":"traceutil/trace.go:171","msg":"trace[440883231] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"198.142123ms","start":"2026-10-01T06:23:42.498859Z","end":"2026-10-01T06:23:42.697001Z","steps":["trace[440883231] 'process raft request' (duration: 197.720077ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:42.696571Z","caller":"traceutil/trace.go:171","msg":"trace[427709697] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"299.950675ms","start":"2026-10-01T06:23:42.396555Z","end":"2026-10-01T06:23:42.696505Z","steps":["trace[427709697] 'process raft request' (duration: 299.557941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:42.697339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:42.396531Z","time spent":"300.680966ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:23:44.357329Z","caller":"traceutil/trace.go:171","msg":"trace[1870474344] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"183.068549ms","start":"2026-10-01T06:23:44.174234Z","end":"2026-10-01T06:23:44.357302Z","steps":["trace[1870474344] 'process raft request' (duration: 182.811016ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:44.766719Z","caller":"traceutil/trace.go:171","msg":"trace[1680731724] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2183; }","duration":"362.115296ms","start":"2026-10-01T06:23:44.404564Z","end":"2026-10-01T06:23:44.766679Z","steps":["trace[1680731724] 'read index received' (duration: 361.892333ms)","trace[1680731724] 'applied index is now lower than readState.Index' (duration: 221.613µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:23:44.766877Z","caller":"traceutil/trace.go:171","msg":"trace[1032884276] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"371.118778ms","start":"2026-10-01T06:23:44.395738Z","end":"2026-10-01T06:23:44.766857Z","steps":["trace[1032884276] 'process raft request' (duration: 370.728302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:44.766995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:44.395712Z","time spent":"371.194608ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:23:44.767215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.66408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-10-01T06:23:44.76733Z","caller":"traceutil/trace.go:171","msg":"trace[1294365674] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2098; }","duration":"338.822603ms","start":"2026-10-01T06:23:44.428483Z","end":"2026-10-01T06:23:44.767306Z","steps":["trace[1294365674] 'agreement among raft nodes before linearized reading' (duration: 338.516439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:44.767387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:44.428465Z","time spent":"338.908684ms","remote":"127.0.0.1:45140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12408,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-01T06:23:44.767628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.061659ms","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-10-01T06:23:44.767679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.647264ms","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-10-01T06:23:44.76773Z","caller":"traceutil/trace.go:171","msg":"trace[2027538706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2098; }","duration":"138.742075ms","start":"2026-10-01T06:23:44.628974Z","end":"2026-10-01T06:23:44.767716Z","steps":["trace[2027538706] 'agreement among raft nodes before linearized reading' (duration: 138.609033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:44.767729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.981826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18da53b0847c10b6\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-10-01T06:23:44.767745Z","caller":"traceutil/trace.go:171","msg":"trace[2091680896] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2098; }","duration":"363.171081ms","start":"2026-10-01T06:23:44.404512Z","end":"2026-10-01T06:23:44.767683Z","steps":["trace[2091680896] 'agreement among raft nodes before linearized reading' (duration: 362.989639ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:44.767785Z","caller":"traceutil/trace.go:171","msg":"trace[1646070477] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18da53b0847c10b6; range_end:; response_count:1; response_revision:2098; }","duration":"333.066386ms","start":"2026-10-01T06:23:44.434702Z","end":"2026-10-01T06:23:44.767768Z","steps":["trace[1646070477] 'agreement among raft nodes before linearized reading' (duration: 332.886114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:44.767824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:44.434685Z","time spent":"333.131567ms","remote":"127.0.0.1:45034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18da53b0847c10b6\" "} {"level":"warn","ts":"2026-10-01T06:23:44.767882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:44.404496Z","time spent":"363.367523ms","remote":"127.0.0.1:56794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-01T06:23:45.137747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.894777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T06:23:45.13794Z","caller":"traceutil/trace.go:171","msg":"trace[630891460] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"367.703558ms","start":"2026-10-01T06:23:44.770154Z","end":"2026-10-01T06:23:45.137858Z","steps":["trace[630891460] 'process raft request' (duration: 192.581978ms)","trace[630891460] 'compare' (duration: 174.438151ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:23:45.137991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:44.770132Z","time spent":"367.835659ms","remote":"127.0.0.1:45034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:23:45.143484Z","caller":"traceutil/trace.go:171","msg":"trace[890641116] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"369.621704ms","start":"2026-10-01T06:23:44.773827Z","end":"2026-10-01T06:23:45.143449Z","steps":["trace[890641116] 'process raft request' (duration: 369.39041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:45.143702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:44.773797Z","time spent":"369.782945ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:23:45.144009Z","caller":"traceutil/trace.go:171","msg":"trace[927418394] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"368.853113ms","start":"2026-10-01T06:23:44.775136Z","end":"2026-10-01T06:23:45.143989Z","steps":["trace[927418394] 'process raft request' (duration: 368.782702ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:45.144Z","caller":"traceutil/trace.go:171","msg":"trace[291604751] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"369.006715ms","start":"2026-10-01T06:23:44.774929Z","end":"2026-10-01T06:23:45.143936Z","steps":["trace[291604751] 'process raft request' (duration: 368.469498ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:45.144004Z","caller":"traceutil/trace.go:171","msg":"trace[1207401706] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"369.031775ms","start":"2026-10-01T06:23:44.774944Z","end":"2026-10-01T06:23:45.143976Z","steps":["trace[1207401706] 'process raft request' (duration: 368.862733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:45.144322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:44.774927Z","time spent":"369.271949ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:23:45.144364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:44.774897Z","time spent":"369.317309ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:23:45.14416Z","caller":"traceutil/trace.go:171","msg":"trace[740340533] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"368.734931ms","start":"2026-10-01T06:23:44.775414Z","end":"2026-10-01T06:23:45.144149Z","steps":["trace[740340533] 'process raft request' (duration: 368.532808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:45.144848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:44.7754Z","time spent":"369.320319ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:23:45.144335Z","caller":"traceutil/trace.go:171","msg":"trace[1882635100] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"320.541413ms","start":"2026-10-01T06:23:44.823767Z","end":"2026-10-01T06:23:45.144309Z","steps":["trace[1882635100] 'process raft request' (duration: 320.29935ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:45.144087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:44.775115Z","time spent":"368.938834ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-01T06:23:45.145303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:44.823724Z","time spent":"321.351324ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:23:45.153785Z","caller":"traceutil/trace.go:171","msg":"trace[1603328082] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2184; }","duration":"374.827693ms","start":"2026-10-01T06:23:44.778884Z","end":"2026-10-01T06:23:45.153712Z","steps":["trace[1603328082] 'read index received' (duration: 184.002472ms)","trace[1603328082] 'applied index is now lower than readState.Index' (duration: 190.824271ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:23:45.154033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.089897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:23:45.154104Z","caller":"traceutil/trace.go:171","msg":"trace[1839354799] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2106; }","duration":"375.234608ms","start":"2026-10-01T06:23:44.778852Z","end":"2026-10-01T06:23:45.154087Z","steps":["trace[1839354799] 'agreement among raft nodes before linearized reading' (duration: 374.986265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:45.154188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:23:44.778841Z","time spent":"375.331879ms","remote":"127.0.0.1:45414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-01T06:23:49.346636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.796471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-01T06:23:49.346799Z","caller":"traceutil/trace.go:171","msg":"trace[748607353] linearizableReadLoop","detail":"{readStateIndex:2210; appliedIndex:2209; }","duration":"159.021527ms","start":"2026-10-01T06:23:49.18776Z","end":"2026-10-01T06:23:49.346781Z","steps":["trace[748607353] 'read index received' (duration: 12.944092ms)","trace[748607353] 'applied index is now lower than readState.Index' (duration: 146.076105ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:23:49.346941Z","caller":"traceutil/trace.go:171","msg":"trace[1245790069] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"168.921409ms","start":"2026-10-01T06:23:49.177952Z","end":"2026-10-01T06:23:49.346874Z","steps":["trace[1245790069] 'process raft request' (duration: 22.792013ms)","trace[1245790069] 'compare' (duration: 145.67054ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:23:49.347224Z","caller":"traceutil/trace.go:171","msg":"trace[677179481] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"154.048471ms","start":"2026-10-01T06:23:49.19313Z","end":"2026-10-01T06:23:49.347179Z","steps":["trace[677179481] 'process raft request' (duration: 153.609965ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:23:49.347633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.075752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-01T06:23:49.347692Z","caller":"traceutil/trace.go:171","msg":"trace[1980561104] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"154.017441ms","start":"2026-10-01T06:23:49.193644Z","end":"2026-10-01T06:23:49.347661Z","steps":["trace[1980561104] 'process raft request' (duration: 153.352742ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:49.347769Z","caller":"traceutil/trace.go:171","msg":"trace[1021495389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2125; }","duration":"172.246344ms","start":"2026-10-01T06:23:49.175491Z","end":"2026-10-01T06:23:49.347737Z","steps":["trace[1021495389] 'agreement among raft nodes before linearized reading' (duration: 171.764737ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:23:49.347729Z","caller":"traceutil/trace.go:171","msg":"trace[262671499] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"152.941916ms","start":"2026-10-01T06:23:49.194776Z","end":"2026-10-01T06:23:49.347718Z","steps":["trace[262671499] 'process raft request' (duration: 152.692723ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:26:04.209309Z","caller":"traceutil/trace.go:171","msg":"trace[102684864] linearizableReadLoop","detail":"{readStateIndex:3062; appliedIndex:3061; }","duration":"183.506877ms","start":"2026-10-01T06:26:04.025781Z","end":"2026-10-01T06:26:04.209288Z","steps":["trace[102684864] 'read index received' (duration: 183.269019ms)","trace[102684864] 'applied index is now lower than readState.Index' (duration: 236.828µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:26:04.209369Z","caller":"traceutil/trace.go:171","msg":"trace[753509826] transaction","detail":"{read_only:false; response_revision:2945; number_of_response:1; }","duration":"184.879643ms","start":"2026-10-01T06:26:04.02446Z","end":"2026-10-01T06:26:04.20934Z","steps":["trace[753509826] 'process raft request' (duration: 184.599333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:26:04.209913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.125128ms","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-10-01T06:26:04.210147Z","caller":"traceutil/trace.go:171","msg":"trace[129940696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2945; }","duration":"184.404936ms","start":"2026-10-01T06:26:04.025724Z","end":"2026-10-01T06:26:04.210129Z","steps":["trace[129940696] 'agreement among raft nodes before linearized reading' (duration: 184.059025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:26:04.238533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.305588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-01T06:26:04.238689Z","caller":"traceutil/trace.go:171","msg":"trace[2017637729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2946; }","duration":"209.39276ms","start":"2026-10-01T06:26:04.029197Z","end":"2026-10-01T06:26:04.238589Z","steps":["trace[2017637729] 'agreement among raft nodes before linearized reading' (duration: 209.185254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:26:04.23881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.381014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-01T06:26:04.238864Z","caller":"traceutil/trace.go:171","msg":"trace[1722092933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2946; }","duration":"199.479178ms","start":"2026-10-01T06:26:04.039371Z","end":"2026-10-01T06:26:04.238851Z","steps":["trace[1722092933] 'agreement among raft nodes before linearized reading' (duration: 199.345184ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:26:04.239172Z","caller":"traceutil/trace.go:171","msg":"trace[2133097251] transaction","detail":"{read_only:false; response_revision:2946; number_of_response:1; }","duration":"157.11638ms","start":"2026-10-01T06:26:04.081572Z","end":"2026-10-01T06:26:04.238689Z","steps":["trace[2133097251] 'process raft request' (duration: 156.696346ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:29:46.020432Z","caller":"traceutil/trace.go:171","msg":"trace[1254020188] transaction","detail":"{read_only:false; response_revision:4236; number_of_response:1; }","duration":"105.675615ms","start":"2026-10-01T06:29:45.914714Z","end":"2026-10-01T06:29:46.02039Z","steps":["trace[1254020188] 'process raft request' (duration: 100.504849ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:29:46.417623Z","caller":"traceutil/trace.go:171","msg":"trace[861257533] transaction","detail":"{read_only:false; response_revision:4243; number_of_response:1; }","duration":"125.546073ms","start":"2026-10-01T06:29:46.292058Z","end":"2026-10-01T06:29:46.417604Z","steps":["trace[861257533] 'process raft request' (duration: 77.340474ms)","trace[861257533] 'compare' (duration: 48.128418ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:30:32.722946Z","caller":"traceutil/trace.go:171","msg":"trace[1276288531] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"112.603041ms","start":"2026-10-01T06:30:32.610289Z","end":"2026-10-01T06:30:32.722892Z","steps":["trace[1276288531] 'process raft request' (duration: 60.300242ms)","trace[1276288531] 'compare' (duration: 52.052675ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:30:32.723137Z","caller":"traceutil/trace.go:171","msg":"trace[590840647] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"106.402351ms","start":"2026-10-01T06:30:32.616708Z","end":"2026-10-01T06:30:32.723111Z","steps":["trace[590840647] 'process raft request' (duration: 106.090436ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:30:45.550865Z","caller":"traceutil/trace.go:171","msg":"trace[1897820053] linearizableReadLoop","detail":"{readStateIndex:5330; appliedIndex:5329; }","duration":"243.397699ms","start":"2026-10-01T06:30:45.307157Z","end":"2026-10-01T06:30:45.550555Z","steps":["trace[1897820053] 'read index received' (duration: 210.854716ms)","trace[1897820053] 'applied index is now lower than readState.Index' (duration: 32.541633ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:30:45.551385Z","caller":"traceutil/trace.go:171","msg":"trace[1490940036] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"309.263947ms","start":"2026-10-01T06:30:45.242096Z","end":"2026-10-01T06:30:45.55136Z","steps":["trace[1490940036] 'process raft request' (duration: 275.996461ms)","trace[1490940036] 'compare' (duration: 32.093915ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:30:45.551463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.298625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-01T06:30:45.551499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:30:45.242068Z","time spent":"309.362268ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:30:45.551508Z","caller":"traceutil/trace.go:171","msg":"trace[134083945] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5127; }","duration":"244.390077ms","start":"2026-10-01T06:30:45.307106Z","end":"2026-10-01T06:30:45.551496Z","steps":["trace[134083945] 'agreement among raft nodes before linearized reading' (duration: 244.236374ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:30:45.706498Z","caller":"traceutil/trace.go:171","msg":"trace[1323891081] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"152.431519ms","start":"2026-10-01T06:30:45.554037Z","end":"2026-10-01T06:30:45.706468Z","steps":["trace[1323891081] 'process raft request' (duration: 147.172737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:30:45.706631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.772051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-50ae49e7-86dc-4080-a5d2-59adbbc92a33\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-10-01T06:30:45.706663Z","caller":"traceutil/trace.go:171","msg":"trace[1312733861] range","detail":"{range_begin:/registry/persistentvolumes/pvc-50ae49e7-86dc-4080-a5d2-59adbbc92a33; range_end:; response_count:1; response_revision:5128; }","duration":"150.858382ms","start":"2026-10-01T06:30:45.555797Z","end":"2026-10-01T06:30:45.706655Z","steps":["trace[1312733861] 'agreement among raft nodes before linearized reading' (duration: 150.729629ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:30:45.706484Z","caller":"traceutil/trace.go:171","msg":"trace[1680710665] linearizableReadLoop","detail":"{readStateIndex:5331; appliedIndex:5330; }","duration":"150.592578ms","start":"2026-10-01T06:30:45.555841Z","end":"2026-10-01T06:30:45.706434Z","steps":["trace[1680710665] 'read index received' (duration: 145.407696ms)","trace[1680710665] 'applied index is now lower than readState.Index' (duration: 5.183972ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:30:45.732445Z","caller":"traceutil/trace.go:171","msg":"trace[597828286] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"166.241763ms","start":"2026-10-01T06:30:45.566185Z","end":"2026-10-01T06:30:45.732426Z","steps":["trace[597828286] 'process raft request' (duration: 166.190062ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:30:45.732801Z","caller":"traceutil/trace.go:171","msg":"trace[342941245] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"175.684118ms","start":"2026-10-01T06:30:45.557109Z","end":"2026-10-01T06:30:45.732793Z","steps":["trace[342941245] 'process raft request' (duration: 175.168139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:30:45.73496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.011026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-01T06:30:45.735105Z","caller":"traceutil/trace.go:171","msg":"trace[2125592488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5130; }","duration":"109.17448ms","start":"2026-10-01T06:30:45.625873Z","end":"2026-10-01T06:30:45.735048Z","steps":["trace[2125592488] 'agreement among raft nodes before linearized reading' (duration: 106.879439ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:30:46.197197Z","caller":"traceutil/trace.go:171","msg":"trace[771221790] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"154.394442ms","start":"2026-10-01T06:30:46.042781Z","end":"2026-10-01T06:30:46.197175Z","steps":["trace[771221790] 'process raft request' (duration: 110.871477ms)","trace[771221790] 'compare' (duration: 43.389703ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:30:46.197654Z","caller":"traceutil/trace.go:171","msg":"trace[1805839568] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"154.765628ms","start":"2026-10-01T06:30:46.04286Z","end":"2026-10-01T06:30:46.197625Z","steps":["trace[1805839568] 'process raft request' (duration: 154.28728ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:30:46.625657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2855} {"level":"info","ts":"2026-10-01T06:30:46.70592Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2855,"took":"79.193691ms","hash":2899086547,"current-db-size-bytes":50900992,"current-db-size":"51 MB","current-db-size-in-use-bytes":26804224,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-01T06:30:46.705982Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2899086547,"revision":2855,"compact-revision":-1} {"level":"info","ts":"2026-10-01T06:30:49.102798Z","caller":"traceutil/trace.go:171","msg":"trace[2123879690] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"218.854886ms","start":"2026-10-01T06:30:48.883898Z","end":"2026-10-01T06:30:49.102753Z","steps":["trace[2123879690] 'process raft request' (duration: 218.701753ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:31:16.94478Z","caller":"traceutil/trace.go:171","msg":"trace[1268629800] linearizableReadLoop","detail":"{readStateIndex:5663; appliedIndex:5662; }","duration":"197.394391ms","start":"2026-10-01T06:31:16.747361Z","end":"2026-10-01T06:31:16.944755Z","steps":["trace[1268629800] 'read index received' (duration: 197.103166ms)","trace[1268629800] 'applied index is now lower than readState.Index' (duration: 290.275µs)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:31:16.945181Z","caller":"traceutil/trace.go:171","msg":"trace[1459195701] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"198.654883ms","start":"2026-10-01T06:31:16.746484Z","end":"2026-10-01T06:31:16.945139Z","steps":["trace[1459195701] 'process raft request' (duration: 198.128664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-01T06:31:16.945336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.939941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-950e900d-1071-48d7-9e41-d1829159934f\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-10-01T06:31:16.945395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.648325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-01T06:31:16.94544Z","caller":"traceutil/trace.go:171","msg":"trace[330210985] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:5450; }","duration":"116.713526ms","start":"2026-10-01T06:31:16.828714Z","end":"2026-10-01T06:31:16.945428Z","steps":["trace[330210985] 'agreement among raft nodes before linearized reading' (duration: 116.616544ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:31:16.945436Z","caller":"traceutil/trace.go:171","msg":"trace[1889559784] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-950e900d-1071-48d7-9e41-d1829159934f; range_end:; response_count:1; response_revision:5450; }","duration":"198.094084ms","start":"2026-10-01T06:31:16.747314Z","end":"2026-10-01T06:31:16.945408Z","steps":["trace[1889559784] 'agreement among raft nodes before linearized reading' (duration: 197.770748ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:31:17.268124Z","caller":"traceutil/trace.go:171","msg":"trace[1490271654] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"314.445828ms","start":"2026-10-01T06:31:16.953648Z","end":"2026-10-01T06:31:17.268094Z","steps":["trace[1490271654] 'process raft request' (duration: 270.86037ms)","trace[1490271654] 'compare' (duration: 43.414165ms)"],"step_count":2} {"level":"warn","ts":"2026-10-01T06:31:17.268349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-01T06:31:16.953625Z","time spent":"314.642751ms","remote":"127.0.0.1:45140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3747,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-01T06:31:17.327301Z","caller":"traceutil/trace.go:171","msg":"trace[1074470906] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"125.166318ms","start":"2026-10-01T06:31:17.202119Z","end":"2026-10-01T06:31:17.327285Z","steps":["trace[1074470906] 'process raft request' (duration: 125.057226ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:31:27.986955Z","caller":"traceutil/trace.go:171","msg":"trace[2110626487] transaction","detail":"{read_only:false; response_revision:5524; number_of_response:1; }","duration":"121.710975ms","start":"2026-10-01T06:31:27.865217Z","end":"2026-10-01T06:31:27.986928Z","steps":["trace[2110626487] 'process raft request' (duration: 103.835161ms)","trace[2110626487] 'compare' (duration: 17.739043ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:31:27.990817Z","caller":"traceutil/trace.go:171","msg":"trace[1719186798] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"124.492793ms","start":"2026-10-01T06:31:27.866309Z","end":"2026-10-01T06:31:27.990802Z","steps":["trace[1719186798] 'process raft request' (duration: 124.278049ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:31:27.99093Z","caller":"traceutil/trace.go:171","msg":"trace[2116576534] transaction","detail":"{read_only:false; response_revision:5527; number_of_response:1; }","duration":"124.482913ms","start":"2026-10-01T06:31:27.866437Z","end":"2026-10-01T06:31:27.99092Z","steps":["trace[2116576534] 'process raft request' (duration: 124.234639ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:31:27.990881Z","caller":"traceutil/trace.go:171","msg":"trace[1989231228] transaction","detail":"{read_only:false; response_revision:5526; number_of_response:1; }","duration":"124.507993ms","start":"2026-10-01T06:31:27.866359Z","end":"2026-10-01T06:31:27.990867Z","steps":["trace[1989231228] 'process raft request' (duration: 124.292069ms)"],"step_count":1} {"level":"info","ts":"2026-10-01T06:31:28.118342Z","caller":"traceutil/trace.go:171","msg":"trace[959661457] transaction","detail":"{read_only:false; response_revision:5530; number_of_response:1; }","duration":"107.159537ms","start":"2026-10-01T06:31:28.011112Z","end":"2026-10-01T06:31:28.118272Z","steps":["trace[959661457] 'process raft request' (duration: 80.94731ms)","trace[959661457] 'compare' (duration: 25.80684ms)"],"step_count":2} {"level":"info","ts":"2026-10-01T06:35:46.672876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5152} {"level":"info","ts":"2026-10-01T06:35:46.745794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5152,"took":"71.936586ms","hash":1915226779,"current-db-size-bytes":50900992,"current-db-size":"51 MB","current-db-size-in-use-bytes":19501056,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-10-01T06:35:46.745861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1915226779,"revision":5152,"compact-revision":2855}