{"level":"warn","ts":"2026-06-19T00:17:06.175873Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-19T00:17:06.17597Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-19T00:17:06.176042Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-19T00:17:06.176053Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-06-19T00:17:06.176074Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-19T00:17:06.176781Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-06-19T00:17:06.176914Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-19T00:17:06.182612Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.428589ms"} {"level":"info","ts":"2026-06-19T00:17:06.192581Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-06-19T00:17:06.192649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-06-19T00:17:06.192669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-06-19T00:17:06.19268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-19T00:17:06.192691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-06-19T00:17:06.192736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-06-19T00:17:06.205627Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-19T00:17:06.207888Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-19T00:17:06.209309Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-19T00:17:06.212934Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-19T00:17:06.213303Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-19T00:17:06.213497Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-19T00:17:06.213567Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-19T00:17:06.213582Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-19T00:17:06.215181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-06-19T00:17:06.216114Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-06-19T00:17:06.218116Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-19T00:17:06.218465Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-19T00:17:06.218525Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-19T00:17:06.218581Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-06-19T00:17:06.218652Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-06-19T00:17:06.493754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-06-19T00:17:06.4938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-06-19T00:17:06.493831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-06-19T00:17:06.493842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-06-19T00:17:06.493847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-06-19T00:17:06.493854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-06-19T00:17:06.493861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-06-19T00:17:06.494627Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T00:17:06.495555Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-06-19T00:17:06.495592Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-19T00:17:06.495679Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-19T00:17:06.496028Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-19T00:17:06.496055Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-19T00:17:06.498022Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T00:17:06.498214Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T00:17:06.498308Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T00:17:06.499202Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-06-19T00:17:06.501785Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-19T00:17:10.996994Z","caller":"traceutil/trace.go:171","msg":"trace[208548335] linearizableReadLoop","detail":"{readStateIndex:233; appliedIndex:232; }","duration":"140.437475ms","start":"2026-06-19T00:17:10.85653Z","end":"2026-06-19T00:17:10.996967Z","steps":["trace[208548335] 'read index received' (duration: 140.240851ms)","trace[208548335] 'applied index is now lower than readState.Index' (duration: 195.214µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:10.997156Z","caller":"traceutil/trace.go:171","msg":"trace[1704098774] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"157.220295ms","start":"2026-06-19T00:17:10.839925Z","end":"2026-06-19T00:17:10.997145Z","steps":["trace[1704098774] 'process raft request' (duration: 156.856326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:10.997264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.818533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-06-19T00:17:10.997395Z","caller":"traceutil/trace.go:171","msg":"trace[1109332120] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:227; }","duration":"110.986196ms","start":"2026-06-19T00:17:10.886384Z","end":"2026-06-19T00:17:10.99737Z","steps":["trace[1109332120] 'agreement among raft nodes before linearized reading' (duration: 110.808322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:10.997622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.078811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-19T00:17:10.997676Z","caller":"traceutil/trace.go:171","msg":"trace[185417970] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:227; }","duration":"141.168923ms","start":"2026-06-19T00:17:10.856496Z","end":"2026-06-19T00:17:10.997665Z","steps":["trace[185417970] 'agreement among raft nodes before linearized reading' (duration: 141.06249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:11.207994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.7003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-06-19T00:17:11.208105Z","caller":"traceutil/trace.go:171","msg":"trace[766231593] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:229; }","duration":"118.856322ms","start":"2026-06-19T00:17:11.089217Z","end":"2026-06-19T00:17:11.208073Z","steps":["trace[766231593] 'range keys from in-memory index tree' (duration: 118.548135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:11.208092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.025644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-h382qk\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:17:11.208251Z","caller":"traceutil/trace.go:171","msg":"trace[2066457488] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-h382qk; range_end:; response_count:0; response_revision:229; }","duration":"118.231508ms","start":"2026-06-19T00:17:11.089992Z","end":"2026-06-19T00:17:11.208224Z","steps":["trace[2066457488] 'range keys from in-memory index tree' (duration: 117.935981ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:11.571256Z","caller":"traceutil/trace.go:171","msg":"trace[175381024] linearizableReadLoop","detail":"{readStateIndex:245; appliedIndex:244; }","duration":"115.153516ms","start":"2026-06-19T00:17:11.456085Z","end":"2026-06-19T00:17:11.571239Z","steps":["trace[175381024] 'read index received' (duration: 113.25088ms)","trace[175381024] 'applied index is now lower than readState.Index' (duration: 1.902216ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:17:11.571372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.265048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:17:11.571398Z","caller":"traceutil/trace.go:171","msg":"trace[1758221484] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:239; }","duration":"115.35017ms","start":"2026-06-19T00:17:11.456041Z","end":"2026-06-19T00:17:11.571391Z","steps":["trace[1758221484] 'agreement among raft nodes before linearized reading' (duration: 115.260178ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:11.572436Z","caller":"traceutil/trace.go:171","msg":"trace[1928183506] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"162.272124ms","start":"2026-06-19T00:17:11.409082Z","end":"2026-06-19T00:17:11.571354Z","steps":["trace[1928183506] 'process raft request' (duration: 160.240676ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:12.142265Z","caller":"traceutil/trace.go:171","msg":"trace[1543998299] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"117.636064ms","start":"2026-06-19T00:17:12.024596Z","end":"2026-06-19T00:17:12.142232Z","steps":["trace[1543998299] 'process raft request' (duration: 115.965524ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:12.142393Z","caller":"traceutil/trace.go:171","msg":"trace[2110811471] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"115.061523ms","start":"2026-06-19T00:17:12.027302Z","end":"2026-06-19T00:17:12.142363Z","steps":["trace[2110811471] 'process raft request' (duration: 114.782266ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:12.142451Z","caller":"traceutil/trace.go:171","msg":"trace[29596987] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"109.343228ms","start":"2026-06-19T00:17:12.033085Z","end":"2026-06-19T00:17:12.142429Z","steps":["trace[29596987] 'process raft request' (duration: 109.149493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:12.142774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.377948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-06-19T00:17:12.142807Z","caller":"traceutil/trace.go:171","msg":"trace[1931425861] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:248; }","duration":"120.147004ms","start":"2026-06-19T00:17:12.022649Z","end":"2026-06-19T00:17:12.142796Z","steps":["trace[1931425861] 'agreement among raft nodes before linearized reading' (duration: 120.071643ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:12.142756Z","caller":"traceutil/trace.go:171","msg":"trace[170151994] linearizableReadLoop","detail":"{readStateIndex:252; appliedIndex:250; }","duration":"117.352478ms","start":"2026-06-19T00:17:12.025382Z","end":"2026-06-19T00:17:12.142735Z","steps":["trace[170151994] 'read index received' (duration: 56.922132ms)","trace[170151994] 'applied index is now lower than readState.Index' (duration: 60.087108ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:17:18.704532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.592578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:17:18.704636Z","caller":"traceutil/trace.go:171","msg":"trace[841877347] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:366; }","duration":"188.257572ms","start":"2026-06-19T00:17:18.516362Z","end":"2026-06-19T00:17:18.704619Z","steps":["trace[841877347] 'read index received' (duration: 39.781µs)","trace[841877347] 'applied index is now lower than readState.Index' (duration: 188.216711ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:18.704698Z","caller":"traceutil/trace.go:171","msg":"trace[210435489] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"320.256986ms","start":"2026-06-19T00:17:18.384411Z","end":"2026-06-19T00:17:18.704668Z","steps":["trace[210435489] 'process raft request' (duration: 29.982742ms)","trace[210435489] 'compare' (duration: 289.402354ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:17:18.704738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.373765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-19T00:17:18.70487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:18.384385Z","time spent":"320.38693ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:17:18.704895Z","caller":"traceutil/trace.go:171","msg":"trace[212472728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:356; }","duration":"188.555979ms","start":"2026-06-19T00:17:18.516321Z","end":"2026-06-19T00:17:18.704877Z","steps":["trace[212472728] 'agreement among raft nodes before linearized reading' (duration: 188.380495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:18.973546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.012664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:17:18.973658Z","caller":"traceutil/trace.go:171","msg":"trace[1435442918] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"265.097407ms","start":"2026-06-19T00:17:18.708538Z","end":"2026-06-19T00:17:18.973635Z","steps":["trace[1435442918] 'process raft request' (duration: 162.91922ms)","trace[1435442918] 'compare' (duration: 101.8972ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:19.198235Z","caller":"traceutil/trace.go:171","msg":"trace[1067291403] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"169.170807ms","start":"2026-06-19T00:17:19.029036Z","end":"2026-06-19T00:17:19.198207Z","steps":["trace[1067291403] 'process raft request' (duration: 168.999333ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:23.035661Z","caller":"traceutil/trace.go:171","msg":"trace[2015160679] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"293.24185ms","start":"2026-06-19T00:17:22.742372Z","end":"2026-06-19T00:17:23.035614Z","steps":["trace[2015160679] 'process raft request' (duration: 292.962624ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:23.284544Z","caller":"traceutil/trace.go:171","msg":"trace[304744799] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"150.447306ms","start":"2026-06-19T00:17:23.134059Z","end":"2026-06-19T00:17:23.284506Z","steps":["trace[304744799] 'process raft request' (duration: 131.070659ms)","trace[304744799] 'compare' (duration: 19.123131ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:23.777804Z","caller":"traceutil/trace.go:171","msg":"trace[1786126622] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"160.353879ms","start":"2026-06-19T00:17:23.61742Z","end":"2026-06-19T00:17:23.777774Z","steps":["trace[1786126622] 'process raft request' (duration: 105.084546ms)","trace[1786126622] 'compare' (duration: 54.972826ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:25.253704Z","caller":"traceutil/trace.go:171","msg":"trace[1345716801] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"118.118643ms","start":"2026-06-19T00:17:25.135566Z","end":"2026-06-19T00:17:25.253685Z","steps":["trace[1345716801] 'process raft request' (duration: 117.98586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:26.656157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.77398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-19T00:17:26.656207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307084044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-19T00:17:26.656243Z","caller":"traceutil/trace.go:171","msg":"trace[813089285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:376; }","duration":"306.909903ms","start":"2026-06-19T00:17:26.349312Z","end":"2026-06-19T00:17:26.656222Z","steps":["trace[813089285] 'range keys from in-memory index tree' (duration: 306.664927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:26.656291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:26.349293Z","time spent":"306.988475ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-19T00:17:26.6563Z","caller":"traceutil/trace.go:171","msg":"trace[387538009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:376; }","duration":"1.307232728s","start":"2026-06-19T00:17:25.349038Z","end":"2026-06-19T00:17:26.656271Z","steps":["trace[387538009] 'range keys from in-memory index tree' (duration: 1.306967301s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:26.65635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:25.349018Z","time spent":"1.307322589s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-19T00:17:26.656422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.641708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:17:26.656496Z","caller":"traceutil/trace.go:171","msg":"trace[2058909032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"671.807672ms","start":"2026-06-19T00:17:25.984671Z","end":"2026-06-19T00:17:26.656479Z","steps":["trace[2058909032] 'range keys from in-memory index tree' (duration: 671.569777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:26.656535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:25.984653Z","time spent":"671.875223ms","remote":"127.0.0.1:43638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T00:17:27.304991Z","caller":"traceutil/trace.go:171","msg":"trace[39127261] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"644.428557ms","start":"2026-06-19T00:17:26.660542Z","end":"2026-06-19T00:17:27.304971Z","steps":["trace[39127261] 'process raft request' (duration: 644.298254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:27.305243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:26.660521Z","time spent":"644.626982ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:17:27.3586Z","caller":"traceutil/trace.go:171","msg":"trace[565031312] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"697.60308ms","start":"2026-06-19T00:17:26.66095Z","end":"2026-06-19T00:17:27.358553Z","steps":["trace[565031312] 'process raft request' (duration: 697.400376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:27.358705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.547477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-19T00:17:27.358748Z","caller":"traceutil/trace.go:171","msg":"trace[802531707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:378; }","duration":"310.64512ms","start":"2026-06-19T00:17:27.048091Z","end":"2026-06-19T00:17:27.358736Z","steps":["trace[802531707] 'agreement among raft nodes before linearized reading' (duration: 310.532427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:27.35877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:27.04807Z","time spent":"310.695641ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-19T00:17:27.358735Z","caller":"traceutil/trace.go:171","msg":"trace[579027310] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:389; }","duration":"310.339703ms","start":"2026-06-19T00:17:27.048137Z","end":"2026-06-19T00:17:27.358477Z","steps":["trace[579027310] 'read index received' (duration: 256.75307ms)","trace[579027310] 'applied index is now lower than readState.Index' (duration: 53.585363ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:17:27.358765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:26.660937Z","time spent":"697.741943ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:17:27.358896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.741713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-19T00:17:27.358941Z","caller":"traceutil/trace.go:171","msg":"trace[378259082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:378; }","duration":"100.829615ms","start":"2026-06-19T00:17:27.258098Z","end":"2026-06-19T00:17:27.358927Z","steps":["trace[378259082] 'agreement among raft nodes before linearized reading' (duration: 100.753053ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:43.721167Z","caller":"traceutil/trace.go:171","msg":"trace[2128868871] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"184.190891ms","start":"2026-06-19T00:17:43.536947Z","end":"2026-06-19T00:17:43.721137Z","steps":["trace[2128868871] 'process raft request' (duration: 183.359251ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:44.312921Z","caller":"traceutil/trace.go:171","msg":"trace[1099997148] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"198.788735ms","start":"2026-06-19T00:17:44.114079Z","end":"2026-06-19T00:17:44.312868Z","steps":["trace[1099997148] 'process raft request' (duration: 198.439387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:44.510867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.892682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:17:44.511022Z","caller":"traceutil/trace.go:171","msg":"trace[2044363587] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"175.672831ms","start":"2026-06-19T00:17:44.335332Z","end":"2026-06-19T00:17:44.511005Z","steps":["trace[2044363587] 'process raft request' (duration: 175.62702ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:44.511058Z","caller":"traceutil/trace.go:171","msg":"trace[1661071548] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"175.776653ms","start":"2026-06-19T00:17:44.335231Z","end":"2026-06-19T00:17:44.511007Z","steps":["trace[1661071548] 'process raft request' (duration: 73.664866ms)","trace[1661071548] 'compare' (duration: 101.763009ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:17:45.214681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.385976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:17:45.214939Z","caller":"traceutil/trace.go:171","msg":"trace[234762934] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:496; }","duration":"354.219148ms","start":"2026-06-19T00:17:44.860686Z","end":"2026-06-19T00:17:45.214905Z","steps":["trace[234762934] 'read index received' (duration: 225.496224ms)","trace[234762934] 'applied index is now lower than readState.Index' (duration: 128.721784ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:45.214968Z","caller":"traceutil/trace.go:171","msg":"trace[1881862322] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"381.605573ms","start":"2026-06-19T00:17:44.833333Z","end":"2026-06-19T00:17:45.214939Z","steps":["trace[1881862322] 'process raft request' (duration: 381.468759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:45.215133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:44.833313Z","time spent":"381.734446ms","remote":"127.0.0.1:36632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16946,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:17:45.215244Z","caller":"traceutil/trace.go:171","msg":"trace[78601432] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"385.516925ms","start":"2026-06-19T00:17:44.829701Z","end":"2026-06-19T00:17:45.215218Z","steps":["trace[78601432] 'process raft request' (duration: 256.440363ms)","trace[78601432] 'compare' (duration: 128.198691ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:17:45.215339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.658348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"warn","ts":"2026-06-19T00:17:45.215363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:44.829681Z","time spent":"385.624868ms","remote":"127.0.0.1:36892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14448,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:17:45.215394Z","caller":"traceutil/trace.go:171","msg":"trace[1617147484] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:480; }","duration":"354.713229ms","start":"2026-06-19T00:17:44.860663Z","end":"2026-06-19T00:17:45.215377Z","steps":["trace[1617147484] 'agreement among raft nodes before linearized reading' (duration: 354.413032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:45.215424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.679218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-06-19T00:17:45.215498Z","caller":"traceutil/trace.go:171","msg":"trace[1685298102] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:480; }","duration":"292.789919ms","start":"2026-06-19T00:17:44.92269Z","end":"2026-06-19T00:17:45.21548Z","steps":["trace[1685298102] 'agreement among raft nodes before linearized reading' (duration: 292.593595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:45.21552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.318129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-06-19T00:17:45.215554Z","caller":"traceutil/trace.go:171","msg":"trace[1534988976] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:480; }","duration":"292.396891ms","start":"2026-06-19T00:17:44.923147Z","end":"2026-06-19T00:17:45.215544Z","steps":["trace[1534988976] 'agreement among raft nodes before linearized reading' (duration: 292.336899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:45.215442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:44.860635Z","time spent":"354.794871ms","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329980,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-06-19T00:17:45.467251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.8585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-06-19T00:17:45.467335Z","caller":"traceutil/trace.go:171","msg":"trace[864962529] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:480; }","duration":"143.992893ms","start":"2026-06-19T00:17:45.32332Z","end":"2026-06-19T00:17:45.467313Z","steps":["trace[864962529] 'range keys from in-memory index tree' (duration: 143.609735ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:45.839021Z","caller":"traceutil/trace.go:171","msg":"trace[1651091887] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"280.290986ms","start":"2026-06-19T00:17:45.558703Z","end":"2026-06-19T00:17:45.838994Z","steps":["trace[1651091887] 'read index received' (duration: 280.120532ms)","trace[1651091887] 'applied index is now lower than readState.Index' (duration: 169.424µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:45.839137Z","caller":"traceutil/trace.go:171","msg":"trace[2009616050] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"306.30994ms","start":"2026-06-19T00:17:45.532784Z","end":"2026-06-19T00:17:45.839094Z","steps":["trace[2009616050] 'process raft request' (duration: 306.047464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:45.839194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.995786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-19T00:17:45.839274Z","caller":"traceutil/trace.go:171","msg":"trace[847001692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"114.12566ms","start":"2026-06-19T00:17:45.725137Z","end":"2026-06-19T00:17:45.839263Z","steps":["trace[847001692] 'agreement among raft nodes before linearized reading' (duration: 113.995037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:45.839298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:45.532766Z","time spent":"306.461443ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:17:45.839175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.45624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-19T00:17:45.839455Z","caller":"traceutil/trace.go:171","msg":"trace[1706974282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:482; }","duration":"280.767957ms","start":"2026-06-19T00:17:45.558651Z","end":"2026-06-19T00:17:45.839419Z","steps":["trace[1706974282] 'agreement among raft nodes before linearized reading' (duration: 280.4349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:46.271118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.552839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:17:46.271278Z","caller":"traceutil/trace.go:171","msg":"trace[840453188] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:500; }","duration":"287.610588ms","start":"2026-06-19T00:17:45.983653Z","end":"2026-06-19T00:17:46.271263Z","steps":["trace[840453188] 'read index received' (duration: 88.836533ms)","trace[840453188] 'applied index is now lower than readState.Index' (duration: 198.772995ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:17:46.271362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.712061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:17:46.271387Z","caller":"traceutil/trace.go:171","msg":"trace[110494567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"287.765402ms","start":"2026-06-19T00:17:45.983613Z","end":"2026-06-19T00:17:46.271378Z","steps":["trace[110494567] 'agreement among raft nodes before linearized reading' (duration: 287.69859ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:46.271459Z","caller":"traceutil/trace.go:171","msg":"trace[2138660978] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"427.597097ms","start":"2026-06-19T00:17:45.843834Z","end":"2026-06-19T00:17:46.271431Z","steps":["trace[2138660978] 'process raft request' (duration: 228.639938ms)","trace[2138660978] 'compare' (duration: 198.422896ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:46.271626Z","caller":"traceutil/trace.go:171","msg":"trace[1267868133] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"427.585727ms","start":"2026-06-19T00:17:45.844028Z","end":"2026-06-19T00:17:46.271614Z","steps":["trace[1267868133] 'process raft request' (duration: 427.181658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:46.271683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:45.844006Z","time spent":"427.644138ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:17:46.271785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:45.843812Z","time spent":"427.755011ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:17:47.447657Z","caller":"traceutil/trace.go:171","msg":"trace[837260107] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"111.458846ms","start":"2026-06-19T00:17:47.336169Z","end":"2026-06-19T00:17:47.447628Z","steps":["trace[837260107] 'process raft request' (duration: 105.589218ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:47.734774Z","caller":"traceutil/trace.go:171","msg":"trace[1710052217] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"112.044311ms","start":"2026-06-19T00:17:47.622696Z","end":"2026-06-19T00:17:47.734741Z","steps":["trace[1710052217] 'process raft request' (duration: 99.326881ms)","trace[1710052217] 'compare' (duration: 12.30793ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:48.23927Z","caller":"traceutil/trace.go:171","msg":"trace[876128768] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"396.279369ms","start":"2026-06-19T00:17:47.842972Z","end":"2026-06-19T00:17:48.239252Z","steps":["trace[876128768] 'read index received' (duration: 386.12633ms)","trace[876128768] 'applied index is now lower than readState.Index' (duration: 10.152359ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:48.239491Z","caller":"traceutil/trace.go:171","msg":"trace[464791411] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"410.973565ms","start":"2026-06-19T00:17:47.828491Z","end":"2026-06-19T00:17:48.239465Z","steps":["trace[464791411] 'process raft request' (duration: 400.594121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:48.239608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:47.828445Z","time spent":"411.100348ms","remote":"127.0.0.1:43674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-19T00:17:48.239669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.669888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-19T00:17:48.239833Z","caller":"traceutil/trace.go:171","msg":"trace[283198910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"396.882183ms","start":"2026-06-19T00:17:47.842921Z","end":"2026-06-19T00:17:48.239803Z","steps":["trace[283198910] 'agreement among raft nodes before linearized reading' (duration: 396.581306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:48.24Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:47.842897Z","time spent":"397.085597ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-19T00:17:48.242752Z","caller":"traceutil/trace.go:171","msg":"trace[823640042] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"339.266245ms","start":"2026-06-19T00:17:47.903455Z","end":"2026-06-19T00:17:48.242721Z","steps":["trace[823640042] 'process raft request' (duration: 337.724729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:48.242876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:47.903422Z","time spent":"339.385288ms","remote":"127.0.0.1:43674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:17:48.243708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.132616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:17:48.243777Z","caller":"traceutil/trace.go:171","msg":"trace[811027667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"256.241869ms","start":"2026-06-19T00:17:47.987519Z","end":"2026-06-19T00:17:48.24376Z","steps":["trace[811027667] 'agreement among raft nodes before linearized reading' (duration: 255.092371ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:54.381999Z","caller":"traceutil/trace.go:171","msg":"trace[963640091] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"110.803911ms","start":"2026-06-19T00:17:54.271175Z","end":"2026-06-19T00:17:54.381979Z","steps":["trace[963640091] 'read index received' (duration: 110.528885ms)","trace[963640091] 'applied index is now lower than readState.Index' (duration: 274.116µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:54.382453Z","caller":"traceutil/trace.go:171","msg":"trace[1391684754] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"209.996779ms","start":"2026-06-19T00:17:54.172417Z","end":"2026-06-19T00:17:54.382414Z","steps":["trace[1391684754] 'process raft request' (duration: 209.380764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:54.38248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.294393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-19T00:17:54.382562Z","caller":"traceutil/trace.go:171","msg":"trace[18595960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"111.429546ms","start":"2026-06-19T00:17:54.271114Z","end":"2026-06-19T00:17:54.382543Z","steps":["trace[18595960] 'agreement among raft nodes before linearized reading' (duration: 111.148249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:54.839131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.950321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:17:54.839416Z","caller":"traceutil/trace.go:171","msg":"trace[1899359546] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"451.523801ms","start":"2026-06-19T00:17:54.387852Z","end":"2026-06-19T00:17:54.839376Z","steps":["trace[1899359546] 'process raft request' (duration: 243.183221ms)","trace[1899359546] 'compare' (duration: 207.838148ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:54.839599Z","caller":"traceutil/trace.go:171","msg":"trace[1840408080] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"451.277525ms","start":"2026-06-19T00:17:54.388308Z","end":"2026-06-19T00:17:54.839585Z","steps":["trace[1840408080] 'process raft request' (duration: 450.905916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:54.839602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:54.387838Z","time spent":"451.644325ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:17:54.839671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:54.388268Z","time spent":"451.375647ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:17:55.366616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.333477ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-19T00:17:55.366713Z","caller":"traceutil/trace.go:171","msg":"trace[1721661156] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"382.651308ms","start":"2026-06-19T00:17:54.984045Z","end":"2026-06-19T00:17:55.366697Z","steps":["trace[1721661156] 'read index received' (duration: 45.412µs)","trace[1721661156] 'applied index is now lower than readState.Index' (duration: 382.604246ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:17:55.366798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.73694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:17:55.366824Z","caller":"traceutil/trace.go:171","msg":"trace[587921412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"382.841052ms","start":"2026-06-19T00:17:54.983975Z","end":"2026-06-19T00:17:55.366816Z","steps":["trace[587921412] 'agreement among raft nodes before linearized reading' (duration: 382.76621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:55.366857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:54.983951Z","time spent":"382.898134ms","remote":"127.0.0.1:43638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T00:17:55.763359Z","caller":"traceutil/trace.go:171","msg":"trace[407552812] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"151.986822ms","start":"2026-06-19T00:17:55.611351Z","end":"2026-06-19T00:17:55.763338Z","steps":["trace[407552812] 'process raft request' (duration: 151.855009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:57.344045Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279595272672533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-19T00:17:57.745914Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.303600893s","expected-duration":"1s"} {"level":"info","ts":"2026-06-19T00:17:57.746293Z","caller":"traceutil/trace.go:171","msg":"trace[1711677854] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"902.744976ms","start":"2026-06-19T00:17:56.84352Z","end":"2026-06-19T00:17:57.746265Z","steps":["trace[1711677854] 'read index received' (duration: 902.578272ms)","trace[1711677854] 'applied index is now lower than readState.Index' (duration: 163.234µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:57.746687Z","caller":"traceutil/trace.go:171","msg":"trace[1690957305] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.304453563s","start":"2026-06-19T00:17:56.442218Z","end":"2026-06-19T00:17:57.746672Z","steps":["trace[1690957305] 'process raft request' (duration: 1.30389204s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:57.746796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:56.442194Z","time spent":"1.304531755s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:17:57.746954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.409481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-19T00:17:57.746993Z","caller":"traceutil/trace.go:171","msg":"trace[1431617167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"903.481744ms","start":"2026-06-19T00:17:56.843496Z","end":"2026-06-19T00:17:57.746978Z","steps":["trace[1431617167] 'agreement among raft nodes before linearized reading' (duration: 903.305069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:57.747027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:56.843484Z","time spent":"903.536775ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-19T00:17:57.747034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.475083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-19T00:17:57.747114Z","caller":"traceutil/trace.go:171","msg":"trace[1145424813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"903.616016ms","start":"2026-06-19T00:17:56.843478Z","end":"2026-06-19T00:17:57.747094Z","steps":["trace[1145424813] 'agreement among raft nodes before linearized reading' (duration: 903.36385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:57.747156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.30884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T00:17:57.747158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:56.843452Z","time spent":"903.697619ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-19T00:17:57.747177Z","caller":"traceutil/trace.go:171","msg":"trace[1875174072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"763.356581ms","start":"2026-06-19T00:17:56.983814Z","end":"2026-06-19T00:17:57.74717Z","steps":["trace[1875174072] 'agreement among raft nodes before linearized reading' (duration: 763.32363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:57.747196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:56.983798Z","time spent":"763.392542ms","remote":"127.0.0.1:43638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T00:17:58.022745Z","caller":"traceutil/trace.go:171","msg":"trace[732729348] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"270.669739ms","start":"2026-06-19T00:17:57.752033Z","end":"2026-06-19T00:17:58.022703Z","steps":["trace[732729348] 'process raft request' (duration: 270.543806ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:58.024471Z","caller":"traceutil/trace.go:171","msg":"trace[1222897956] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"256.333751ms","start":"2026-06-19T00:17:57.768122Z","end":"2026-06-19T00:17:58.024456Z","steps":["trace[1222897956] 'read index received' (duration: 254.400415ms)","trace[1222897956] 'applied index is now lower than readState.Index' (duration: 1.932526ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:58.024572Z","caller":"traceutil/trace.go:171","msg":"trace[1250262277] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"272.360528ms","start":"2026-06-19T00:17:57.752204Z","end":"2026-06-19T00:17:58.024564Z","steps":["trace[1250262277] 'process raft request' (duration: 272.178384ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:58.02489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.753421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-19T00:17:58.024942Z","caller":"traceutil/trace.go:171","msg":"trace[466688882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:611; }","duration":"256.898924ms","start":"2026-06-19T00:17:57.768032Z","end":"2026-06-19T00:17:58.02493Z","steps":["trace[466688882] 'agreement among raft nodes before linearized reading' (duration: 256.71583ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:17:59.765309Z","caller":"traceutil/trace.go:171","msg":"trace[1570189807] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"173.750424ms","start":"2026-06-19T00:17:59.591542Z","end":"2026-06-19T00:17:59.765292Z","steps":["trace[1570189807] 'read index received' (duration: 173.645722ms)","trace[1570189807] 'applied index is now lower than readState.Index' (duration: 104.002µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:17:59.765374Z","caller":"traceutil/trace.go:171","msg":"trace[1923558089] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"369.377206ms","start":"2026-06-19T00:17:59.395964Z","end":"2026-06-19T00:17:59.765341Z","steps":["trace[1923558089] 'process raft request' (duration: 369.218992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:59.765415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.857768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:17:59.765438Z","caller":"traceutil/trace.go:171","msg":"trace[1755768117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"173.95126ms","start":"2026-06-19T00:17:59.59148Z","end":"2026-06-19T00:17:59.765431Z","steps":["trace[1755768117] 'agreement among raft nodes before linearized reading' (duration: 173.895318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:17:59.765546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:17:59.395943Z","time spent":"369.497958ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:17:59.765637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.286935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-19T00:17:59.765695Z","caller":"traceutil/trace.go:171","msg":"trace[283962298] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:624; }","duration":"137.366498ms","start":"2026-06-19T00:17:59.628315Z","end":"2026-06-19T00:17:59.765681Z","steps":["trace[283962298] 'agreement among raft nodes before linearized reading' (duration: 137.231894ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:18:00.352365Z","caller":"traceutil/trace.go:171","msg":"trace[1681052465] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"150.394815ms","start":"2026-06-19T00:18:00.20194Z","end":"2026-06-19T00:18:00.352335Z","steps":["trace[1681052465] 'process raft request' (duration: 137.558632ms)","trace[1681052465] 'compare' (duration: 12.635058ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:18:00.500547Z","caller":"traceutil/trace.go:171","msg":"trace[2045247077] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"101.619965ms","start":"2026-06-19T00:18:00.398901Z","end":"2026-06-19T00:18:00.500521Z","steps":["trace[2045247077] 'read index received' (duration: 69.427596ms)","trace[2045247077] 'applied index is now lower than readState.Index' (duration: 32.191419ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:18:00.500702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.790119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:18:00.500737Z","caller":"traceutil/trace.go:171","msg":"trace[1074345621] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:648; }","duration":"101.878231ms","start":"2026-06-19T00:18:00.398847Z","end":"2026-06-19T00:18:00.500725Z","steps":["trace[1074345621] 'agreement among raft nodes before linearized reading' (duration: 101.771548ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:18:00.500856Z","caller":"traceutil/trace.go:171","msg":"trace[389009937] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"138.203797ms","start":"2026-06-19T00:18:00.362605Z","end":"2026-06-19T00:18:00.500809Z","steps":["trace[389009937] 'process raft request' (duration: 105.783853ms)","trace[389009937] 'compare' (duration: 31.995734ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:18:02.58155Z","caller":"traceutil/trace.go:171","msg":"trace[724861365] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"180.541815ms","start":"2026-06-19T00:18:02.400983Z","end":"2026-06-19T00:18:02.581525Z","steps":["trace[724861365] 'process raft request' (duration: 180.422993ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:18:03.326202Z","caller":"traceutil/trace.go:171","msg":"trace[1420515739] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"296.61382ms","start":"2026-06-19T00:18:03.029563Z","end":"2026-06-19T00:18:03.326177Z","steps":["trace[1420515739] 'process raft request' (duration: 296.446246ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:18:03.373561Z","caller":"traceutil/trace.go:171","msg":"trace[1862088] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:703; }","duration":"174.927022ms","start":"2026-06-19T00:18:03.19862Z","end":"2026-06-19T00:18:03.373547Z","steps":["trace[1862088] 'read index received' (duration: 127.255609ms)","trace[1862088] 'applied index is now lower than readState.Index' (duration: 47.670893ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:18:03.373651Z","caller":"traceutil/trace.go:171","msg":"trace[364269982] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"232.947801ms","start":"2026-06-19T00:18:03.140697Z","end":"2026-06-19T00:18:03.373645Z","steps":["trace[364269982] 'process raft request' (duration: 225.846563ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:18:03.373753Z","caller":"traceutil/trace.go:171","msg":"trace[1095732414] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"145.160321ms","start":"2026-06-19T00:18:03.228588Z","end":"2026-06-19T00:18:03.373748Z","steps":["trace[1095732414] 'process raft request' (duration: 144.928115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:03.373961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.333208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pbt87\" ","response":"range_response_count:1 size:18333"} {"level":"info","ts":"2026-06-19T00:18:03.374015Z","caller":"traceutil/trace.go:171","msg":"trace[250840519] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pbt87; range_end:; response_count:1; response_revision:683; }","duration":"146.447401ms","start":"2026-06-19T00:18:03.227555Z","end":"2026-06-19T00:18:03.374003Z","steps":["trace[250840519] 'agreement among raft nodes before linearized reading' (duration: 146.298817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:03.374092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.467196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-06-19T00:18:03.374125Z","caller":"traceutil/trace.go:171","msg":"trace[1925452586] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"175.540548ms","start":"2026-06-19T00:18:03.198574Z","end":"2026-06-19T00:18:03.374115Z","steps":["trace[1925452586] 'agreement among raft nodes before linearized reading' (duration: 175.336033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:03.592772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.043798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:18:03.592921Z","caller":"traceutil/trace.go:171","msg":"trace[278429028] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"172.447044ms","start":"2026-06-19T00:18:03.420457Z","end":"2026-06-19T00:18:03.592904Z","steps":["trace[278429028] 'process raft request' (duration: 58.196371ms)","trace[278429028] 'compare' (duration: 113.834163ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:18:03.642184Z","caller":"traceutil/trace.go:171","msg":"trace[1325715527] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"216.788549ms","start":"2026-06-19T00:18:03.425374Z","end":"2026-06-19T00:18:03.642162Z","steps":["trace[1325715527] 'process raft request' (duration: 216.597495ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:18:03.642387Z","caller":"traceutil/trace.go:171","msg":"trace[1991661209] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"211.125496ms","start":"2026-06-19T00:18:03.431146Z","end":"2026-06-19T00:18:03.642271Z","steps":["trace[1991661209] 'process raft request' (duration: 210.963732ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:18:03.828242Z","caller":"traceutil/trace.go:171","msg":"trace[537885806] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"177.918883ms","start":"2026-06-19T00:18:03.650287Z","end":"2026-06-19T00:18:03.828206Z","steps":["trace[537885806] 'process raft request' (duration: 103.204662ms)","trace[537885806] 'compare' (duration: 74.526646ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:18:03.831525Z","caller":"traceutil/trace.go:171","msg":"trace[1372785292] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"160.797209ms","start":"2026-06-19T00:18:03.670708Z","end":"2026-06-19T00:18:03.831505Z","steps":["trace[1372785292] 'process raft request' (duration: 159.98113ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:18:03.831874Z","caller":"traceutil/trace.go:171","msg":"trace[1514902295] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"120.793926ms","start":"2026-06-19T00:18:03.711057Z","end":"2026-06-19T00:18:03.831851Z","steps":["trace[1514902295] 'process raft request' (duration: 119.873815ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:18:04.072882Z","caller":"traceutil/trace.go:171","msg":"trace[1310088272] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"233.425132ms","start":"2026-06-19T00:18:03.839434Z","end":"2026-06-19T00:18:04.07286Z","steps":["trace[1310088272] 'process raft request' (duration: 172.866964ms)","trace[1310088272] 'compare' (duration: 60.427165ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:18:04.131168Z","caller":"traceutil/trace.go:171","msg":"trace[1037280995] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:714; }","duration":"147.67118ms","start":"2026-06-19T00:18:03.983475Z","end":"2026-06-19T00:18:04.131146Z","steps":["trace[1037280995] 'read index received' (duration: 28.830959ms)","trace[1037280995] 'applied index is now lower than readState.Index' (duration: 118.839321ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:18:04.131283Z","caller":"traceutil/trace.go:171","msg":"trace[95860417] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"162.049569ms","start":"2026-06-19T00:18:03.969223Z","end":"2026-06-19T00:18:04.131273Z","steps":["trace[95860417] 'process raft request' (duration: 161.682421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:04.131595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.041959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:18:04.131663Z","caller":"traceutil/trace.go:171","msg":"trace[264664306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"148.212653ms","start":"2026-06-19T00:18:03.983435Z","end":"2026-06-19T00:18:04.131647Z","steps":["trace[264664306] 'agreement among raft nodes before linearized reading' (duration: 148.004518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:04.131719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.421434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-06-19T00:18:04.131812Z","caller":"traceutil/trace.go:171","msg":"trace[239579453] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:693; }","duration":"133.569738ms","start":"2026-06-19T00:18:03.998204Z","end":"2026-06-19T00:18:04.131774Z","steps":["trace[239579453] 'agreement among raft nodes before linearized reading' (duration: 133.23973ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:18:10.01858Z","caller":"traceutil/trace.go:171","msg":"trace[759849107] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"189.771842ms","start":"2026-06-19T00:18:09.828786Z","end":"2026-06-19T00:18:10.018558Z","steps":["trace[759849107] 'process raft request' (duration: 189.193249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:13.77888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.448644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:18:13.778958Z","caller":"traceutil/trace.go:171","msg":"trace[906330396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"794.571076ms","start":"2026-06-19T00:18:12.984369Z","end":"2026-06-19T00:18:13.77894Z","steps":["trace[906330396] 'range keys from in-memory index tree' (duration: 794.383472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:13.778955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549514408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-19T00:18:13.778999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:18:12.984351Z","time spent":"794.639728ms","remote":"127.0.0.1:43638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T00:18:13.779035Z","caller":"traceutil/trace.go:171","msg":"trace[2019752624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"1.549645351s","start":"2026-06-19T00:18:12.229366Z","end":"2026-06-19T00:18:13.779012Z","steps":["trace[2019752624] 'range keys from in-memory index tree' (duration: 1.549345453s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:13.779076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:18:12.229343Z","time spent":"1.549723683s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-19T00:18:13.779671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547096681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-19T00:18:13.779702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546667341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-19T00:18:13.779714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514131824s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pq6vw\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-06-19T00:18:13.779728Z","caller":"traceutil/trace.go:171","msg":"trace[860306000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"1.547202703s","start":"2026-06-19T00:18:12.232512Z","end":"2026-06-19T00:18:13.779715Z","steps":["trace[860306000] 'range keys from in-memory index tree' (duration: 1.546927557s)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:18:13.779757Z","caller":"traceutil/trace.go:171","msg":"trace[1970436010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"1.546741582s","start":"2026-06-19T00:18:12.233003Z","end":"2026-06-19T00:18:13.779745Z","steps":["trace[1970436010] 'range keys from in-memory index tree' (duration: 1.546579069s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:13.779767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:18:12.232491Z","time spent":"1.547269684s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-19T00:18:13.779767Z","caller":"traceutil/trace.go:171","msg":"trace[1951672608] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pq6vw; range_end:; response_count:1; response_revision:760; }","duration":"1.514235156s","start":"2026-06-19T00:18:12.265518Z","end":"2026-06-19T00:18:13.779753Z","steps":["trace[1951672608] 'range keys from in-memory index tree' (duration: 1.51399108s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:13.779785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:18:12.232993Z","time spent":"1.546786143s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-19T00:18:13.779803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:18:12.265511Z","time spent":"1.514283397s","remote":"127.0.0.1:36632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-pq6vw\" "} {"level":"info","ts":"2026-06-19T00:18:13.806145Z","caller":"traceutil/trace.go:171","msg":"trace[2095405779] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"639.560783ms","start":"2026-06-19T00:18:13.166567Z","end":"2026-06-19T00:18:13.806128Z","steps":["trace[2095405779] 'process raft request' (duration: 639.369388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:13.806398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:18:13.166549Z","time spent":"639.659006ms","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5051,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:18:13.813284Z","caller":"traceutil/trace.go:171","msg":"trace[1583723070] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"339.111643ms","start":"2026-06-19T00:18:13.474148Z","end":"2026-06-19T00:18:13.81326Z","steps":["trace[1583723070] 'process raft request' (duration: 338.938988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:13.813429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:18:13.474123Z","time spent":"339.230245ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:18:42.707109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.533957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:18:42.707233Z","caller":"traceutil/trace.go:171","msg":"trace[1289509656] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"249.698114ms","start":"2026-06-19T00:18:42.457516Z","end":"2026-06-19T00:18:42.707214Z","steps":["trace[1289509656] 'process raft request' (duration: 38.972734ms)","trace[1289509656] 'compare' (duration: 210.223539ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:18:48.274055Z","caller":"traceutil/trace.go:171","msg":"trace[1650333656] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"262.671621ms","start":"2026-06-19T00:18:48.011363Z","end":"2026-06-19T00:18:48.274034Z","steps":["trace[1650333656] 'read index received' (duration: 262.550168ms)","trace[1650333656] 'applied index is now lower than readState.Index' (duration: 120.513µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:18:48.274327Z","caller":"traceutil/trace.go:171","msg":"trace[327827494] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"271.49817ms","start":"2026-06-19T00:18:48.002805Z","end":"2026-06-19T00:18:48.274303Z","steps":["trace[327827494] 'process raft request' (duration: 271.03868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:48.274415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.03314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-19T00:18:48.274454Z","caller":"traceutil/trace.go:171","msg":"trace[8533456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"263.098502ms","start":"2026-06-19T00:18:48.011345Z","end":"2026-06-19T00:18:48.274444Z","steps":["trace[8533456] 'agreement among raft nodes before linearized reading' (duration: 262.962709ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:48.274445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.735096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-19T00:18:48.274513Z","caller":"traceutil/trace.go:171","msg":"trace[257273537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"187.857329ms","start":"2026-06-19T00:18:48.086639Z","end":"2026-06-19T00:18:48.274496Z","steps":["trace[257273537] 'agreement among raft nodes before linearized reading' (duration: 187.627094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:49.485321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279595272673355,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-19T00:18:49.985667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279595272673355,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-19T00:18:50.486453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279595272673355,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-19T00:18:50.576243Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.148490507s","expected-duration":"1s"} {"level":"info","ts":"2026-06-19T00:18:50.576509Z","caller":"traceutil/trace.go:171","msg":"trace[1230484279] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"2.148840785s","start":"2026-06-19T00:18:48.427648Z","end":"2026-06-19T00:18:50.576489Z","steps":["trace[1230484279] 'process raft request' (duration: 2.148706632s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:50.576615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:18:48.427626Z","time spent":"2.148934748s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:18:50.590017Z","caller":"traceutil/trace.go:171","msg":"trace[518448330] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.861612513s","start":"2026-06-19T00:18:48.728385Z","end":"2026-06-19T00:18:50.589998Z","steps":["trace[518448330] 'process raft request' (duration: 1.86148585s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:50.590134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:18:48.728367Z","time spent":"1.861691535s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:18:50.590163Z","caller":"traceutil/trace.go:171","msg":"trace[1296992609] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"1.605873676s","start":"2026-06-19T00:18:48.984265Z","end":"2026-06-19T00:18:50.590138Z","steps":["trace[1296992609] 'read index received' (duration: 1.592262114s)","trace[1296992609] 'applied index is now lower than readState.Index' (duration: 13.610332ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:18:50.590442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.328102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-19T00:18:50.590472Z","caller":"traceutil/trace.go:171","msg":"trace[559075694] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:979; }","duration":"959.389084ms","start":"2026-06-19T00:18:49.631075Z","end":"2026-06-19T00:18:50.590464Z","steps":["trace[559075694] 'agreement among raft nodes before linearized reading' (duration: 959.273881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:50.590493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:18:49.631057Z","time spent":"959.431824ms","remote":"127.0.0.1:43664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-06-19T00:18:50.590611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.174184ms","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-06-19T00:18:50.590635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.839933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-19T00:18:50.590648Z","caller":"traceutil/trace.go:171","msg":"trace[523020272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"217.246376ms","start":"2026-06-19T00:18:50.373391Z","end":"2026-06-19T00:18:50.590638Z","steps":["trace[523020272] 'agreement among raft nodes before linearized reading' (duration: 217.102662ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:18:50.590655Z","caller":"traceutil/trace.go:171","msg":"trace[1701972883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"234.873133ms","start":"2026-06-19T00:18:50.355776Z","end":"2026-06-19T00:18:50.590649Z","steps":["trace[1701972883] 'agreement among raft nodes before linearized reading' (duration: 234.812212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:50.59081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.123709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-19T00:18:50.590837Z","caller":"traceutil/trace.go:171","msg":"trace[120871430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:979; }","duration":"235.17671ms","start":"2026-06-19T00:18:50.355652Z","end":"2026-06-19T00:18:50.590829Z","steps":["trace[120871430] 'agreement among raft nodes before linearized reading' (duration: 235.121149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:50.590871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.606613443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:18:50.590908Z","caller":"traceutil/trace.go:171","msg":"trace[210160052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"1.606674235s","start":"2026-06-19T00:18:48.984225Z","end":"2026-06-19T00:18:50.590899Z","steps":["trace[210160052] 'agreement among raft nodes before linearized reading' (duration: 1.606621154s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:50.590939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:18:48.984209Z","time spent":"1.606722946s","remote":"127.0.0.1:43638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-19T00:18:50.590979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.468395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T00:18:50.591004Z","caller":"traceutil/trace.go:171","msg":"trace[1038851728] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:979; }","duration":"617.518106ms","start":"2026-06-19T00:18:49.973478Z","end":"2026-06-19T00:18:50.590997Z","steps":["trace[1038851728] 'agreement among raft nodes before linearized reading' (duration: 617.477815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:50.591023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:18:49.973464Z","time spent":"617.553137ms","remote":"127.0.0.1:36862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-06-19T00:18:50.590945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.643871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-19T00:18:50.591247Z","caller":"traceutil/trace.go:171","msg":"trace[1514629313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"311.968579ms","start":"2026-06-19T00:18:50.279267Z","end":"2026-06-19T00:18:50.591236Z","steps":["trace[1514629313] 'agreement among raft nodes before linearized reading' (duration: 311.643051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:18:50.591287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:18:50.279245Z","time spent":"312.028461ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-19T00:18:59.955127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.92314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:18:59.957255Z","caller":"traceutil/trace.go:171","msg":"trace[1394061866] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"281.213411ms","start":"2026-06-19T00:18:59.675995Z","end":"2026-06-19T00:18:59.957208Z","steps":["trace[1394061866] 'process raft request' (duration: 75.110099ms)","trace[1394061866] 'compare' (duration: 203.340287ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:19:11.995517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.826488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:19:11.995595Z","caller":"traceutil/trace.go:171","msg":"trace[1745570681] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1050; }","duration":"119.956651ms","start":"2026-06-19T00:19:11.875622Z","end":"2026-06-19T00:19:11.995579Z","steps":["trace[1745570681] 'count revisions from in-memory index tree' (duration: 119.748296ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:12.968002Z","caller":"traceutil/trace.go:171","msg":"trace[1060071538] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"254.433347ms","start":"2026-06-19T00:19:12.713541Z","end":"2026-06-19T00:19:12.967974Z","steps":["trace[1060071538] 'process raft request' (duration: 254.248402ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:13.051884Z","caller":"traceutil/trace.go:171","msg":"trace[1207636872] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1091; }","duration":"234.488884ms","start":"2026-06-19T00:19:12.817366Z","end":"2026-06-19T00:19:13.051855Z","steps":["trace[1207636872] 'read index received' (duration: 150.530625ms)","trace[1207636872] 'applied index is now lower than readState.Index' (duration: 83.956929ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:19:13.052137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.010886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-19T00:19:13.05214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.702973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-19T00:19:13.052174Z","caller":"traceutil/trace.go:171","msg":"trace[86178197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1052; }","duration":"194.095497ms","start":"2026-06-19T00:19:12.858065Z","end":"2026-06-19T00:19:13.052161Z","steps":["trace[86178197] 'agreement among raft nodes before linearized reading' (duration: 193.942583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:13.052158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.760751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-19T00:19:13.052209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.726349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-19T00:19:13.052235Z","caller":"traceutil/trace.go:171","msg":"trace[378503147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1052; }","duration":"234.895393ms","start":"2026-06-19T00:19:12.817322Z","end":"2026-06-19T00:19:13.052218Z","steps":["trace[378503147] 'agreement among raft nodes before linearized reading' (duration: 234.630127ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:13.052222Z","caller":"traceutil/trace.go:171","msg":"trace[231429669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1052; }","duration":"197.829666ms","start":"2026-06-19T00:19:12.854367Z","end":"2026-06-19T00:19:13.052197Z","steps":["trace[231429669] 'agreement among raft nodes before linearized reading' (duration: 197.638371ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:13.052281Z","caller":"traceutil/trace.go:171","msg":"trace[1395321725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"196.834792ms","start":"2026-06-19T00:19:12.85543Z","end":"2026-06-19T00:19:13.052265Z","steps":["trace[1395321725] 'agreement among raft nodes before linearized reading' (duration: 196.620747ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:13.05198Z","caller":"traceutil/trace.go:171","msg":"trace[1300393366] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"338.064078ms","start":"2026-06-19T00:19:12.713874Z","end":"2026-06-19T00:19:13.051938Z","steps":["trace[1300393366] 'process raft request' (duration: 292.508428ms)","trace[1300393366] 'compare' (duration: 45.305024ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:19:13.052639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:12.713851Z","time spent":"338.720243ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:19:15.179636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.92462ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-19T00:19:15.179912Z","caller":"traceutil/trace.go:171","msg":"trace[395622359] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1098; }","duration":"197.59242ms","start":"2026-06-19T00:19:14.982298Z","end":"2026-06-19T00:19:15.179891Z","steps":["trace[395622359] 'read index received' (duration: 58.406213ms)","trace[395622359] 'applied index is now lower than readState.Index' (duration: 139.185027ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:19:15.180019Z","caller":"traceutil/trace.go:171","msg":"trace[1505167814] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"206.01068ms","start":"2026-06-19T00:19:14.973994Z","end":"2026-06-19T00:19:15.180005Z","steps":["trace[1505167814] 'process raft request' (duration: 205.759254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:15.180215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.144536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-19T00:19:15.180266Z","caller":"traceutil/trace.go:171","msg":"trace[1776618804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1058; }","duration":"120.227658ms","start":"2026-06-19T00:19:15.060026Z","end":"2026-06-19T00:19:15.180254Z","steps":["trace[1776618804] 'agreement among raft nodes before linearized reading' (duration: 120.082274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:15.180372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.078182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:19:15.180404Z","caller":"traceutil/trace.go:171","msg":"trace[1597498476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"198.141243ms","start":"2026-06-19T00:19:14.982255Z","end":"2026-06-19T00:19:15.180396Z","steps":["trace[1597498476] 'agreement among raft nodes before linearized reading' (duration: 198.098462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:15.311786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.383732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:19:15.311987Z","caller":"traceutil/trace.go:171","msg":"trace[381698822] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"127.429688ms","start":"2026-06-19T00:19:15.184533Z","end":"2026-06-19T00:19:15.311963Z","steps":["trace[381698822] 'process raft request' (duration: 14.77156ms)","trace[381698822] 'compare' (duration: 112.149286ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:19:15.312372Z","caller":"traceutil/trace.go:171","msg":"trace[1559676288] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"126.942576ms","start":"2026-06-19T00:19:15.185412Z","end":"2026-06-19T00:19:15.312355Z","steps":["trace[1559676288] 'process raft request' (duration: 126.485466ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:15.312411Z","caller":"traceutil/trace.go:171","msg":"trace[1081121636] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"126.631979ms","start":"2026-06-19T00:19:15.185758Z","end":"2026-06-19T00:19:15.31239Z","steps":["trace[1081121636] 'process raft request' (duration: 126.595698ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:15.312413Z","caller":"traceutil/trace.go:171","msg":"trace[127868268] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"126.895655ms","start":"2026-06-19T00:19:15.185485Z","end":"2026-06-19T00:19:15.312381Z","steps":["trace[127868268] 'process raft request' (duration: 126.745612ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:15.312498Z","caller":"traceutil/trace.go:171","msg":"trace[809267223] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"126.949606ms","start":"2026-06-19T00:19:15.185526Z","end":"2026-06-19T00:19:15.312476Z","steps":["trace[809267223] 'process raft request' (duration: 126.795913ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:17.429853Z","caller":"traceutil/trace.go:171","msg":"trace[1903039227] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"108.175973ms","start":"2026-06-19T00:19:17.321654Z","end":"2026-06-19T00:19:17.42983Z","steps":["trace[1903039227] 'process raft request' (duration: 108.134782ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:17.43007Z","caller":"traceutil/trace.go:171","msg":"trace[684557458] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"108.752016ms","start":"2026-06-19T00:19:17.321302Z","end":"2026-06-19T00:19:17.430054Z","steps":["trace[684557458] 'process raft request' (duration: 108.416668ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:17.430141Z","caller":"traceutil/trace.go:171","msg":"trace[1782236210] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"108.720026ms","start":"2026-06-19T00:19:17.321409Z","end":"2026-06-19T00:19:17.430129Z","steps":["trace[1782236210] 'process raft request' (duration: 108.349887ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:17.430216Z","caller":"traceutil/trace.go:171","msg":"trace[1460528091] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"108.998932ms","start":"2026-06-19T00:19:17.321206Z","end":"2026-06-19T00:19:17.430205Z","steps":["trace[1460528091] 'process raft request' (duration: 56.57357ms)","trace[1460528091] 'compare' (duration: 51.788466ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:19:35.76988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.565993ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-19T00:19:35.770382Z","caller":"traceutil/trace.go:171","msg":"trace[1656379084] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"478.961031ms","start":"2026-06-19T00:19:35.291313Z","end":"2026-06-19T00:19:35.770274Z","steps":["trace[1656379084] 'process raft request' (duration: 478.681774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:35.770517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:35.291287Z","time spent":"479.150265ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:19:35.809606Z","caller":"traceutil/trace.go:171","msg":"trace[1035465132] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1220; }","duration":"249.353094ms","start":"2026-06-19T00:19:35.560215Z","end":"2026-06-19T00:19:35.809568Z","steps":["trace[1035465132] 'read index received' (duration: 56.692422ms)","trace[1035465132] 'applied index is now lower than readState.Index' (duration: 192.659602ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:19:35.809646Z","caller":"traceutil/trace.go:171","msg":"trace[513413581] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"401.116117ms","start":"2026-06-19T00:19:35.4085Z","end":"2026-06-19T00:19:35.809616Z","steps":["trace[513413581] 'process raft request' (duration: 376.508525ms)","trace[513413581] 'compare' (duration: 24.442399ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:19:35.809806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:35.408476Z","time spent":"401.241171ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:19:35.809843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.6065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-19T00:19:35.809884Z","caller":"traceutil/trace.go:171","msg":"trace[1864630764] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1175; }","duration":"249.697942ms","start":"2026-06-19T00:19:35.560172Z","end":"2026-06-19T00:19:35.80987Z","steps":["trace[1864630764] 'agreement among raft nodes before linearized reading' (duration: 249.541939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:38.319345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279595272673830,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-19T00:19:38.756541Z","caller":"traceutil/trace.go:171","msg":"trace[1010379881] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"940.946528ms","start":"2026-06-19T00:19:37.815572Z","end":"2026-06-19T00:19:38.756519Z","steps":["trace[1010379881] 'process raft request' (duration: 940.810855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:38.756684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:37.815556Z","time spent":"941.054481ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:19:38.757083Z","caller":"traceutil/trace.go:171","msg":"trace[715649714] linearizableReadLoop","detail":"{readStateIndex:1229; appliedIndex:1229; }","duration":"938.717246ms","start":"2026-06-19T00:19:37.818352Z","end":"2026-06-19T00:19:38.757069Z","steps":["trace[715649714] 'read index received' (duration: 938.708856ms)","trace[715649714] 'applied index is now lower than readState.Index' (duration: 4.95µs)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:19:38.757312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.960262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-19T00:19:38.75735Z","caller":"traceutil/trace.go:171","msg":"trace[20847374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1181; }","duration":"939.038734ms","start":"2026-06-19T00:19:37.818302Z","end":"2026-06-19T00:19:38.757341Z","steps":["trace[20847374] 'agreement among raft nodes before linearized reading' (duration: 938.87892ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:38.75738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:37.818282Z","time spent":"939.091414ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-19T00:19:38.757816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.863216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-19T00:19:38.757891Z","caller":"traceutil/trace.go:171","msg":"trace[328693960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1181; }","duration":"929.957508ms","start":"2026-06-19T00:19:37.827915Z","end":"2026-06-19T00:19:38.757873Z","steps":["trace[328693960] 'agreement among raft nodes before linearized reading' (duration: 929.783274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:38.75793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:37.827911Z","time spent":"930.011929ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-19T00:19:38.757949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.03123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-19T00:19:38.757987Z","caller":"traceutil/trace.go:171","msg":"trace[624682537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1181; }","duration":"930.094902ms","start":"2026-06-19T00:19:37.827878Z","end":"2026-06-19T00:19:38.757973Z","steps":["trace[624682537] 'agreement among raft nodes before linearized reading' (duration: 929.979119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:38.758034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:37.827861Z","time spent":"930.165953ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-19T00:19:38.758132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.105099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:19:38.758171Z","caller":"traceutil/trace.go:171","msg":"trace[2063335731] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1181; }","duration":"603.192011ms","start":"2026-06-19T00:19:38.154968Z","end":"2026-06-19T00:19:38.75816Z","steps":["trace[2063335731] 'agreement among raft nodes before linearized reading' (duration: 603.120019ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:38.758205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:38.15495Z","time spent":"603.247793ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-06-19T00:19:38.758229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.324457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-19T00:19:38.758264Z","caller":"traceutil/trace.go:171","msg":"trace[1155203245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1181; }","duration":"930.380738ms","start":"2026-06-19T00:19:37.827874Z","end":"2026-06-19T00:19:38.758255Z","steps":["trace[1155203245] 'agreement among raft nodes before linearized reading' (duration: 930.298966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:38.75829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:37.827863Z","time spent":"930.420669ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-19T00:19:38.757816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.914554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-19T00:19:38.758349Z","caller":"traceutil/trace.go:171","msg":"trace[1797105515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1181; }","duration":"932.479028ms","start":"2026-06-19T00:19:37.825861Z","end":"2026-06-19T00:19:38.75834Z","steps":["trace[1797105515] 'agreement among raft nodes before linearized reading' (duration: 931.808222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:38.758384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:37.825854Z","time spent":"932.521759ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-19T00:19:39.482567Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279595272673833,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-19T00:19:39.789479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.859671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:19:39.789619Z","caller":"traceutil/trace.go:171","msg":"trace[1120591065] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"1.026624278s","start":"2026-06-19T00:19:38.762969Z","end":"2026-06-19T00:19:39.789593Z","steps":["trace[1120591065] 'process raft request' (duration: 881.541053ms)","trace[1120591065] 'compare' (duration: 144.686406ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:19:39.789704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:38.762947Z","time spent":"1.026713771s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:19:39.835179Z","caller":"traceutil/trace.go:171","msg":"trace[1762657686] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"1.072087685s","start":"2026-06-19T00:19:38.763062Z","end":"2026-06-19T00:19:39.835149Z","steps":["trace[1762657686] 'process raft request' (duration: 1.071376928s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:39.835324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:38.763048Z","time spent":"1.072198107s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:19:39.835522Z","caller":"traceutil/trace.go:171","msg":"trace[309386263] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"1.072370741s","start":"2026-06-19T00:19:38.763128Z","end":"2026-06-19T00:19:39.835499Z","steps":["trace[309386263] 'process raft request' (duration: 1.071956221s)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:39.835552Z","caller":"traceutil/trace.go:171","msg":"trace[1982772568] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"1.072389211s","start":"2026-06-19T00:19:38.763137Z","end":"2026-06-19T00:19:39.835526Z","steps":["trace[1982772568] 'process raft request' (duration: 1.072270369s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:39.835648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:38.763119Z","time spent":"1.072462124s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:19:39.835692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:38.763129Z","time spent":"1.072487074s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:19:39.835768Z","caller":"traceutil/trace.go:171","msg":"trace[617822186] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"1.071291936s","start":"2026-06-19T00:19:38.764447Z","end":"2026-06-19T00:19:39.835739Z","steps":["trace[617822186] 'process raft request' (duration: 1.0710385s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:39.836601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:38.764439Z","time spent":"1.072007202s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:19:39.836898Z","caller":"traceutil/trace.go:171","msg":"trace[1659227775] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1229; }","duration":"855.053405ms","start":"2026-06-19T00:19:38.981825Z","end":"2026-06-19T00:19:39.836879Z","steps":["trace[1659227775] 'read index received' (duration: 662.764673ms)","trace[1659227775] 'applied index is now lower than readState.Index' (duration: 192.286182ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:19:39.837038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.342737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-19T00:19:39.83703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.195199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:19:39.837067Z","caller":"traceutil/trace.go:171","msg":"trace[1337561985] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1186; }","duration":"220.408879ms","start":"2026-06-19T00:19:39.616648Z","end":"2026-06-19T00:19:39.837057Z","steps":["trace[1337561985] 'agreement among raft nodes before linearized reading' (duration: 220.342437ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:39.837076Z","caller":"traceutil/trace.go:171","msg":"trace[269535719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1186; }","duration":"855.265951ms","start":"2026-06-19T00:19:38.981798Z","end":"2026-06-19T00:19:39.837064Z","steps":["trace[269535719] 'agreement among raft nodes before linearized reading' (duration: 855.172069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:39.8371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:38.981784Z","time spent":"855.310052ms","remote":"127.0.0.1:43638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-19T00:19:39.83733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.199008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-19T00:19:39.837362Z","caller":"traceutil/trace.go:171","msg":"trace[866658813] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:1186; }","duration":"202.27313ms","start":"2026-06-19T00:19:39.635079Z","end":"2026-06-19T00:19:39.837352Z","steps":["trace[866658813] 'agreement among raft nodes before linearized reading' (duration: 202.155787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:44.913536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.587365ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-19T00:19:44.914128Z","caller":"traceutil/trace.go:171","msg":"trace[1748647096] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"124.962338ms","start":"2026-06-19T00:19:44.789146Z","end":"2026-06-19T00:19:44.914109Z","steps":["trace[1748647096] 'process raft request' (duration: 124.838095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:46.081771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.657911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:19:46.082005Z","caller":"traceutil/trace.go:171","msg":"trace[550380049] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"206.15006ms","start":"2026-06-19T00:19:45.875844Z","end":"2026-06-19T00:19:46.081994Z","steps":["trace[550380049] 'process raft request' (duration: 206.089099ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:46.08203Z","caller":"traceutil/trace.go:171","msg":"trace[1438070339] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"207.596045ms","start":"2026-06-19T00:19:45.874414Z","end":"2026-06-19T00:19:46.08201Z","steps":["trace[1438070339] 'process raft request' (duration: 93.638706ms)","trace[1438070339] 'compare' (duration: 113.510938ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:19:46.082112Z","caller":"traceutil/trace.go:171","msg":"trace[583209289] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"206.181351ms","start":"2026-06-19T00:19:45.875926Z","end":"2026-06-19T00:19:46.082107Z","steps":["trace[583209289] 'process raft request' (duration: 206.051388ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:46.082225Z","caller":"traceutil/trace.go:171","msg":"trace[1627732105] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"205.767411ms","start":"2026-06-19T00:19:45.876425Z","end":"2026-06-19T00:19:46.082192Z","steps":["trace[1627732105] 'process raft request' (duration: 205.664518ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:56.080345Z","caller":"traceutil/trace.go:171","msg":"trace[525067287] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"169.030742ms","start":"2026-06-19T00:19:55.911289Z","end":"2026-06-19T00:19:56.08032Z","steps":["trace[525067287] 'process raft request' (duration: 168.873928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:56.632889Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279595272674006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-19T00:19:57.099375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.594758ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:19:57.099469Z","caller":"traceutil/trace.go:171","msg":"trace[1107004320] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1255; }","duration":"216.698221ms","start":"2026-06-19T00:19:56.882759Z","end":"2026-06-19T00:19:57.099457Z","steps":["trace[1107004320] 'range keys from in-memory index tree' (duration: 216.582298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:57.129073Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.004156995s","expected-duration":"1s"} {"level":"info","ts":"2026-06-19T00:19:57.129521Z","caller":"traceutil/trace.go:171","msg":"trace[1383738057] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"1.004741849s","start":"2026-06-19T00:19:56.12475Z","end":"2026-06-19T00:19:57.129492Z","steps":["trace[1383738057] 'process raft request' (duration: 1.004532724s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:57.129691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:56.12473Z","time spent":"1.004866902s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:19:57.168348Z","caller":"traceutil/trace.go:171","msg":"trace[1445596982] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"1.042181515s","start":"2026-06-19T00:19:56.126148Z","end":"2026-06-19T00:19:57.16833Z","steps":["trace[1445596982] 'process raft request' (duration: 1.042113204s)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:57.168393Z","caller":"traceutil/trace.go:171","msg":"trace[1485086363] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"1.043355733s","start":"2026-06-19T00:19:56.125Z","end":"2026-06-19T00:19:57.168356Z","steps":["trace[1485086363] 'process raft request' (duration: 1.043159938s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:57.16846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:56.126135Z","time spent":"1.042265427s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:19:57.168579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:56.124984Z","time spent":"1.043477687s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:19:57.168692Z","caller":"traceutil/trace.go:171","msg":"trace[175024950] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"168.283525ms","start":"2026-06-19T00:19:57.000395Z","end":"2026-06-19T00:19:57.168679Z","steps":["trace[175024950] 'process raft request' (duration: 168.157152ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:57.168726Z","caller":"traceutil/trace.go:171","msg":"trace[1562910172] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1307; }","duration":"1.036694595s","start":"2026-06-19T00:19:56.131962Z","end":"2026-06-19T00:19:57.168657Z","steps":["trace[1562910172] 'read index received' (duration: 997.364884ms)","trace[1562910172] 'applied index is now lower than readState.Index' (duration: 39.328901ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:19:57.168931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036962572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-19T00:19:57.168961Z","caller":"traceutil/trace.go:171","msg":"trace[496458445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1260; }","duration":"1.037002953s","start":"2026-06-19T00:19:56.131951Z","end":"2026-06-19T00:19:57.168954Z","steps":["trace[496458445] 'agreement among raft nodes before linearized reading' (duration: 1.036890551s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:57.16898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:56.131946Z","time spent":"1.037029644s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-19T00:19:57.168931Z","caller":"traceutil/trace.go:171","msg":"trace[1142042351] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"812.070957ms","start":"2026-06-19T00:19:56.356803Z","end":"2026-06-19T00:19:57.168874Z","steps":["trace[1142042351] 'process raft request' (duration: 811.500144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:57.16965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:56.356775Z","time spent":"812.795175ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:19:58.142416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.334019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:19:58.142498Z","caller":"traceutil/trace.go:171","msg":"trace[1060603676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"158.457592ms","start":"2026-06-19T00:19:57.984024Z","end":"2026-06-19T00:19:58.142482Z","steps":["trace[1060603676] 'range keys from in-memory index tree' (duration: 158.257597ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:58.420389Z","caller":"traceutil/trace.go:171","msg":"trace[1029235063] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"212.456551ms","start":"2026-06-19T00:19:58.207911Z","end":"2026-06-19T00:19:58.420368Z","steps":["trace[1029235063] 'read index received' (duration: 212.277276ms)","trace[1029235063] 'applied index is now lower than readState.Index' (duration: 178.365µs)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:19:58.420573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.640815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:19:58.420616Z","caller":"traceutil/trace.go:171","msg":"trace[839137258] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1263; }","duration":"212.741737ms","start":"2026-06-19T00:19:58.207862Z","end":"2026-06-19T00:19:58.420604Z","steps":["trace[839137258] 'agreement among raft nodes before linearized reading' (duration: 212.624384ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:58.420609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.342875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:19:58.420682Z","caller":"traceutil/trace.go:171","msg":"trace[599321759] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1263; }","duration":"165.477048ms","start":"2026-06-19T00:19:58.255192Z","end":"2026-06-19T00:19:58.420669Z","steps":["trace[599321759] 'agreement among raft nodes before linearized reading' (duration: 165.339735ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:58.421089Z","caller":"traceutil/trace.go:171","msg":"trace[336590208] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"274.238573ms","start":"2026-06-19T00:19:58.146201Z","end":"2026-06-19T00:19:58.42044Z","steps":["trace[336590208] 'process raft request' (duration: 274.037159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:59.239674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.262684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:19:59.239751Z","caller":"traceutil/trace.go:171","msg":"trace[170832737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"255.402977ms","start":"2026-06-19T00:19:58.984331Z","end":"2026-06-19T00:19:59.239734Z","steps":["trace[170832737] 'range keys from in-memory index tree' (duration: 255.141611ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:59.240217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.221462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-19T00:19:59.240889Z","caller":"traceutil/trace.go:171","msg":"trace[1091812724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1263; }","duration":"105.948278ms","start":"2026-06-19T00:19:59.13492Z","end":"2026-06-19T00:19:59.240868Z","steps":["trace[1091812724] 'range keys from in-memory index tree' (duration: 105.021827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:59.990448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.92392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:19:59.99063Z","caller":"traceutil/trace.go:171","msg":"trace[1297138801] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1316; }","duration":"721.404051ms","start":"2026-06-19T00:19:59.269211Z","end":"2026-06-19T00:19:59.990615Z","steps":["trace[1297138801] 'read index received' (duration: 258.237415ms)","trace[1297138801] 'applied index is now lower than readState.Index' (duration: 463.165586ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:19:59.990703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.786763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:19:59.990727Z","caller":"traceutil/trace.go:171","msg":"trace[416812647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"749.842354ms","start":"2026-06-19T00:19:59.240877Z","end":"2026-06-19T00:19:59.990719Z","steps":["trace[416812647] 'agreement among raft nodes before linearized reading' (duration: 749.801873ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:19:59.990713Z","caller":"traceutil/trace.go:171","msg":"trace[1559100048] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"746.544977ms","start":"2026-06-19T00:19:59.244135Z","end":"2026-06-19T00:19:59.99068Z","steps":["trace[1559100048] 'process raft request' (duration: 746.401783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:59.990857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.994436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-19T00:19:59.990756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:59.240862Z","time spent":"749.888205ms","remote":"127.0.0.1:43654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T00:19:59.990856Z","caller":"traceutil/trace.go:171","msg":"trace[761112508] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"746.676309ms","start":"2026-06-19T00:19:59.244062Z","end":"2026-06-19T00:19:59.990738Z","steps":["trace[761112508] 'process raft request' (duration: 283.38609ms)","trace[761112508] 'compare' (duration: 462.701875ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:19:59.990885Z","caller":"traceutil/trace.go:171","msg":"trace[1099122225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1266; }","duration":"804.037518ms","start":"2026-06-19T00:19:59.18684Z","end":"2026-06-19T00:19:59.990877Z","steps":["trace[1099122225] 'agreement among raft nodes before linearized reading' (duration: 803.936345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:19:59.990915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:59.186832Z","time spent":"804.077308ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-19T00:19:59.990917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:59.244118Z","time spent":"746.674059ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:19:59.990963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:59.244043Z","time spent":"746.868364ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:20:00.491754Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256279595272674030,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-19T00:20:00.911572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.596772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:20:00.911745Z","caller":"traceutil/trace.go:171","msg":"trace[1734194333] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"1.639437107s","start":"2026-06-19T00:19:59.272275Z","end":"2026-06-19T00:20:00.911712Z","steps":["trace[1734194333] 'process raft request' (duration: 1.250545229s)","trace[1734194333] 'compare' (duration: 388.220302ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:20:00.911872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:59.272257Z","time spent":"1.63957189s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:20:01.004Z","caller":"traceutil/trace.go:171","msg":"trace[788180994] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1318; }","duration":"1.013300442s","start":"2026-06-19T00:19:59.990657Z","end":"2026-06-19T00:20:01.003957Z","steps":["trace[788180994] 'read index received' (duration: 532.115878ms)","trace[788180994] 'applied index is now lower than readState.Index' (duration: 481.182904ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:20:01.004102Z","caller":"traceutil/trace.go:171","msg":"trace[1796650307] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"1.008441377s","start":"2026-06-19T00:19:59.995607Z","end":"2026-06-19T00:20:01.004048Z","steps":["trace[1796650307] 'process raft request' (duration: 1.0081363s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:20:01.004231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005830365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T00:20:01.004278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367737213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-19T00:20:01.004282Z","caller":"traceutil/trace.go:171","msg":"trace[1367257099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1268; }","duration":"1.005928717s","start":"2026-06-19T00:19:59.998339Z","end":"2026-06-19T00:20:01.004268Z","steps":["trace[1367257099] 'agreement among raft nodes before linearized reading' (duration: 1.005833785s)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:20:01.004311Z","caller":"traceutil/trace.go:171","msg":"trace[203611199] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:1268; }","duration":"1.367809485s","start":"2026-06-19T00:19:59.636491Z","end":"2026-06-19T00:20:01.004301Z","steps":["trace[203611199] 'agreement among raft nodes before linearized reading' (duration: 1.367636361s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:20:01.004317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:59.998328Z","time spent":"1.005981778s","remote":"127.0.0.1:43638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-19T00:20:01.004354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:59.636473Z","time spent":"1.367861957s","remote":"127.0.0.1:43664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-06-19T00:20:01.004354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:19:59.995586Z","time spent":"1.008634881s","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:20:01.004592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.046064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:20:01.004665Z","caller":"traceutil/trace.go:171","msg":"trace[503330382] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1268; }","duration":"591.155057ms","start":"2026-06-19T00:20:00.413486Z","end":"2026-06-19T00:20:01.004641Z","steps":["trace[503330382] 'agreement among raft nodes before linearized reading' (duration: 591.033624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:20:01.004686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.774158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-19T00:20:01.004725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:20:00.413465Z","time spent":"591.24364ms","remote":"127.0.0.1:39488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-06-19T00:20:01.004735Z","caller":"traceutil/trace.go:171","msg":"trace[652659736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1268; }","duration":"579.84644ms","start":"2026-06-19T00:20:00.424875Z","end":"2026-06-19T00:20:01.004722Z","steps":["trace[652659736] 'agreement among raft nodes before linearized reading' (duration: 579.709767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:20:01.004774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:20:00.424864Z","time spent":"579.900031ms","remote":"127.0.0.1:36708","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-06-19T00:20:01.748533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.555881ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-06-19T00:20:01.748835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:20:01.005668Z","time spent":"743.144077ms","remote":"127.0.0.1:43664","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-19T00:20:01.748957Z","caller":"traceutil/trace.go:171","msg":"trace[137893627] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"742.982552ms","start":"2026-06-19T00:20:01.005946Z","end":"2026-06-19T00:20:01.748928Z","steps":["trace[137893627] 'read index received' (duration: 446.157174ms)","trace[137893627] 'applied index is now lower than readState.Index' (duration: 296.822028ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:20:01.749093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.130646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:20:01.749153Z","caller":"traceutil/trace.go:171","msg":"trace[427926304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1268; }","duration":"743.244738ms","start":"2026-06-19T00:20:01.005895Z","end":"2026-06-19T00:20:01.749139Z","steps":["trace[427926304] 'agreement among raft nodes before linearized reading' (duration: 743.137016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:20:01.749193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:20:01.005873Z","time spent":"743.313001ms","remote":"127.0.0.1:43654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T00:20:01.749212Z","caller":"traceutil/trace.go:171","msg":"trace[838207754] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"739.347647ms","start":"2026-06-19T00:20:01.009846Z","end":"2026-06-19T00:20:01.749193Z","steps":["trace[838207754] 'process raft request' (duration: 738.780633ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:20:01.749337Z","caller":"traceutil/trace.go:171","msg":"trace[129140391] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"474.70135ms","start":"2026-06-19T00:20:01.274624Z","end":"2026-06-19T00:20:01.749325Z","steps":["trace[129140391] 'process raft request' (duration: 474.501205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:20:01.7494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:20:01.274597Z","time spent":"474.767522ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:20:01.749466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.998324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T00:20:01.749525Z","caller":"traceutil/trace.go:171","msg":"trace[971876062] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1270; }","duration":"648.193938ms","start":"2026-06-19T00:20:01.101315Z","end":"2026-06-19T00:20:01.749509Z","steps":["trace[971876062] 'agreement among raft nodes before linearized reading' (duration: 647.985753ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:20:01.749588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:20:01.101254Z","time spent":"648.30105ms","remote":"127.0.0.1:36914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-06-19T00:20:01.749888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:20:01.009828Z","time spent":"739.423258ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:20:02.11334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.99814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:20:02.113436Z","caller":"traceutil/trace.go:171","msg":"trace[940345934] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"320.246293ms","start":"2026-06-19T00:20:01.793168Z","end":"2026-06-19T00:20:02.113415Z","steps":["trace[940345934] 'read index received' (duration: 191.986956ms)","trace[940345934] 'applied index is now lower than readState.Index' (duration: 128.258217ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:20:02.113675Z","caller":"traceutil/trace.go:171","msg":"trace[1720601181] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"339.247142ms","start":"2026-06-19T00:20:01.774412Z","end":"2026-06-19T00:20:02.113659Z","steps":["trace[1720601181] 'process raft request' (duration: 210.869803ms)","trace[1720601181] 'compare' (duration: 127.824706ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:20:02.113743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:20:01.77435Z","time spent":"339.363486ms","remote":"127.0.0.1:43694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-19T00:20:02.113939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.739584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-06-19T00:20:02.114025Z","caller":"traceutil/trace.go:171","msg":"trace[1400633051] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1272; }","duration":"320.881568ms","start":"2026-06-19T00:20:01.793126Z","end":"2026-06-19T00:20:02.114007Z","steps":["trace[1400633051] 'agreement among raft nodes before linearized reading' (duration: 320.664063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:20:02.114072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:20:01.793107Z","time spent":"320.954789ms","remote":"127.0.0.1:36620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-06-19T00:20:02.114274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.440958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-19T00:20:02.114356Z","caller":"traceutil/trace.go:171","msg":"trace[391193676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1272; }","duration":"119.586802ms","start":"2026-06-19T00:20:01.994756Z","end":"2026-06-19T00:20:02.114343Z","steps":["trace[391193676] 'agreement among raft nodes before linearized reading' (duration: 119.221693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:20:02.114741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.782526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-19T00:20:02.114895Z","caller":"traceutil/trace.go:171","msg":"trace[526075562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"119.824468ms","start":"2026-06-19T00:20:01.994939Z","end":"2026-06-19T00:20:02.114764Z","steps":["trace[526075562] 'agreement among raft nodes before linearized reading' (duration: 119.720025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:20:05.304245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.845583ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-19T00:20:05.304352Z","caller":"traceutil/trace.go:171","msg":"trace[1532841196] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"408.880641ms","start":"2026-06-19T00:20:04.895455Z","end":"2026-06-19T00:20:05.304336Z","steps":["trace[1532841196] 'read index received' (duration: 137.868934ms)","trace[1532841196] 'applied index is now lower than readState.Index' (duration: 271.010337ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:20:05.304595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.100427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:20:05.304651Z","caller":"traceutil/trace.go:171","msg":"trace[615756773] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1293; }","duration":"409.22858ms","start":"2026-06-19T00:20:04.895406Z","end":"2026-06-19T00:20:05.304635Z","steps":["trace[615756773] 'agreement among raft nodes before linearized reading' (duration: 409.106697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:20:05.304689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:20:04.895389Z","time spent":"409.289331ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-06-19T00:20:05.304919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.308283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-19T00:20:05.30506Z","caller":"traceutil/trace.go:171","msg":"trace[1807667499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1293; }","duration":"287.491567ms","start":"2026-06-19T00:20:05.017545Z","end":"2026-06-19T00:20:05.305037Z","steps":["trace[1807667499] 'agreement among raft nodes before linearized reading' (duration: 287.025806ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:20:05.304919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.148386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-19T00:20:05.305183Z","caller":"traceutil/trace.go:171","msg":"trace[735713111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1293; }","duration":"376.464803ms","start":"2026-06-19T00:20:04.928702Z","end":"2026-06-19T00:20:05.305166Z","steps":["trace[735713111] 'agreement among raft nodes before linearized reading' (duration: 375.849909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:20:05.305231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:20:04.928679Z","time spent":"376.539336ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-19T00:20:05.411366Z","caller":"traceutil/trace.go:171","msg":"trace[1527717468] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"101.716219ms","start":"2026-06-19T00:20:05.309602Z","end":"2026-06-19T00:20:05.411318Z","steps":["trace[1527717468] 'process raft request' (duration: 101.594366ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:20:05.69707Z","caller":"traceutil/trace.go:171","msg":"trace[1220285901] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"387.432833ms","start":"2026-06-19T00:20:05.309613Z","end":"2026-06-19T00:20:05.697045Z","steps":["trace[1220285901] 'process raft request' (duration: 320.795755ms)","trace[1220285901] 'compare' (duration: 66.509665ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:20:05.697209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:20:05.309591Z","time spent":"387.544396ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:20:07.018532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.824866ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:20:07.018672Z","caller":"traceutil/trace.go:171","msg":"trace[1817603242] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1300; }","duration":"135.99392ms","start":"2026-06-19T00:20:06.882658Z","end":"2026-06-19T00:20:07.018652Z","steps":["trace[1817603242] 'range keys from in-memory index tree' (duration: 135.762284ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:20:27.778719Z","caller":"traceutil/trace.go:171","msg":"trace[1549684311] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"162.527239ms","start":"2026-06-19T00:20:27.616158Z","end":"2026-06-19T00:20:27.778685Z","steps":["trace[1549684311] 'process raft request' (duration: 162.285973ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:20:28.609651Z","caller":"traceutil/trace.go:171","msg":"trace[1905296237] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"123.959695ms","start":"2026-06-19T00:20:28.485661Z","end":"2026-06-19T00:20:28.609621Z","steps":["trace[1905296237] 'process raft request' (duration: 75.851826ms)","trace[1905296237] 'compare' (duration: 47.923295ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:21:52.037801Z","caller":"traceutil/trace.go:171","msg":"trace[2140595999] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1877; }","duration":"126.472713ms","start":"2026-06-19T00:21:51.911304Z","end":"2026-06-19T00:21:52.037777Z","steps":["trace[2140595999] 'read index received' (duration: 126.219267ms)","trace[2140595999] 'applied index is now lower than readState.Index' (duration: 252.526µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:21:52.037874Z","caller":"traceutil/trace.go:171","msg":"trace[1776095985] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"156.070793ms","start":"2026-06-19T00:21:51.881774Z","end":"2026-06-19T00:21:52.037844Z","steps":["trace[1776095985] 'process raft request' (duration: 155.806387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:21:52.038219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.694168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T00:21:52.038287Z","caller":"traceutil/trace.go:171","msg":"trace[133661818] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1803; }","duration":"127.056446ms","start":"2026-06-19T00:21:51.911214Z","end":"2026-06-19T00:21:52.038271Z","steps":["trace[133661818] 'agreement among raft nodes before linearized reading' (duration: 126.693218ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:21:52.530036Z","caller":"traceutil/trace.go:171","msg":"trace[1971492665] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"329.571668ms","start":"2026-06-19T00:21:52.200438Z","end":"2026-06-19T00:21:52.53001Z","steps":["trace[1971492665] 'process raft request' (duration: 329.411745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:21:52.530453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:21:52.200419Z","time spent":"329.688921ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T00:21:52.710151Z","caller":"traceutil/trace.go:171","msg":"trace[449709140] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1879; }","duration":"387.58964ms","start":"2026-06-19T00:21:52.322512Z","end":"2026-06-19T00:21:52.710102Z","steps":["trace[449709140] 'read index received' (duration: 207.550431ms)","trace[449709140] 'applied index is now lower than readState.Index' (duration: 180.037139ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T00:21:52.710332Z","caller":"traceutil/trace.go:171","msg":"trace[616915649] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"390.5233ms","start":"2026-06-19T00:21:52.319773Z","end":"2026-06-19T00:21:52.710296Z","steps":["trace[616915649] 'process raft request' (duration: 326.26322ms)","trace[616915649] 'compare' (duration: 63.772538ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T00:21:52.710516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:21:52.319733Z","time spent":"390.689104ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T00:21:52.710611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.034761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-19T00:21:52.710677Z","caller":"traceutil/trace.go:171","msg":"trace[102045581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1805; }","duration":"388.163834ms","start":"2026-06-19T00:21:52.322495Z","end":"2026-06-19T00:21:52.710659Z","steps":["trace[102045581] 'agreement among raft nodes before linearized reading' (duration: 387.855297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:21:52.710743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:21:52.322418Z","time spent":"388.311088ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-19T00:21:52.71085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.775394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T00:21:52.710909Z","caller":"traceutil/trace.go:171","msg":"trace[293727629] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1805; }","duration":"384.858706ms","start":"2026-06-19T00:21:52.326037Z","end":"2026-06-19T00:21:52.710895Z","steps":["trace[293727629] 'agreement among raft nodes before linearized reading' (duration: 384.755044ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:21:52.710946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:21:52.326023Z","time spent":"384.912287ms","remote":"127.0.0.1:45736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-19T00:21:52.710979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.440914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-06-19T00:21:52.711026Z","caller":"traceutil/trace.go:171","msg":"trace[1650519262] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1805; }","duration":"315.513106ms","start":"2026-06-19T00:21:52.395498Z","end":"2026-06-19T00:21:52.711011Z","steps":["trace[1650519262] 'agreement among raft nodes before linearized reading' (duration: 315.350402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:21:52.711057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:21:52.395487Z","time spent":"315.563287ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-19T00:21:52.711103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.740968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-19T00:21:52.711104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.20069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-06-19T00:21:52.711178Z","caller":"traceutil/trace.go:171","msg":"trace[2048737514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1805; }","duration":"235.874281ms","start":"2026-06-19T00:21:52.475285Z","end":"2026-06-19T00:21:52.711159Z","steps":["trace[2048737514] 'agreement among raft nodes before linearized reading' (duration: 235.626425ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:21:52.711202Z","caller":"traceutil/trace.go:171","msg":"trace[1350230446] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1805; }","duration":"343.314333ms","start":"2026-06-19T00:21:52.367862Z","end":"2026-06-19T00:21:52.711176Z","steps":["trace[1350230446] 'agreement among raft nodes before linearized reading' (duration: 343.023536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:21:52.711268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T00:21:52.367851Z","time spent":"343.402826ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-19T00:21:53.010557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.582445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-19T00:21:53.010641Z","caller":"traceutil/trace.go:171","msg":"trace[123012816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1809; }","duration":"156.722598ms","start":"2026-06-19T00:21:52.8539Z","end":"2026-06-19T00:21:53.010623Z","steps":["trace[123012816] 'range keys from in-memory index tree' (duration: 156.233957ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:21:53.121422Z","caller":"traceutil/trace.go:171","msg":"trace[1143455957] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"104.531483ms","start":"2026-06-19T00:21:53.016862Z","end":"2026-06-19T00:21:53.121393Z","steps":["trace[1143455957] 'process raft request' (duration: 104.41419ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T00:22:32.507444Z","caller":"traceutil/trace.go:171","msg":"trace[199611788] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"283.798615ms","start":"2026-06-19T00:22:32.223626Z","end":"2026-06-19T00:22:32.507425Z","steps":["trace[199611788] 'process raft request' (duration: 283.643842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T00:22:54.747057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.052762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T00:22:54.747244Z","caller":"traceutil/trace.go:171","msg":"trace[572958332] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"122.710412ms","start":"2026-06-19T00:22:54.624512Z","end":"2026-06-19T00:22:54.747223Z","steps":["trace[572958332] 'process raft request' (duration: 21.410094ms)","trace[572958332] 'compare' (duration: 100.900788ms)"],"step_count":2}