{"level":"warn","ts":"2026-05-12T05:41:59.627283Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T05:41:59.627377Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.244: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.19.213.244:2380","--initial-cluster=instance=https://199.19.213.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.244:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-12T05:41:59.627448Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T05:41:59.62746Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.244:2380"]} {"level":"info","ts":"2026-05-12T05:41:59.627482Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T05:41:59.628159Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"]} {"level":"info","ts":"2026-05-12T05:41:59.628599Z","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.19.213.244:2380"],"listen-peer-urls":["https://199.19.213.244:2380"],"advertise-client-urls":["https://199.19.213.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.244:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-12T05:41:59.649747Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.775606ms"} {"level":"info","ts":"2026-05-12T05:41:59.667242Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"95a1d32286abc938","cluster-id":"f9243fb70f4df4f4"} {"level":"info","ts":"2026-05-12T05:41:59.667394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T05:41:59.667514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became follower at term 0"} {"level":"info","ts":"2026-05-12T05:41:59.667596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 95a1d32286abc938 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T05:41:59.667612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became follower at term 1"} {"level":"info","ts":"2026-05-12T05:41:59.667822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=(10782131128143431992)"} {"level":"warn","ts":"2026-05-12T05:41:59.679517Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T05:41:59.681715Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T05:41:59.685599Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-12T05:41:59.689338Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"95a1d32286abc938","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T05:41:59.689665Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T05:41:59.689814Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T05:41:59.689884Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-12T05:41:59.690244Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"95a1d32286abc938","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T05:41:59.696649Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-12T05:41:59.696935Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"95a1d32286abc938","initial-advertise-peer-urls":["https://199.19.213.244:2380"],"listen-peer-urls":["https://199.19.213.244:2380"],"advertise-client-urls":["https://199.19.213.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T05:41:59.696999Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T05:41:59.697076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=(10782131128143431992)"} {"level":"info","ts":"2026-05-12T05:41:59.697103Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.244:2380"} {"level":"info","ts":"2026-05-12T05:41:59.697161Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.244:2380"} {"level":"info","ts":"2026-05-12T05:41:59.697292Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f9243fb70f4df4f4","local-member-id":"95a1d32286abc938","added-peer-id":"95a1d32286abc938","added-peer-peer-urls":["https://199.19.213.244:2380"]} {"level":"info","ts":"2026-05-12T05:42:00.668242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T05:42:00.668391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T05:42:00.668492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 received MsgPreVoteResp from 95a1d32286abc938 at term 1"} {"level":"info","ts":"2026-05-12T05:42:00.668587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became candidate at term 2"} {"level":"info","ts":"2026-05-12T05:42:00.668654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 received MsgVoteResp from 95a1d32286abc938 at term 2"} {"level":"info","ts":"2026-05-12T05:42:00.66874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became leader at term 2"} {"level":"info","ts":"2026-05-12T05:42:00.668786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 95a1d32286abc938 elected leader 95a1d32286abc938 at term 2"} {"level":"info","ts":"2026-05-12T05:42:00.724829Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:42:00.726402Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T05:42:00.726379Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"95a1d32286abc938","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.244:2379]}","request-path":"/0/members/95a1d32286abc938/attributes","cluster-id":"f9243fb70f4df4f4","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T05:42:00.726422Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T05:42:00.726941Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T05:42:00.727047Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T05:42:00.729268Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T05:42:00.732233Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.244:2379"} {"level":"info","ts":"2026-05-12T05:42:00.736357Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f9243fb70f4df4f4","local-member-id":"95a1d32286abc938","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:42:00.736489Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:42:00.736535Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T05:42:02.445963Z","caller":"traceutil/trace.go:171","msg":"trace[1845062051] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"127.821555ms","start":"2026-05-12T05:42:02.318103Z","end":"2026-05-12T05:42:02.445925Z","steps":["trace[1845062051] 'process raft request' (duration: 127.646372ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:02.446017Z","caller":"traceutil/trace.go:171","msg":"trace[822296930] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"129.105557ms","start":"2026-05-12T05:42:02.316871Z","end":"2026-05-12T05:42:02.445977Z","steps":["trace[822296930] 'process raft request' (duration: 95.603439ms)","trace[822296930] 'compare' (duration: 33.050993ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:02.446246Z","caller":"traceutil/trace.go:171","msg":"trace[603293986] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"127.652023ms","start":"2026-05-12T05:42:02.318563Z","end":"2026-05-12T05:42:02.446215Z","steps":["trace[603293986] 'process raft request' (duration: 127.26732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:03.764651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.29979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:42:03.764797Z","caller":"traceutil/trace.go:171","msg":"trace[1095928426] range","detail":"{range_begin:/registry/clusterroles/system:controller:resourcequota-controller; range_end:; response_count:0; response_revision:166; }","duration":"147.547125ms","start":"2026-05-12T05:42:03.617217Z","end":"2026-05-12T05:42:03.764764Z","steps":["trace[1095928426] 'range keys from in-memory index tree' (duration: 147.210419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:04.536126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.121501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:42:04.536285Z","caller":"traceutil/trace.go:171","msg":"trace[1259312706] linearizableReadLoop","detail":"{readStateIndex:173; appliedIndex:172; }","duration":"600.973876ms","start":"2026-05-12T05:42:03.93529Z","end":"2026-05-12T05:42:04.536263Z","steps":["trace[1259312706] 'read index received' (duration: 54.731µs)","trace[1259312706] 'applied index is now lower than readState.Index' (duration: 600.917725ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:04.536369Z","caller":"traceutil/trace.go:171","msg":"trace[1470666394] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"715.907736ms","start":"2026-05-12T05:42:03.820449Z","end":"2026-05-12T05:42:04.536357Z","steps":["trace[1470666394] 'process raft request' (duration: 53.876737ms)","trace[1470666394] 'compare' (duration: 660.932407ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:42:04.536425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:03.82042Z","time spent":"715.978807ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":662,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T05:42:04.536965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.673669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:42:04.537008Z","caller":"traceutil/trace.go:171","msg":"trace[1416188563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:168; }","duration":"601.796622ms","start":"2026-05-12T05:42:03.9352Z","end":"2026-05-12T05:42:04.536997Z","steps":["trace[1416188563] 'agreement among raft nodes before linearized reading' (duration: 601.68802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:04.537031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:03.935173Z","time spent":"601.852772ms","remote":"127.0.0.1:37046","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-05-12T05:42:05.188057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.362013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:42:05.188233Z","caller":"traceutil/trace.go:171","msg":"trace[275738983] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"1.158162332s","start":"2026-05-12T05:42:04.030028Z","end":"2026-05-12T05:42:05.18819Z","steps":["trace[275738983] 'process raft request' (duration: 940.524714ms)","trace[275738983] 'compare' (duration: 217.19372ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:42:05.188293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:04.029996Z","time spent":"1.158274534s","remote":"127.0.0.1:37202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:42:05.188314Z","caller":"traceutil/trace.go:171","msg":"trace[298878085] linearizableReadLoop","detail":"{readStateIndex:174; appliedIndex:173; }","duration":"651.952992ms","start":"2026-05-12T05:42:04.536315Z","end":"2026-05-12T05:42:05.188268Z","steps":["trace[298878085] 'read index received' (duration: 434.139241ms)","trace[298878085] 'applied index is now lower than readState.Index' (duration: 217.807631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:42:05.188574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T05:42:05.188574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.062009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"info","ts":"2026-05-12T05:42:05.188659Z","caller":"traceutil/trace.go:171","msg":"trace[1944368161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:170; }","duration":"650.801491ms","start":"2026-05-12T05:42:04.537827Z","end":"2026-05-12T05:42:05.188628Z","steps":["trace[1944368161] 'agreement among raft nodes before linearized reading' (duration: 650.645368ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:05.188669Z","caller":"traceutil/trace.go:171","msg":"trace[1082898211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:170; }","duration":"947.207463ms","start":"2026-05-12T05:42:04.241439Z","end":"2026-05-12T05:42:05.188646Z","steps":["trace[1082898211] 'agreement among raft nodes before linearized reading' (duration: 946.920537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:05.18863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.153641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-12T05:42:05.188714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:04.241412Z","time spent":"947.290125ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T05:42:05.188715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:04.537769Z","time spent":"650.933524ms","remote":"127.0.0.1:37032","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-05-12T05:42:05.188629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.15114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:42:05.188745Z","caller":"traceutil/trace.go:171","msg":"trace[340519663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:170; }","duration":"947.337535ms","start":"2026-05-12T05:42:04.241378Z","end":"2026-05-12T05:42:05.188715Z","steps":["trace[340519663] 'agreement among raft nodes before linearized reading' (duration: 947.020049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:05.188798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:04.241346Z","time spent":"947.442237ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T05:42:05.188795Z","caller":"traceutil/trace.go:171","msg":"trace[2053405323] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-account-controller; range_end:; response_count:0; response_revision:170; }","duration":"649.406775ms","start":"2026-05-12T05:42:04.539357Z","end":"2026-05-12T05:42:05.188763Z","steps":["trace[2053405323] 'agreement among raft nodes before linearized reading' (duration: 649.13632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:05.188851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:04.539344Z","time spent":"649.490286ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":29,"request content":"key:\"/registry/clusterroles/system:controller:service-account-controller\" "} {"level":"info","ts":"2026-05-12T05:42:05.82194Z","caller":"traceutil/trace.go:171","msg":"trace[376658550] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"137.617617ms","start":"2026-05-12T05:42:05.684291Z","end":"2026-05-12T05:42:05.821909Z","steps":["trace[376658550] 'process raft request' (duration: 83.312877ms)","trace[376658550] 'compare' (duration: 54.153807ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:42:06.083234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.96644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:42:06.083392Z","caller":"traceutil/trace.go:171","msg":"trace[932611484] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"196.680643ms","start":"2026-05-12T05:42:05.886682Z","end":"2026-05-12T05:42:06.083362Z","steps":["trace[932611484] 'process raft request' (duration: 35.488059ms)","trace[932611484] 'compare' (duration: 160.719316ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:06.322513Z","caller":"traceutil/trace.go:171","msg":"trace[1192245092] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"142.792423ms","start":"2026-05-12T05:42:06.179682Z","end":"2026-05-12T05:42:06.322475Z","steps":["trace[1192245092] 'process raft request' (duration: 55.575225ms)","trace[1192245092] 'compare' (duration: 87.011374ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:07.225184Z","caller":"traceutil/trace.go:171","msg":"trace[1627189493] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"107.326043ms","start":"2026-05-12T05:42:07.117832Z","end":"2026-05-12T05:42:07.225159Z","steps":["trace[1627189493] 'process raft request' (duration: 107.282583ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:07.225225Z","caller":"traceutil/trace.go:171","msg":"trace[1744235515] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"109.038165ms","start":"2026-05-12T05:42:07.116141Z","end":"2026-05-12T05:42:07.22518Z","steps":["trace[1744235515] 'process raft request' (duration: 101.294613ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:09.440844Z","caller":"traceutil/trace.go:171","msg":"trace[1204429146] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"123.879762ms","start":"2026-05-12T05:42:09.316929Z","end":"2026-05-12T05:42:09.440809Z","steps":["trace[1204429146] 'process raft request' (duration: 91.486108ms)","trace[1204429146] 'compare' (duration: 32.121498ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:11.175799Z","caller":"traceutil/trace.go:171","msg":"trace[1684660015] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:335; }","duration":"125.68098ms","start":"2026-05-12T05:42:11.050086Z","end":"2026-05-12T05:42:11.175767Z","steps":["trace[1684660015] 'read index received' (duration: 94.744661ms)","trace[1684660015] 'applied index is now lower than readState.Index' (duration: 30.934769ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:11.175951Z","caller":"traceutil/trace.go:171","msg":"trace[341504351] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"142.742393ms","start":"2026-05-12T05:42:11.033195Z","end":"2026-05-12T05:42:11.175938Z","steps":["trace[341504351] 'process raft request' (duration: 111.664421ms)","trace[341504351] 'compare' (duration: 30.607494ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:42:11.176142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.041376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-12T05:42:11.176175Z","caller":"traceutil/trace.go:171","msg":"trace[787014186] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:323; }","duration":"126.138168ms","start":"2026-05-12T05:42:11.050026Z","end":"2026-05-12T05:42:11.176164Z","steps":["trace[787014186] 'agreement among raft nodes before linearized reading' (duration: 126.038946ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:13.434147Z","caller":"traceutil/trace.go:171","msg":"trace[276914596] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"122.98409ms","start":"2026-05-12T05:42:13.311141Z","end":"2026-05-12T05:42:13.434125Z","steps":["trace[276914596] 'process raft request' (duration: 116.62316ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:13.751277Z","caller":"traceutil/trace.go:171","msg":"trace[1171990293] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"148.11578ms","start":"2026-05-12T05:42:13.603129Z","end":"2026-05-12T05:42:13.751245Z","steps":["trace[1171990293] 'process raft request' (duration: 119.162094ms)","trace[1171990293] 'compare' (duration: 28.749582ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:13.751313Z","caller":"traceutil/trace.go:171","msg":"trace[796615899] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"144.379643ms","start":"2026-05-12T05:42:13.606905Z","end":"2026-05-12T05:42:13.751285Z","steps":["trace[796615899] 'process raft request' (duration: 144.288292ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:13.751654Z","caller":"traceutil/trace.go:171","msg":"trace[1841602042] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"143.402657ms","start":"2026-05-12T05:42:13.608242Z","end":"2026-05-12T05:42:13.751645Z","steps":["trace[1841602042] 'process raft request' (duration: 143.0031ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:13.751869Z","caller":"traceutil/trace.go:171","msg":"trace[109136468] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"142.843317ms","start":"2026-05-12T05:42:13.608927Z","end":"2026-05-12T05:42:13.75177Z","steps":["trace[109136468] 'process raft request' (duration: 142.605553ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:13.752506Z","caller":"traceutil/trace.go:171","msg":"trace[1140105841] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:375; }","duration":"144.461116ms","start":"2026-05-12T05:42:13.608029Z","end":"2026-05-12T05:42:13.75249Z","steps":["trace[1140105841] 'read index received' (duration: 35.452141ms)","trace[1140105841] 'applied index is now lower than readState.Index' (duration: 109.007095ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:13.752598Z","caller":"traceutil/trace.go:171","msg":"trace[1538772786] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"142.708145ms","start":"2026-05-12T05:42:13.60988Z","end":"2026-05-12T05:42:13.752588Z","steps":["trace[1538772786] 'process raft request' (duration: 141.748117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:13.752817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.764821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-05-12T05:42:13.752853Z","caller":"traceutil/trace.go:171","msg":"trace[1198431708] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:368; }","duration":"144.836902ms","start":"2026-05-12T05:42:13.608006Z","end":"2026-05-12T05:42:13.752842Z","steps":["trace[1198431708] 'agreement among raft nodes before linearized reading' (duration: 144.7085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:13.753005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.48268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T05:42:13.753039Z","caller":"traceutil/trace.go:171","msg":"trace[2055021782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:368; }","duration":"107.569001ms","start":"2026-05-12T05:42:13.645455Z","end":"2026-05-12T05:42:13.753024Z","steps":["trace[2055021782] 'agreement among raft nodes before linearized reading' (duration: 107.5145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:13.753445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.562271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-12T05:42:13.753536Z","caller":"traceutil/trace.go:171","msg":"trace[1440056999] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:368; }","duration":"103.705143ms","start":"2026-05-12T05:42:13.649807Z","end":"2026-05-12T05:42:13.753512Z","steps":["trace[1440056999] 'agreement among raft nodes before linearized reading' (duration: 103.54124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:14.105086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.765081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:42:14.105206Z","caller":"traceutil/trace.go:171","msg":"trace[89860192] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"267.050235ms","start":"2026-05-12T05:42:13.838136Z","end":"2026-05-12T05:42:14.105186Z","steps":["trace[89860192] 'process raft request' (duration: 72.11256ms)","trace[89860192] 'compare' (duration: 194.567708ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:14.113064Z","caller":"traceutil/trace.go:171","msg":"trace[1374479581] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"272.349987ms","start":"2026-05-12T05:42:13.840675Z","end":"2026-05-12T05:42:14.113025Z","steps":["trace[1374479581] 'process raft request' (duration: 271.490932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:14.113391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.611526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-12T05:42:14.113496Z","caller":"traceutil/trace.go:171","msg":"trace[2023109309] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:377; }","duration":"262.767559ms","start":"2026-05-12T05:42:13.850713Z","end":"2026-05-12T05:42:14.113481Z","steps":["trace[2023109309] 'agreement among raft nodes before linearized reading' (duration: 262.608726ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:14.113298Z","caller":"traceutil/trace.go:171","msg":"trace[416410411] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:388; }","duration":"262.510924ms","start":"2026-05-12T05:42:13.850757Z","end":"2026-05-12T05:42:14.113268Z","steps":["trace[416410411] 'read index received' (duration: 59.50578ms)","trace[416410411] 'applied index is now lower than readState.Index' (duration: 202.670858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:42:14.637156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.212982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:42:14.637288Z","caller":"traceutil/trace.go:171","msg":"trace[2039432056] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"526.658009ms","start":"2026-05-12T05:42:14.110609Z","end":"2026-05-12T05:42:14.637267Z","steps":["trace[2039432056] 'process raft request' (duration: 144.05295ms)","trace[2039432056] 'compare' (duration: 381.683203ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:42:14.637362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:14.110583Z","time spent":"526.746872ms","remote":"127.0.0.1:37202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:42:14.669766Z","caller":"traceutil/trace.go:171","msg":"trace[261791179] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:390; }","duration":"551.311526ms","start":"2026-05-12T05:42:14.118422Z","end":"2026-05-12T05:42:14.669734Z","steps":["trace[261791179] 'read index received' (duration: 136.166703ms)","trace[261791179] 'applied index is now lower than readState.Index' (duration: 415.143773ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:14.669833Z","caller":"traceutil/trace.go:171","msg":"trace[37830076] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"555.296775ms","start":"2026-05-12T05:42:14.114496Z","end":"2026-05-12T05:42:14.669793Z","steps":["trace[37830076] 'process raft request' (duration: 554.98611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:14.670002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:14.114463Z","time spent":"555.466379ms","remote":"127.0.0.1:37114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:42:14.670019Z","caller":"traceutil/trace.go:171","msg":"trace[1129585653] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"554.95662ms","start":"2026-05-12T05:42:14.114978Z","end":"2026-05-12T05:42:14.669935Z","steps":["trace[1129585653] 'process raft request' (duration: 554.652355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:14.670121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.660773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-12T05:42:14.670218Z","caller":"traceutil/trace.go:171","msg":"trace[1747421056] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:381; }","duration":"551.807015ms","start":"2026-05-12T05:42:14.118395Z","end":"2026-05-12T05:42:14.670202Z","steps":["trace[1747421056] 'agreement among raft nodes before linearized reading' (duration: 551.574112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:14.67031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:14.118387Z","time spent":"551.908247ms","remote":"127.0.0.1:37488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4041,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-05-12T05:42:14.670405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:14.114966Z","time spent":"555.187574ms","remote":"127.0.0.1:37512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:42:14.671136Z","caller":"traceutil/trace.go:171","msg":"trace[623974778] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"551.135194ms","start":"2026-05-12T05:42:14.119949Z","end":"2026-05-12T05:42:14.671084Z","steps":["trace[623974778] 'process raft request' (duration: 549.737909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:14.671311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:14.119941Z","time spent":"551.302127ms","remote":"127.0.0.1:37202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:42:14.955209Z","caller":"traceutil/trace.go:171","msg":"trace[2004832099] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"279.111474ms","start":"2026-05-12T05:42:14.67605Z","end":"2026-05-12T05:42:14.955161Z","steps":["trace[2004832099] 'process raft request' (duration: 222.268898ms)","trace[2004832099] 'compare' (duration: 56.682803ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:14.955741Z","caller":"traceutil/trace.go:171","msg":"trace[1917574937] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"279.012941ms","start":"2026-05-12T05:42:14.676704Z","end":"2026-05-12T05:42:14.955717Z","steps":["trace[1917574937] 'process raft request' (duration: 278.583184ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:14.955762Z","caller":"traceutil/trace.go:171","msg":"trace[202780893] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"269.179521ms","start":"2026-05-12T05:42:14.686549Z","end":"2026-05-12T05:42:14.955728Z","steps":["trace[202780893] 'process raft request' (duration: 269.041059ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:14.964692Z","caller":"traceutil/trace.go:171","msg":"trace[1776321046] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"237.296017ms","start":"2026-05-12T05:42:14.727367Z","end":"2026-05-12T05:42:14.964663Z","steps":["trace[1776321046] 'process raft request' (duration: 233.007123ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:14.964897Z","caller":"traceutil/trace.go:171","msg":"trace[1523450405] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"231.115571ms","start":"2026-05-12T05:42:14.733675Z","end":"2026-05-12T05:42:14.964791Z","steps":["trace[1523450405] 'process raft request' (duration: 228.667589ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:15.160385Z","caller":"traceutil/trace.go:171","msg":"trace[962909237] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"100.958329ms","start":"2026-05-12T05:42:15.05938Z","end":"2026-05-12T05:42:15.160338Z","steps":["trace[962909237] 'process raft request' (duration: 100.704985ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:20.12428Z","caller":"traceutil/trace.go:171","msg":"trace[1038516115] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"180.397796ms","start":"2026-05-12T05:42:19.943852Z","end":"2026-05-12T05:42:20.12425Z","steps":["trace[1038516115] 'process raft request' (duration: 179.689184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:20.896838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499512837859637152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:42:21.397142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499512837859637152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:42:21.898316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499512837859637152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:42:22.271188Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.024543134s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-12T05:42:22.396836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000014239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-12T05:42:22.396916Z","caller":"traceutil/trace.go:171","msg":"trace[1917628937] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00015659s","start":"2026-05-12T05:42:20.396744Z","end":"2026-05-12T05:42:22.3969Z","steps":["trace[1917628937] 'agreement among raft nodes before linearized reading' (duration: 2.000045309s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:22.396967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:20.396723Z","time spent":"2.000232361s","remote":"127.0.0.1:37046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/12 05:42:22 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-12T05:42:22.432533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.03977845s","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-05-12T05:42:22.432741Z","caller":"traceutil/trace.go:171","msg":"trace[1173303697] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:418; }","duration":"2.040000894s","start":"2026-05-12T05:42:20.392715Z","end":"2026-05-12T05:42:22.432716Z","steps":["trace[1173303697] 'range keys from in-memory index tree' (duration: 2.039720739s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:22.433149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.65393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:42:22.433208Z","caller":"traceutil/trace.go:171","msg":"trace[1296603319] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"2.036408465s","start":"2026-05-12T05:42:20.396789Z","end":"2026-05-12T05:42:22.433197Z","steps":["trace[1296603319] 'read index received' (duration: 1.874583771s)","trace[1296603319] 'applied index is now lower than readState.Index' (duration: 161.823764ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:42:22.433486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016658088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T05:42:22.433567Z","caller":"traceutil/trace.go:171","msg":"trace[1141985239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:419; }","duration":"1.01678191s","start":"2026-05-12T05:42:21.416761Z","end":"2026-05-12T05:42:22.433543Z","steps":["trace[1141985239] 'agreement among raft nodes before linearized reading' (duration: 1.016614706s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:22.433598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.162106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-12T05:42:22.433614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:21.416682Z","time spent":"1.016923332s","remote":"127.0.0.1:37280","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-05-12T05:42:22.433652Z","caller":"traceutil/trace.go:171","msg":"trace[310157732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"622.259037ms","start":"2026-05-12T05:42:21.811375Z","end":"2026-05-12T05:42:22.433634Z","steps":["trace[310157732] 'agreement among raft nodes before linearized reading' (duration: 622.129914ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:22.43365Z","caller":"traceutil/trace.go:171","msg":"trace[883896797] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"2.18707919s","start":"2026-05-12T05:42:20.246529Z","end":"2026-05-12T05:42:22.433608Z","steps":["trace[883896797] 'process raft request' (duration: 2.02489027s)","trace[883896797] 'compare' (duration: 160.887098ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:42:22.433691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:21.811355Z","time spent":"622.325158ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T05:42:22.43378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.430122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-12T05:42:22.433795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:20.246502Z","time spent":"2.187217542s","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:42:22.433808Z","caller":"traceutil/trace.go:171","msg":"trace[417946006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:419; }","duration":"734.067222ms","start":"2026-05-12T05:42:21.699732Z","end":"2026-05-12T05:42:22.433799Z","steps":["trace[417946006] 'agreement among raft nodes before linearized reading' (duration: 733.982301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:22.433839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:21.699703Z","time spent":"734.129184ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T05:42:22.434324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.097762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T05:42:22.434398Z","caller":"traceutil/trace.go:171","msg":"trace[1463411912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:419; }","duration":"545.194274ms","start":"2026-05-12T05:42:21.889185Z","end":"2026-05-12T05:42:22.434379Z","steps":["trace[1463411912] 'agreement among raft nodes before linearized reading' (duration: 545.061142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:22.434453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:21.889173Z","time spent":"545.267245ms","remote":"127.0.0.1:37280","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-05-12T05:42:24.574716Z","caller":"traceutil/trace.go:171","msg":"trace[1432035442] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:442; }","duration":"113.984594ms","start":"2026-05-12T05:42:24.460693Z","end":"2026-05-12T05:42:24.574678Z","steps":["trace[1432035442] 'read index received' (duration: 113.573068ms)","trace[1432035442] 'applied index is now lower than readState.Index' (duration: 410.056µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:24.57482Z","caller":"traceutil/trace.go:171","msg":"trace[988960719] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"117.883737ms","start":"2026-05-12T05:42:24.456897Z","end":"2026-05-12T05:42:24.57478Z","steps":["trace[988960719] 'process raft request' (duration: 117.493091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:24.574949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.225358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T05:42:24.575Z","caller":"traceutil/trace.go:171","msg":"trace[444475124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:427; }","duration":"114.34334ms","start":"2026-05-12T05:42:24.460639Z","end":"2026-05-12T05:42:24.574982Z","steps":["trace[444475124] 'agreement among raft nodes before linearized reading' (duration: 114.227728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:24.575227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.307009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T05:42:24.575261Z","caller":"traceutil/trace.go:171","msg":"trace[700081709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:427; }","duration":"114.352889ms","start":"2026-05-12T05:42:24.4609Z","end":"2026-05-12T05:42:24.575253Z","steps":["trace[700081709] 'agreement among raft nodes before linearized reading' (duration: 114.292949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:24.57536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.496922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-12T05:42:24.575517Z","caller":"traceutil/trace.go:171","msg":"trace[2112051306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:427; }","duration":"114.796617ms","start":"2026-05-12T05:42:24.460678Z","end":"2026-05-12T05:42:24.575475Z","steps":["trace[2112051306] 'agreement among raft nodes before linearized reading' (duration: 114.401851ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:24.824661Z","caller":"traceutil/trace.go:171","msg":"trace[1240914021] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"244.541246ms","start":"2026-05-12T05:42:24.5801Z","end":"2026-05-12T05:42:24.824641Z","steps":["trace[1240914021] 'process raft request' (duration: 244.372824ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:24.824669Z","caller":"traceutil/trace.go:171","msg":"trace[483091708] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"245.317258ms","start":"2026-05-12T05:42:24.579303Z","end":"2026-05-12T05:42:24.824621Z","steps":["trace[483091708] 'process raft request' (duration: 232.941098ms)","trace[483091708] 'compare' (duration: 11.994974ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:24.824631Z","caller":"traceutil/trace.go:171","msg":"trace[1082672060] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"243.832914ms","start":"2026-05-12T05:42:24.580761Z","end":"2026-05-12T05:42:24.824594Z","steps":["trace[1082672060] 'process raft request' (duration: 243.770913ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:29.06909Z","caller":"traceutil/trace.go:171","msg":"trace[2078082824] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"204.538406ms","start":"2026-05-12T05:42:28.864533Z","end":"2026-05-12T05:42:29.069071Z","steps":["trace[2078082824] 'process raft request' (duration: 204.491825ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:29.069194Z","caller":"traceutil/trace.go:171","msg":"trace[568468073] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"206.72754ms","start":"2026-05-12T05:42:28.862421Z","end":"2026-05-12T05:42:29.069149Z","steps":["trace[568468073] 'process raft request' (duration: 175.954288ms)","trace[568468073] 'compare' (duration: 30.527548ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:32.617801Z","caller":"traceutil/trace.go:171","msg":"trace[12698994] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"136.175993ms","start":"2026-05-12T05:42:32.481593Z","end":"2026-05-12T05:42:32.617769Z","steps":["trace[12698994] 'process raft request' (duration: 135.97959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:33.081335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.513655ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-12T05:42:33.081513Z","caller":"traceutil/trace.go:171","msg":"trace[732800061] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"164.389699ms","start":"2026-05-12T05:42:32.917099Z","end":"2026-05-12T05:42:33.081489Z","steps":["trace[732800061] 'read index received' (duration: 32.141µs)","trace[732800061] 'applied index is now lower than readState.Index' (duration: 164.355318ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:42:33.081648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.533001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-12T05:42:33.081713Z","caller":"traceutil/trace.go:171","msg":"trace[1434033925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"164.621172ms","start":"2026-05-12T05:42:32.917066Z","end":"2026-05-12T05:42:33.081687Z","steps":["trace[1434033925] 'agreement among raft nodes before linearized reading' (duration: 164.49102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:33.327103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.226423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T05:42:33.327247Z","caller":"traceutil/trace.go:171","msg":"trace[1506941653] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"182.229323ms","start":"2026-05-12T05:42:33.144992Z","end":"2026-05-12T05:42:33.327221Z","steps":["trace[1506941653] 'process raft request' (duration: 52.766636ms)","trace[1506941653] 'compare' (duration: 128.936239ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:34.853387Z","caller":"traceutil/trace.go:171","msg":"trace[2075262366] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"137.036729ms","start":"2026-05-12T05:42:34.716328Z","end":"2026-05-12T05:42:34.853365Z","steps":["trace[2075262366] 'process raft request' (duration: 136.884587ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:35.33159Z","caller":"traceutil/trace.go:171","msg":"trace[565777084] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"154.834877ms","start":"2026-05-12T05:42:35.176724Z","end":"2026-05-12T05:42:35.331559Z","steps":["trace[565777084] 'process raft request' (duration: 96.143711ms)","trace[565777084] 'compare' (duration: 58.350581ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:35.499675Z","caller":"traceutil/trace.go:171","msg":"trace[1930001962] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"103.509162ms","start":"2026-05-12T05:42:35.396124Z","end":"2026-05-12T05:42:35.499633Z","steps":["trace[1930001962] 'read index received' (duration: 54.300809ms)","trace[1930001962] 'applied index is now lower than readState.Index' (duration: 49.206133ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:42:35.500046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.854397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:42:35.500118Z","caller":"traceutil/trace.go:171","msg":"trace[85683269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"104.04915ms","start":"2026-05-12T05:42:35.396049Z","end":"2026-05-12T05:42:35.500098Z","steps":["trace[85683269] 'agreement among raft nodes before linearized reading' (duration: 103.745845ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:35.500713Z","caller":"traceutil/trace.go:171","msg":"trace[282748794] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"164.840986ms","start":"2026-05-12T05:42:35.33585Z","end":"2026-05-12T05:42:35.500691Z","steps":["trace[282748794] 'process raft request' (duration: 114.602509ms)","trace[282748794] 'compare' (duration: 49.006279ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:39.1175Z","caller":"traceutil/trace.go:171","msg":"trace[1187564180] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"166.711149ms","start":"2026-05-12T05:42:38.950745Z","end":"2026-05-12T05:42:39.117456Z","steps":["trace[1187564180] 'process raft request' (duration: 166.332914ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:39.118272Z","caller":"traceutil/trace.go:171","msg":"trace[1364240000] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:593; }","duration":"147.051718ms","start":"2026-05-12T05:42:38.971199Z","end":"2026-05-12T05:42:39.118251Z","steps":["trace[1364240000] 'read index received' (duration: 67.328564ms)","trace[1364240000] 'applied index is now lower than readState.Index' (duration: 79.721814ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:39.118358Z","caller":"traceutil/trace.go:171","msg":"trace[118560848] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"139.668419ms","start":"2026-05-12T05:42:38.978659Z","end":"2026-05-12T05:42:39.118328Z","steps":["trace[118560848] 'process raft request' (duration: 139.515716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:39.118399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.193001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-12T05:42:39.118432Z","caller":"traceutil/trace.go:171","msg":"trace[704798774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"147.276742ms","start":"2026-05-12T05:42:38.971146Z","end":"2026-05-12T05:42:39.118423Z","steps":["trace[704798774] 'agreement among raft nodes before linearized reading' (duration: 147.186181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:39.687929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499512837859637424,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:42:40.188909Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499512837859637424,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:42:40.632125Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.447654229s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-12T05:42:40.675144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.937039ms","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-05-12T05:42:40.675301Z","caller":"traceutil/trace.go:171","msg":"trace[1529110987] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:576; }","duration":"282.131301ms","start":"2026-05-12T05:42:40.39315Z","end":"2026-05-12T05:42:40.675281Z","steps":["trace[1529110987] 'range keys from in-memory index tree' (duration: 281.915488ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:40.675219Z","caller":"traceutil/trace.go:171","msg":"trace[195725529] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.499540128s","start":"2026-05-12T05:42:39.17565Z","end":"2026-05-12T05:42:40.675191Z","steps":["trace[195725529] 'process raft request' (duration: 1.456803073s)","trace[195725529] 'compare' (duration: 42.141687ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:42:40.675696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:39.175628Z","time spent":"1.499969715s","remote":"127.0.0.1:37092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:42:40.976785Z","caller":"traceutil/trace.go:171","msg":"trace[1703134326] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.770709519s","start":"2026-05-12T05:42:39.206036Z","end":"2026-05-12T05:42:40.976745Z","steps":["trace[1703134326] 'process raft request' (duration: 1.764177403s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:40.976942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:39.206016Z","time spent":"1.770854271s","remote":"127.0.0.1:37092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T05:42:40.977009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.78984069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-12T05:42:40.977075Z","caller":"traceutil/trace.go:171","msg":"trace[1082079616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"1.789940571s","start":"2026-05-12T05:42:39.18712Z","end":"2026-05-12T05:42:40.977061Z","steps":["trace[1082079616] 'agreement among raft nodes before linearized reading' (duration: 1.789715398s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:40.977125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:39.187105Z","time spent":"1.790009753s","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T05:42:40.976785Z","caller":"traceutil/trace.go:171","msg":"trace[247426747] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:599; }","duration":"1.789583297s","start":"2026-05-12T05:42:39.187151Z","end":"2026-05-12T05:42:40.976735Z","steps":["trace[247426747] 'read index received' (duration: 1.445334084s)","trace[247426747] 'applied index is now lower than readState.Index' (duration: 344.248033ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:40.977107Z","caller":"traceutil/trace.go:171","msg":"trace[1541502103] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.755861391s","start":"2026-05-12T05:42:39.221208Z","end":"2026-05-12T05:42:40.977069Z","steps":["trace[1541502103] 'process raft request' (duration: 1.755359483s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:40.977311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:39.221181Z","time spent":"1.756025252s","remote":"127.0.0.1:37092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:42:40.98504Z","caller":"traceutil/trace.go:171","msg":"trace[547702812] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"252.176104ms","start":"2026-05-12T05:42:40.732836Z","end":"2026-05-12T05:42:40.985013Z","steps":["trace[547702812] 'process raft request' (duration: 252.013481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:40.986369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636953236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T05:42:40.986433Z","caller":"traceutil/trace.go:171","msg":"trace[42734651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"1.637064837s","start":"2026-05-12T05:42:39.349352Z","end":"2026-05-12T05:42:40.986417Z","steps":["trace[42734651] 'agreement among raft nodes before linearized reading' (duration: 1.636830283s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:40.986476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:39.349332Z","time spent":"1.637135018s","remote":"127.0.0.1:37280","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-05-12T05:42:40.986626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.831244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:42:40.986652Z","caller":"traceutil/trace.go:171","msg":"trace[1730275274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"588.880725ms","start":"2026-05-12T05:42:40.397764Z","end":"2026-05-12T05:42:40.986645Z","steps":["trace[1730275274] 'agreement among raft nodes before linearized reading' (duration: 588.844915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:40.986668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:40.397749Z","time spent":"588.915915ms","remote":"127.0.0.1:37046","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-05-12T05:42:40.986875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.471296048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T05:42:40.986989Z","caller":"traceutil/trace.go:171","msg":"trace[1122562787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"1.471457061s","start":"2026-05-12T05:42:39.515518Z","end":"2026-05-12T05:42:40.986976Z","steps":["trace[1122562787] 'agreement among raft nodes before linearized reading' (duration: 1.471161326s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:40.987035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:39.515499Z","time spent":"1.471526231s","remote":"127.0.0.1:37280","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-05-12T05:42:42.162849Z","caller":"traceutil/trace.go:171","msg":"trace[1645313521] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"272.541725ms","start":"2026-05-12T05:42:41.890285Z","end":"2026-05-12T05:42:42.162827Z","steps":["trace[1645313521] 'read index received' (duration: 272.1197ms)","trace[1645313521] 'applied index is now lower than readState.Index' (duration: 421.185µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:42.163034Z","caller":"traceutil/trace.go:171","msg":"trace[941022345] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"282.550231ms","start":"2026-05-12T05:42:41.88045Z","end":"2026-05-12T05:42:42.163Z","steps":["trace[941022345] 'process raft request' (duration: 282.044694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:42.163141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.83915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-05-12T05:42:42.163188Z","caller":"traceutil/trace.go:171","msg":"trace[840667734] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:599; }","duration":"272.951441ms","start":"2026-05-12T05:42:41.890224Z","end":"2026-05-12T05:42:42.163176Z","steps":["trace[840667734] 'agreement among raft nodes before linearized reading' (duration: 272.720668ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:42.191215Z","caller":"traceutil/trace.go:171","msg":"trace[247986038] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"283.456292ms","start":"2026-05-12T05:42:41.90773Z","end":"2026-05-12T05:42:42.191186Z","steps":["trace[247986038] 'process raft request' (duration: 282.891314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:47.368138Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.306002251s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T05:42:47.370864Z","caller":"traceutil/trace.go:171","msg":"trace[162375797] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"355.614939ms","start":"2026-05-12T05:42:47.015029Z","end":"2026-05-12T05:42:47.370644Z","steps":["trace[162375797] 'read index received' (duration: 353.287757ms)","trace[162375797] 'applied index is now lower than readState.Index' (duration: 2.326182ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:47.371436Z","caller":"traceutil/trace.go:171","msg":"trace[1883211720] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.310560246s","start":"2026-05-12T05:42:46.060638Z","end":"2026-05-12T05:42:47.371198Z","steps":["trace[1883211720] 'process raft request' (duration: 1.308560348s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:47.371477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.36703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-05-12T05:42:47.371644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:46.060617Z","time spent":"1.310906391s","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036217,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:42:47.371664Z","caller":"traceutil/trace.go:171","msg":"trace[562544619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"356.593023ms","start":"2026-05-12T05:42:47.014981Z","end":"2026-05-12T05:42:47.371574Z","steps":["trace[562544619] 'agreement among raft nodes before linearized reading' (duration: 355.967264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:47.371708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.983956ms","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-05-12T05:42:47.37179Z","caller":"traceutil/trace.go:171","msg":"trace[162932181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"332.108007ms","start":"2026-05-12T05:42:47.03966Z","end":"2026-05-12T05:42:47.371768Z","steps":["trace[162932181] 'agreement among raft nodes before linearized reading' (duration: 331.754082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:47.371778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:47.014958Z","time spent":"356.800676ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T05:42:47.371851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:47.039636Z","time spent":"332.204279ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T05:42:47.372419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.79939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T05:42:47.372459Z","caller":"traceutil/trace.go:171","msg":"trace[1126140149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"191.880752ms","start":"2026-05-12T05:42:47.180563Z","end":"2026-05-12T05:42:47.372443Z","steps":["trace[1126140149] 'agreement among raft nodes before linearized reading' (duration: 191.788701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:47.372587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.723296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-12T05:42:47.372667Z","caller":"traceutil/trace.go:171","msg":"trace[1891529088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"332.817207ms","start":"2026-05-12T05:42:47.039819Z","end":"2026-05-12T05:42:47.372636Z","steps":["trace[1891529088] 'agreement among raft nodes before linearized reading' (duration: 332.632564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:47.372793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:47.039804Z","time spent":"332.976389ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-12T05:42:47.962339Z","caller":"traceutil/trace.go:171","msg":"trace[917523710] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"116.735124ms","start":"2026-05-12T05:42:47.845578Z","end":"2026-05-12T05:42:47.962313Z","steps":["trace[917523710] 'process raft request' (duration: 116.177885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:49.670752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.586228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-12T05:42:49.670817Z","caller":"traceutil/trace.go:171","msg":"trace[182159984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:644; }","duration":"249.69821ms","start":"2026-05-12T05:42:49.421104Z","end":"2026-05-12T05:42:49.670802Z","steps":["trace[182159984] 'range keys from in-memory index tree' (duration: 249.362695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:49.670871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.390337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:42:49.67095Z","caller":"traceutil/trace.go:171","msg":"trace[1489310007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"275.52539ms","start":"2026-05-12T05:42:49.395405Z","end":"2026-05-12T05:42:49.670931Z","steps":["trace[1489310007] 'range keys from in-memory index tree' (duration: 275.293626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:49.671025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.38833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-12T05:42:49.671088Z","caller":"traceutil/trace.go:171","msg":"trace[2069728879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"283.551462ms","start":"2026-05-12T05:42:49.387522Z","end":"2026-05-12T05:42:49.671073Z","steps":["trace[2069728879] 'range keys from in-memory index tree' (duration: 283.083445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:49.671226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.195458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T05:42:49.671256Z","caller":"traceutil/trace.go:171","msg":"trace[1307835605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:644; }","duration":"250.263738ms","start":"2026-05-12T05:42:49.420985Z","end":"2026-05-12T05:42:49.671249Z","steps":["trace[1307835605] 'range keys from in-memory index tree' (duration: 249.400276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:49.671372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.744171ms","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-05-12T05:42:49.671461Z","caller":"traceutil/trace.go:171","msg":"trace[232306265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"249.860763ms","start":"2026-05-12T05:42:49.42158Z","end":"2026-05-12T05:42:49.671441Z","steps":["trace[232306265] 'range keys from in-memory index tree' (duration: 249.289174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:50.665959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.008066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-12T05:42:50.666039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.50443ms","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-05-12T05:42:50.666111Z","caller":"traceutil/trace.go:171","msg":"trace[927544644] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"990.79058ms","start":"2026-05-12T05:42:49.675302Z","end":"2026-05-12T05:42:50.666093Z","steps":["trace[927544644] 'process raft request' (duration: 554.568311ms)","trace[927544644] 'compare' (duration: 435.909465ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:50.666171Z","caller":"traceutil/trace.go:171","msg":"trace[127013638] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:645; }","duration":"272.873955ms","start":"2026-05-12T05:42:50.393277Z","end":"2026-05-12T05:42:50.666151Z","steps":["trace[127013638] 'range keys from in-memory index tree' (duration: 272.48878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:50.666194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:49.675277Z","time spent":"990.883461ms","remote":"127.0.0.1:37280","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-05-12T05:42:50.666447Z","caller":"traceutil/trace.go:171","msg":"trace[1327945782] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"990.73203ms","start":"2026-05-12T05:42:49.675684Z","end":"2026-05-12T05:42:50.666416Z","steps":["trace[1327945782] 'process raft request' (duration: 990.367745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:50.666612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:49.675675Z","time spent":"990.849161ms","remote":"127.0.0.1:37280","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-05-12T05:42:50.666574Z","caller":"traceutil/trace.go:171","msg":"trace[1817611904] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"989.305269ms","start":"2026-05-12T05:42:49.677236Z","end":"2026-05-12T05:42:50.666541Z","steps":["trace[1817611904] 'process raft request' (duration: 989.038546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:50.666777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:49.677219Z","time spent":"989.483301ms","remote":"127.0.0.1:37280","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-05-12T05:42:53.171415Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499512837859637643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:42:53.672033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499512837859637643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:42:53.871693Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.134021817s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T05:42:53.871977Z","caller":"traceutil/trace.go:171","msg":"trace[1976195069] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"2.134423013s","start":"2026-05-12T05:42:51.737537Z","end":"2026-05-12T05:42:53.87196Z","steps":["trace[1976195069] 'process raft request' (duration: 2.134311451s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:53.872566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:51.737511Z","time spent":"2.134954969s","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:42:53.883187Z","caller":"traceutil/trace.go:171","msg":"trace[1942348815] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:674; }","duration":"1.212607115s","start":"2026-05-12T05:42:52.67056Z","end":"2026-05-12T05:42:53.883167Z","steps":["trace[1942348815] 'read index received' (duration: 1.201384802s)","trace[1942348815] 'applied index is now lower than readState.Index' (duration: 11.221503ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:42:53.883274Z","caller":"traceutil/trace.go:171","msg":"trace[1177372048] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"824.337001ms","start":"2026-05-12T05:42:53.058913Z","end":"2026-05-12T05:42:53.88325Z","steps":["trace[1177372048] 'process raft request' (duration: 824.21011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:53.883421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:53.058884Z","time spent":"824.465534ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:42:53.883545Z","caller":"traceutil/trace.go:171","msg":"trace[1035445828] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"836.116242ms","start":"2026-05-12T05:42:53.047418Z","end":"2026-05-12T05:42:53.883535Z","steps":["trace[1035445828] 'process raft request' (duration: 835.609966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:53.883606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:53.047397Z","time spent":"836.177593ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:42:53.883712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213103141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-12T05:42:53.883539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21296136s","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-05-12T05:42:53.88378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.962147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:42:53.883812Z","caller":"traceutil/trace.go:171","msg":"trace[57363134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"1.213286064s","start":"2026-05-12T05:42:52.670509Z","end":"2026-05-12T05:42:53.883795Z","steps":["trace[57363134] 'agreement among raft nodes before linearized reading' (duration: 1.212847288s)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:53.883837Z","caller":"traceutil/trace.go:171","msg":"trace[218091891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"1.213209603s","start":"2026-05-12T05:42:52.670561Z","end":"2026-05-12T05:42:53.883771Z","steps":["trace[218091891] 'agreement among raft nodes before linearized reading' (duration: 1.213055181s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:53.883942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:52.670538Z","time spent":"1.213391005s","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T05:42:53.883877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:52.670488Z","time spent":"1.213373796s","remote":"127.0.0.1:37280","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-05-12T05:42:53.884036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213180192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-12T05:42:53.884113Z","caller":"traceutil/trace.go:171","msg":"trace[2016026148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:651; }","duration":"1.213281264s","start":"2026-05-12T05:42:52.67081Z","end":"2026-05-12T05:42:53.884091Z","steps":["trace[2016026148] 'agreement among raft nodes before linearized reading' (duration: 1.21304042s)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:42:53.883829Z","caller":"traceutil/trace.go:171","msg":"trace[2032709823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"487.04667ms","start":"2026-05-12T05:42:53.39677Z","end":"2026-05-12T05:42:53.883817Z","steps":["trace[2032709823] 'agreement among raft nodes before linearized reading' (duration: 486.971509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:53.884183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:53.396749Z","time spent":"487.425245ms","remote":"127.0.0.1:37046","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-05-12T05:42:53.884179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:52.670804Z","time spent":"1.213361965s","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T05:42:56.722827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.980939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:42:56.722905Z","caller":"traceutil/trace.go:171","msg":"trace[501599042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:669; }","duration":"327.11057ms","start":"2026-05-12T05:42:56.395771Z","end":"2026-05-12T05:42:56.722882Z","steps":["trace[501599042] 'range keys from in-memory index tree' (duration: 326.888668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:42:56.722951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:56.395751Z","time spent":"327.189161ms","remote":"127.0.0.1:37046","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-05-12T05:42:59.342552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.457713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-05-12T05:42:59.342639Z","caller":"traceutil/trace.go:171","msg":"trace[1293834841] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:721; }","duration":"174.597324ms","start":"2026-05-12T05:42:59.16802Z","end":"2026-05-12T05:42:59.342617Z","steps":["trace[1293834841] 'range keys from in-memory index tree' (duration: 174.27706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:00.270384Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499512837859637766,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T05:43:00.478308Z","caller":"traceutil/trace.go:171","msg":"trace[1033418431] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"825.668368ms","start":"2026-05-12T05:42:59.652589Z","end":"2026-05-12T05:43:00.478257Z","steps":["trace[1033418431] 'process raft request' (duration: 825.519596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:00.478465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:59.65257Z","time spent":"825.827109ms","remote":"127.0.0.1:37114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T05:43:00.587346Z","caller":"traceutil/trace.go:171","msg":"trace[1998513208] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:749; }","duration":"817.600545ms","start":"2026-05-12T05:42:59.76972Z","end":"2026-05-12T05:43:00.587321Z","steps":["trace[1998513208] 'read index received' (duration: 708.411534ms)","trace[1998513208] 'applied index is now lower than readState.Index' (duration: 109.188181ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:43:00.587422Z","caller":"traceutil/trace.go:171","msg":"trace[1967653130] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"390.309461ms","start":"2026-05-12T05:43:00.197071Z","end":"2026-05-12T05:43:00.587381Z","steps":["trace[1967653130] 'process raft request' (duration: 375.955432ms)","trace[1967653130] 'compare' (duration: 14.030255ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:43:00.587569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:00.197037Z","time spent":"390.465933ms","remote":"127.0.0.1:37092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T05:43:00.587678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.949459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-05-12T05:43:00.587727Z","caller":"traceutil/trace.go:171","msg":"trace[845466149] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:724; }","duration":"818.04583ms","start":"2026-05-12T05:42:59.769667Z","end":"2026-05-12T05:43:00.587712Z","steps":["trace[845466149] 'agreement among raft nodes before linearized reading' (duration: 817.870037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:00.587768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:59.769647Z","time spent":"818.11156ms","remote":"127.0.0.1:37092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-05-12T05:43:00.587955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.446924ms","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-05-12T05:43:00.587991Z","caller":"traceutil/trace.go:171","msg":"trace[909583337] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:724; }","duration":"618.505964ms","start":"2026-05-12T05:42:59.969476Z","end":"2026-05-12T05:43:00.587982Z","steps":["trace[909583337] 'agreement among raft nodes before linearized reading' (duration: 618.422133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:00.588018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:59.969462Z","time spent":"618.549404ms","remote":"127.0.0.1:37092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4204,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-05-12T05:43:00.588088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.432692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-12T05:43:00.588103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.359715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-05-12T05:43:00.588134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.004581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ptwjp\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-05-12T05:43:00.588145Z","caller":"traceutil/trace.go:171","msg":"trace[1999413684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:724; }","duration":"673.509623ms","start":"2026-05-12T05:42:59.91462Z","end":"2026-05-12T05:43:00.588129Z","steps":["trace[1999413684] 'agreement among raft nodes before linearized reading' (duration: 673.343951ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:00.588158Z","caller":"traceutil/trace.go:171","msg":"trace[1376307785] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ptwjp; range_end:; response_count:1; response_revision:724; }","duration":"513.057292ms","start":"2026-05-12T05:43:00.075094Z","end":"2026-05-12T05:43:00.588151Z","steps":["trace[1376307785] 'agreement among raft nodes before linearized reading' (duration: 512.980261ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:00.58816Z","caller":"traceutil/trace.go:171","msg":"trace[289718256] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:724; }","duration":"219.449127ms","start":"2026-05-12T05:43:00.368691Z","end":"2026-05-12T05:43:00.58814Z","steps":["trace[289718256] 'agreement among raft nodes before linearized reading' (duration: 219.291775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:00.588184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:00.075072Z","time spent":"513.106183ms","remote":"127.0.0.1:37202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18361,"request content":"key:\"/registry/pods/kube-system/cilium-ptwjp\" "} {"level":"warn","ts":"2026-05-12T05:43:00.588187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.381235ms","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-05-12T05:43:00.588189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:59.914608Z","time spent":"673.569665ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T05:43:00.588255Z","caller":"traceutil/trace.go:171","msg":"trace[915983300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:724; }","duration":"692.489505ms","start":"2026-05-12T05:42:59.895754Z","end":"2026-05-12T05:43:00.588243Z","steps":["trace[915983300] 'agreement among raft nodes before linearized reading' (duration: 692.304213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:00.588289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:59.895736Z","time spent":"692.545196ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T05:43:00.58823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.516794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-12T05:43:00.588347Z","caller":"traceutil/trace.go:171","msg":"trace[879132290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:724; }","duration":"673.691776ms","start":"2026-05-12T05:42:59.914636Z","end":"2026-05-12T05:43:00.588328Z","steps":["trace[879132290] 'agreement among raft nodes before linearized reading' (duration: 673.400212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:00.588394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:59.914612Z","time spent":"673.772037ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T05:43:00.588374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.921499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T05:43:00.588459Z","caller":"traceutil/trace.go:171","msg":"trace[989864801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:724; }","duration":"674.057501ms","start":"2026-05-12T05:42:59.914382Z","end":"2026-05-12T05:43:00.58844Z","steps":["trace[989864801] 'agreement among raft nodes before linearized reading' (duration: 673.760157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:00.588506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:42:59.914371Z","time spent":"674.126552ms","remote":"127.0.0.1:37280","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-05-12T05:43:00.733773Z","caller":"traceutil/trace.go:171","msg":"trace[1733623108] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"130.499652ms","start":"2026-05-12T05:43:00.603233Z","end":"2026-05-12T05:43:00.733733Z","steps":["trace[1733623108] 'process raft request' (duration: 129.6177ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:00.733839Z","caller":"traceutil/trace.go:171","msg":"trace[1428127261] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"130.493972ms","start":"2026-05-12T05:43:00.603312Z","end":"2026-05-12T05:43:00.733806Z","steps":["trace[1428127261] 'process raft request' (duration: 130.31494ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:00.733986Z","caller":"traceutil/trace.go:171","msg":"trace[1189411097] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"130.550853ms","start":"2026-05-12T05:43:00.603405Z","end":"2026-05-12T05:43:00.733956Z","steps":["trace[1189411097] 'process raft request' (duration: 130.30867ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:00.734275Z","caller":"traceutil/trace.go:171","msg":"trace[252960866] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"124.26048ms","start":"2026-05-12T05:43:00.609997Z","end":"2026-05-12T05:43:00.734258Z","steps":["trace[252960866] 'process raft request' (duration: 123.913396ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:00.735933Z","caller":"traceutil/trace.go:171","msg":"trace[1969525748] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"108.035705ms","start":"2026-05-12T05:43:00.62787Z","end":"2026-05-12T05:43:00.735906Z","steps":["trace[1969525748] 'process raft request' (duration: 106.291763ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:08.926109Z","caller":"traceutil/trace.go:171","msg":"trace[1162510637] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"155.806105ms","start":"2026-05-12T05:43:08.770276Z","end":"2026-05-12T05:43:08.926082Z","steps":["trace[1162510637] 'read index received' (duration: 155.628183ms)","trace[1162510637] 'applied index is now lower than readState.Index' (duration: 176.642µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:43:08.926255Z","caller":"traceutil/trace.go:171","msg":"trace[1917147154] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"258.956807ms","start":"2026-05-12T05:43:08.667289Z","end":"2026-05-12T05:43:08.926246Z","steps":["trace[1917147154] 'process raft request' (duration: 258.598222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:08.92648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.199009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-12T05:43:08.926523Z","caller":"traceutil/trace.go:171","msg":"trace[1311434611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"156.30026ms","start":"2026-05-12T05:43:08.770209Z","end":"2026-05-12T05:43:08.92651Z","steps":["trace[1311434611] 'agreement among raft nodes before linearized reading' (duration: 156.165958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:08.926701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.356921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-12T05:43:08.926801Z","caller":"traceutil/trace.go:171","msg":"trace[1327997960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"156.530303ms","start":"2026-05-12T05:43:08.770257Z","end":"2026-05-12T05:43:08.926788Z","steps":["trace[1327997960] 'agreement among raft nodes before linearized reading' (duration: 156.22059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:08.927332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.980087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T05:43:08.927545Z","caller":"traceutil/trace.go:171","msg":"trace[1662397524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:795; }","duration":"127.18106ms","start":"2026-05-12T05:43:08.800261Z","end":"2026-05-12T05:43:08.927442Z","steps":["trace[1662397524] 'agreement among raft nodes before linearized reading' (duration: 126.39228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:13.467277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499512837859637964,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:43:13.967991Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499512837859637964,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:43:14.469209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499512837859637964,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T05:43:14.718391Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.95426166s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-12T05:43:15.164233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.59515ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-12T05:43:15.167323Z","caller":"traceutil/trace.go:171","msg":"trace[518346376] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"1.925486272s","start":"2026-05-12T05:43:13.24181Z","end":"2026-05-12T05:43:15.167297Z","steps":["trace[518346376] 'process raft request' (duration: 1.925226229s)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:15.167358Z","caller":"traceutil/trace.go:171","msg":"trace[97650192] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:845; }","duration":"2.200458303s","start":"2026-05-12T05:43:12.966882Z","end":"2026-05-12T05:43:15.167341Z","steps":["trace[97650192] 'read index received' (duration: 1.751736044s)","trace[97650192] 'applied index is now lower than readState.Index' (duration: 448.721319ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:43:15.167405Z","caller":"traceutil/trace.go:171","msg":"trace[1730357689] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"1.148299262s","start":"2026-05-12T05:43:14.019082Z","end":"2026-05-12T05:43:15.167381Z","steps":["trace[1730357689] 'process raft request' (duration: 1.14811168s)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:15.167459Z","caller":"traceutil/trace.go:171","msg":"trace[170030912] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"1.0032756s","start":"2026-05-12T05:43:14.164173Z","end":"2026-05-12T05:43:15.167448Z","steps":["trace[170030912] 'process raft request' (duration: 1.003128068s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.167471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:13.24178Z","time spent":"1.925613724s","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:43:15.167537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.164141Z","time spent":"1.00335257s","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:43:15.167639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.019056Z","time spent":"1.148405273s","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T05:43:15.167776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.200894289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T05:43:15.167823Z","caller":"traceutil/trace.go:171","msg":"trace[1829283697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:817; }","duration":"2.20097638s","start":"2026-05-12T05:43:12.966836Z","end":"2026-05-12T05:43:15.167813Z","steps":["trace[1829283697] 'agreement among raft nodes before linearized reading' (duration: 2.200771968s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.167863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:12.966818Z","time spent":"2.201038051s","remote":"127.0.0.1:37280","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-05-12T05:43:15.168025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.621126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:43:15.168053Z","caller":"traceutil/trace.go:171","msg":"trace[1705236210] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:817; }","duration":"864.653797ms","start":"2026-05-12T05:43:14.303392Z","end":"2026-05-12T05:43:15.168046Z","steps":["trace[1705236210] 'agreement among raft nodes before linearized reading' (duration: 864.602466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.168075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.303377Z","time spent":"864.691477ms","remote":"127.0.0.1:56576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:43:15.168064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.310739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T05:43:15.168176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.90932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T05:43:15.168229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.192840657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-12T05:43:15.168248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.118812794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T05:43:15.168289Z","caller":"traceutil/trace.go:171","msg":"trace[542879049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:817; }","duration":"2.19294683s","start":"2026-05-12T05:43:12.975329Z","end":"2026-05-12T05:43:15.168275Z","steps":["trace[542879049] 'agreement among raft nodes before linearized reading' (duration: 2.192734497s)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:15.16829Z","caller":"traceutil/trace.go:171","msg":"trace[811693538] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:817; }","duration":"2.118895695s","start":"2026-05-12T05:43:13.049384Z","end":"2026-05-12T05:43:15.168279Z","steps":["trace[811693538] 'agreement among raft nodes before linearized reading' (duration: 2.118786863s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.168312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.966219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:43:15.168287Z","caller":"traceutil/trace.go:171","msg":"trace[1329459705] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:817; }","duration":"865.041592ms","start":"2026-05-12T05:43:14.303225Z","end":"2026-05-12T05:43:15.168267Z","steps":["trace[1329459705] 'agreement among raft nodes before linearized reading' (duration: 864.893161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.168334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:13.049348Z","time spent":"2.118975685s","remote":"127.0.0.1:37180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:43:15.168365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.303209Z","time spent":"865.148114ms","remote":"127.0.0.1:56576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-05-12T05:43:15.168366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.77221764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:43:15.168388Z","caller":"traceutil/trace.go:171","msg":"trace[1754952983] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:817; }","duration":"864.036499ms","start":"2026-05-12T05:43:14.304309Z","end":"2026-05-12T05:43:15.168346Z","steps":["trace[1754952983] 'agreement among raft nodes before linearized reading' (duration: 863.972459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.168608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.30429Z","time spent":"864.303192ms","remote":"127.0.0.1:56576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-05-12T05:43:15.168326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:12.975321Z","time spent":"2.19299738s","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T05:43:15.168729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.899168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:43:15.168794Z","caller":"traceutil/trace.go:171","msg":"trace[330680181] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:817; }","duration":"834.971008ms","start":"2026-05-12T05:43:14.333797Z","end":"2026-05-12T05:43:15.168768Z","steps":["trace[330680181] 'agreement among raft nodes before linearized reading' (duration: 834.899288ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:15.1684Z","caller":"traceutil/trace.go:171","msg":"trace[1125859252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:817; }","duration":"1.772291891s","start":"2026-05-12T05:43:13.396099Z","end":"2026-05-12T05:43:15.168391Z","steps":["trace[1125859252] 'agreement among raft nodes before linearized reading' (duration: 1.77221224s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.168875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:13.396076Z","time spent":"1.772786068s","remote":"127.0.0.1:37046","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-05-12T05:43:15.168887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.333785Z","time spent":"835.06748ms","remote":"127.0.0.1:56584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-05-12T05:43:15.168812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.339533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T05:43:15.169025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.606408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:43:15.169052Z","caller":"traceutil/trace.go:171","msg":"trace[1619583003] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:817; }","duration":"835.664108ms","start":"2026-05-12T05:43:14.33338Z","end":"2026-05-12T05:43:15.169044Z","steps":["trace[1619583003] 'agreement among raft nodes before linearized reading' (duration: 835.612037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.169056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.193951193s","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-05-12T05:43:15.169077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.333368Z","time spent":"835.702688ms","remote":"127.0.0.1:56584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"info","ts":"2026-05-12T05:43:15.169091Z","caller":"traceutil/trace.go:171","msg":"trace[50738883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:817; }","duration":"2.194029473s","start":"2026-05-12T05:43:12.975051Z","end":"2026-05-12T05:43:15.169081Z","steps":["trace[50738883] 'agreement among raft nodes before linearized reading' (duration: 2.193631737s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.169116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:12.975032Z","time spent":"2.194078704s","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T05:43:15.169148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.3272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:43:15.169167Z","caller":"traceutil/trace.go:171","msg":"trace[1065181467] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:817; }","duration":"850.37266ms","start":"2026-05-12T05:43:14.318788Z","end":"2026-05-12T05:43:15.169161Z","steps":["trace[1065181467] 'agreement among raft nodes before linearized reading' (duration: 850.33966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.169187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.318764Z","time spent":"850.416751ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-05-12T05:43:15.169242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.479634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:43:15.16928Z","caller":"traceutil/trace.go:171","msg":"trace[1174163138] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:817; }","duration":"851.529565ms","start":"2026-05-12T05:43:14.317741Z","end":"2026-05-12T05:43:15.169271Z","steps":["trace[1174163138] 'agreement among raft nodes before linearized reading' (duration: 851.480604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.169299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.317735Z","time spent":"851.558365ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-05-12T05:43:15.169372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.918194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:43:15.169407Z","caller":"traceutil/trace.go:171","msg":"trace[1668366385] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:817; }","duration":"820.983495ms","start":"2026-05-12T05:43:14.348414Z","end":"2026-05-12T05:43:15.169397Z","steps":["trace[1668366385] 'agreement among raft nodes before linearized reading' (duration: 820.925794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.168934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.361728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T05:43:15.169435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.348397Z","time spent":"821.029335ms","remote":"127.0.0.1:56596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-12T05:43:15.169443Z","caller":"traceutil/trace.go:171","msg":"trace[1429257495] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:817; }","duration":"820.949685ms","start":"2026-05-12T05:43:14.348485Z","end":"2026-05-12T05:43:15.169434Z","steps":["trace[1429257495] 'agreement among raft nodes before linearized reading' (duration: 820.356236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.169668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.348476Z","time spent":"820.986115ms","remote":"127.0.0.1:56596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-12T05:43:15.168087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.113480227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T05:43:15.168974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.238136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:43:15.169734Z","caller":"traceutil/trace.go:171","msg":"trace[1125819005] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:817; }","duration":"821.059346ms","start":"2026-05-12T05:43:14.348664Z","end":"2026-05-12T05:43:15.169724Z","steps":["trace[1125819005] 'agreement among raft nodes before linearized reading' (duration: 820.286557ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:15.16973Z","caller":"traceutil/trace.go:171","msg":"trace[780311230] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:817; }","duration":"2.115174408s","start":"2026-05-12T05:43:13.054544Z","end":"2026-05-12T05:43:15.169718Z","steps":["trace[780311230] 'agreement among raft nodes before linearized reading' (duration: 2.113443726s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.169764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.348631Z","time spent":"821.124336ms","remote":"127.0.0.1:56596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:43:15.169776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:13.054494Z","time spent":"2.115266149s","remote":"127.0.0.1:37316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:43:15.170217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.19537668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-12T05:43:15.170252Z","caller":"traceutil/trace.go:171","msg":"trace[2045480953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:817; }","duration":"2.1954507s","start":"2026-05-12T05:43:12.974792Z","end":"2026-05-12T05:43:15.170242Z","steps":["trace[2045480953] 'agreement among raft nodes before linearized reading' (duration: 2.193155642s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.170252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.194902814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-12T05:43:15.170279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:12.974773Z","time spent":"2.195498651s","remote":"127.0.0.1:37280","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-05-12T05:43:15.170326Z","caller":"traceutil/trace.go:171","msg":"trace[1249999921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:817; }","duration":"2.195005465s","start":"2026-05-12T05:43:12.975279Z","end":"2026-05-12T05:43:15.170285Z","steps":["trace[1249999921] 'agreement among raft nodes before linearized reading' (duration: 2.193271513s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.170369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:12.975273Z","time spent":"2.195082206s","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T05:43:15.172587Z","caller":"traceutil/trace.go:171","msg":"trace[1687759528] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:817; }","duration":"850.42815ms","start":"2026-05-12T05:43:14.317694Z","end":"2026-05-12T05:43:15.168122Z","steps":["trace[1687759528] 'agreement among raft nodes before linearized reading' (duration: 850.258398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.172631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.317682Z","time spent":"854.936047ms","remote":"127.0.0.1:56582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-05-12T05:43:15.169027Z","caller":"traceutil/trace.go:171","msg":"trace[1754604866] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:817; }","duration":"835.630457ms","start":"2026-05-12T05:43:14.333377Z","end":"2026-05-12T05:43:15.169007Z","steps":["trace[1754604866] 'agreement among raft nodes before linearized reading' (duration: 835.341443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.172804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:43:14.333358Z","time spent":"839.417934ms","remote":"127.0.0.1:56584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-05-12T05:43:15.384422Z","caller":"traceutil/trace.go:171","msg":"trace[169767073] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"209.270988ms","start":"2026-05-12T05:43:15.175118Z","end":"2026-05-12T05:43:15.384389Z","steps":["trace[169767073] 'process raft request' (duration: 206.442043ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:15.384543Z","caller":"traceutil/trace.go:171","msg":"trace[918547588] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"208.819524ms","start":"2026-05-12T05:43:15.17568Z","end":"2026-05-12T05:43:15.3845Z","steps":["trace[918547588] 'process raft request' (duration: 208.611551ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:15.384621Z","caller":"traceutil/trace.go:171","msg":"trace[1721379388] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"208.666653ms","start":"2026-05-12T05:43:15.175938Z","end":"2026-05-12T05:43:15.384605Z","steps":["trace[1721379388] 'process raft request' (duration: 208.415899ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:15.384422Z","caller":"traceutil/trace.go:171","msg":"trace[443561246] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:849; }","duration":"207.131813ms","start":"2026-05-12T05:43:15.177274Z","end":"2026-05-12T05:43:15.384405Z","steps":["trace[443561246] 'read index received' (duration: 59.631168ms)","trace[443561246] 'applied index is now lower than readState.Index' (duration: 147.497715ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:43:15.384852Z","caller":"traceutil/trace.go:171","msg":"trace[906790554] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"209.28226ms","start":"2026-05-12T05:43:15.175314Z","end":"2026-05-12T05:43:15.384596Z","steps":["trace[906790554] 'process raft request' (duration: 208.867454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:15.384915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.621779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:43:15.385115Z","caller":"traceutil/trace.go:171","msg":"trace[882257425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:822; }","duration":"207.850913ms","start":"2026-05-12T05:43:15.17725Z","end":"2026-05-12T05:43:15.385101Z","steps":["trace[882257425] 'agreement among raft nodes before linearized reading' (duration: 207.57597ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:19.836397Z","caller":"traceutil/trace.go:171","msg":"trace[1580222723] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"107.249548ms","start":"2026-05-12T05:43:19.729124Z","end":"2026-05-12T05:43:19.836373Z","steps":["trace[1580222723] 'process raft request' (duration: 106.724111ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:21.900943Z","caller":"traceutil/trace.go:171","msg":"trace[1399568145] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"123.895048ms","start":"2026-05-12T05:43:21.777017Z","end":"2026-05-12T05:43:21.900912Z","steps":["trace[1399568145] 'process raft request' (duration: 123.686426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:26.505999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.77714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12751"} {"level":"info","ts":"2026-05-12T05:43:26.506123Z","caller":"traceutil/trace.go:171","msg":"trace[961074118] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:967; }","duration":"106.935362ms","start":"2026-05-12T05:43:26.399166Z","end":"2026-05-12T05:43:26.506102Z","steps":["trace[961074118] 'range keys from in-memory index tree' (duration: 106.619447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:26.506435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.796141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:43:26.506465Z","caller":"traceutil/trace.go:171","msg":"trace[210563551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"112.898022ms","start":"2026-05-12T05:43:26.393557Z","end":"2026-05-12T05:43:26.506455Z","steps":["trace[210563551] 'range keys from in-memory index tree' (duration: 112.71161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:39.71747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.583975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-12T05:43:39.717532Z","caller":"traceutil/trace.go:171","msg":"trace[715483156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1027; }","duration":"112.703146ms","start":"2026-05-12T05:43:39.604814Z","end":"2026-05-12T05:43:39.717517Z","steps":["trace[715483156] 'range keys from in-memory index tree' (duration: 112.415613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:39.717707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.737488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-12T05:43:39.7178Z","caller":"traceutil/trace.go:171","msg":"trace[1041566526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"128.879799ms","start":"2026-05-12T05:43:39.5889Z","end":"2026-05-12T05:43:39.71778Z","steps":["trace[1041566526] 'range keys from in-memory index tree' (duration: 128.557466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:39.717857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.019883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-12T05:43:39.717876Z","caller":"traceutil/trace.go:171","msg":"trace[1550651597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1027; }","duration":"109.071273ms","start":"2026-05-12T05:43:39.6088Z","end":"2026-05-12T05:43:39.717871Z","steps":["trace[1550651597] 'range keys from in-memory index tree' (duration: 108.931982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:43:39.718417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.159739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T05:43:39.718577Z","caller":"traceutil/trace.go:171","msg":"trace[24166700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"112.745285ms","start":"2026-05-12T05:43:39.605813Z","end":"2026-05-12T05:43:39.718558Z","steps":["trace[24166700] 'range keys from in-memory index tree' (duration: 111.785355ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:39.973594Z","caller":"traceutil/trace.go:171","msg":"trace[2035319706] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"249.89191ms","start":"2026-05-12T05:43:39.723671Z","end":"2026-05-12T05:43:39.973563Z","steps":["trace[2035319706] 'process raft request' (duration: 226.576104ms)","trace[2035319706] 'compare' (duration: 23.007242ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:43:39.973648Z","caller":"traceutil/trace.go:171","msg":"trace[773240764] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"247.074607ms","start":"2026-05-12T05:43:39.726545Z","end":"2026-05-12T05:43:39.973619Z","steps":["trace[773240764] 'process raft request' (duration: 247.018107ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:39.973664Z","caller":"traceutil/trace.go:171","msg":"trace[1515354023] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"248.910209ms","start":"2026-05-12T05:43:39.724735Z","end":"2026-05-12T05:43:39.973645Z","steps":["trace[1515354023] 'process raft request' (duration: 248.734197ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:43:57.71687Z","caller":"traceutil/trace.go:171","msg":"trace[421697320] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"119.133807ms","start":"2026-05-12T05:43:57.597721Z","end":"2026-05-12T05:43:57.716855Z","steps":["trace[421697320] 'process raft request' (duration: 118.663332ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:06.388559Z","caller":"traceutil/trace.go:171","msg":"trace[422528569] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"162.087219ms","start":"2026-05-12T05:44:06.226443Z","end":"2026-05-12T05:44:06.38853Z","steps":["trace[422528569] 'process raft request' (duration: 86.429331ms)","trace[422528569] 'compare' (duration: 75.531926ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:44:06.409383Z","caller":"traceutil/trace.go:171","msg":"trace[1401015590] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1162; }","duration":"147.766017ms","start":"2026-05-12T05:44:06.261591Z","end":"2026-05-12T05:44:06.409357Z","steps":["trace[1401015590] 'read index received' (duration: 51.296698ms)","trace[1401015590] 'applied index is now lower than readState.Index' (duration: 96.468159ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:44:06.409471Z","caller":"traceutil/trace.go:171","msg":"trace[1413309007] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"165.013481ms","start":"2026-05-12T05:44:06.24443Z","end":"2026-05-12T05:44:06.409443Z","steps":["trace[1413309007] 'process raft request' (duration: 164.799649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:44:06.409533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.925799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T05:44:06.409573Z","caller":"traceutil/trace.go:171","msg":"trace[2047552431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1121; }","duration":"148.01159ms","start":"2026-05-12T05:44:06.261554Z","end":"2026-05-12T05:44:06.409566Z","steps":["trace[2047552431] 'agreement among raft nodes before linearized reading' (duration: 147.896538ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:08.301926Z","caller":"traceutil/trace.go:171","msg":"trace[170048096] linearizableReadLoop","detail":"{readStateIndex:1170; appliedIndex:1169; }","duration":"178.082583ms","start":"2026-05-12T05:44:08.123819Z","end":"2026-05-12T05:44:08.301901Z","steps":["trace[170048096] 'read index received' (duration: 177.909181ms)","trace[170048096] 'applied index is now lower than readState.Index' (duration: 172.022µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:44:08.302072Z","caller":"traceutil/trace.go:171","msg":"trace[894946224] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"203.636495ms","start":"2026-05-12T05:44:08.098408Z","end":"2026-05-12T05:44:08.302045Z","steps":["trace[894946224] 'process raft request' (duration: 203.346661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:44:08.302099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.253805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T05:44:08.302145Z","caller":"traceutil/trace.go:171","msg":"trace[17861535] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1126; }","duration":"178.359586ms","start":"2026-05-12T05:44:08.123775Z","end":"2026-05-12T05:44:08.302135Z","steps":["trace[17861535] 'agreement among raft nodes before linearized reading' (duration: 178.266665ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:27.196846Z","caller":"traceutil/trace.go:171","msg":"trace[1806178585] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"280.346664ms","start":"2026-05-12T05:44:26.916468Z","end":"2026-05-12T05:44:27.196815Z","steps":["trace[1806178585] 'process raft request' (duration: 280.178762ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:38.704481Z","caller":"traceutil/trace.go:171","msg":"trace[476410071] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"114.52657ms","start":"2026-05-12T05:44:38.589929Z","end":"2026-05-12T05:44:38.704456Z","steps":["trace[476410071] 'process raft request' (duration: 114.339038ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:38.91067Z","caller":"traceutil/trace.go:171","msg":"trace[41447223] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"202.57154ms","start":"2026-05-12T05:44:38.708062Z","end":"2026-05-12T05:44:38.910634Z","steps":["trace[41447223] 'process raft request' (duration: 131.389051ms)","trace[41447223] 'compare' (duration: 71.031748ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:44:38.910895Z","caller":"traceutil/trace.go:171","msg":"trace[449230831] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1275; }","duration":"199.150246ms","start":"2026-05-12T05:44:38.711718Z","end":"2026-05-12T05:44:38.910869Z","steps":["trace[449230831] 'read index received' (duration: 127.807204ms)","trace[449230831] 'applied index is now lower than readState.Index' (duration: 71.340092ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:44:38.911716Z","caller":"traceutil/trace.go:171","msg":"trace[246009907] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"199.317638ms","start":"2026-05-12T05:44:38.712369Z","end":"2026-05-12T05:44:38.911687Z","steps":["trace[246009907] 'process raft request' (duration: 199.228927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:44:38.913479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.740522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T05:44:38.913575Z","caller":"traceutil/trace.go:171","msg":"trace[968156252] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1229; }","duration":"201.891884ms","start":"2026-05-12T05:44:38.711662Z","end":"2026-05-12T05:44:38.913554Z","steps":["trace[968156252] 'agreement among raft nodes before linearized reading' (duration: 201.166907ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:38.911815Z","caller":"traceutil/trace.go:171","msg":"trace[1527084516] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"199.58567ms","start":"2026-05-12T05:44:38.7122Z","end":"2026-05-12T05:44:38.911785Z","steps":["trace[1527084516] 'process raft request' (duration: 198.441399ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:38.911868Z","caller":"traceutil/trace.go:171","msg":"trace[1742421185] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"199.529099ms","start":"2026-05-12T05:44:38.712314Z","end":"2026-05-12T05:44:38.911843Z","steps":["trace[1742421185] 'process raft request' (duration: 198.394898ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:40.445264Z","caller":"traceutil/trace.go:171","msg":"trace[295888935] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1279; }","duration":"222.551448ms","start":"2026-05-12T05:44:40.222688Z","end":"2026-05-12T05:44:40.445239Z","steps":["trace[295888935] 'read index received' (duration: 222.312736ms)","trace[295888935] 'applied index is now lower than readState.Index' (duration: 237.642µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:44:40.445588Z","caller":"traceutil/trace.go:171","msg":"trace[701630298] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"289.611214ms","start":"2026-05-12T05:44:40.155958Z","end":"2026-05-12T05:44:40.445569Z","steps":["trace[701630298] 'process raft request' (duration: 289.111639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:44:40.445589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.872141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:44:40.445661Z","caller":"traceutil/trace.go:171","msg":"trace[1606489442] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1230; }","duration":"222.992042ms","start":"2026-05-12T05:44:40.222652Z","end":"2026-05-12T05:44:40.445644Z","steps":["trace[1606489442] 'agreement among raft nodes before linearized reading' (duration: 222.876341ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:47.215622Z","caller":"traceutil/trace.go:171","msg":"trace[569540450] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"182.709251ms","start":"2026-05-12T05:44:47.032888Z","end":"2026-05-12T05:44:47.215597Z","steps":["trace[569540450] 'process raft request' (duration: 153.848441ms)","trace[569540450] 'compare' (duration: 28.659688ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:44:47.215881Z","caller":"traceutil/trace.go:171","msg":"trace[541390557] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"180.106795ms","start":"2026-05-12T05:44:47.035691Z","end":"2026-05-12T05:44:47.215798Z","steps":["trace[541390557] 'process raft request' (duration: 179.832272ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:55.107288Z","caller":"traceutil/trace.go:171","msg":"trace[1606798421] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"120.753379ms","start":"2026-05-12T05:44:54.986512Z","end":"2026-05-12T05:44:55.107265Z","steps":["trace[1606798421] 'read index received' (duration: 71.783163ms)","trace[1606798421] 'applied index is now lower than readState.Index' (duration: 48.969236ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:44:55.107387Z","caller":"traceutil/trace.go:171","msg":"trace[1011444396] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"126.82926ms","start":"2026-05-12T05:44:54.98052Z","end":"2026-05-12T05:44:55.107349Z","steps":["trace[1011444396] 'process raft request' (duration: 77.865593ms)","trace[1011444396] 'compare' (duration: 48.756055ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:44:55.107484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.938421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d72a2e77-588b-4d5a-891c-2406d6d563bc\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-05-12T05:44:55.107864Z","caller":"traceutil/trace.go:171","msg":"trace[1262165342] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d72a2e77-588b-4d5a-891c-2406d6d563bc; range_end:; response_count:1; response_revision:1286; }","duration":"121.266014ms","start":"2026-05-12T05:44:54.986486Z","end":"2026-05-12T05:44:55.107752Z","steps":["trace[1262165342] 'agreement among raft nodes before linearized reading' (duration: 120.90041ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:59.420627Z","caller":"traceutil/trace.go:171","msg":"trace[1433473090] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"210.742014ms","start":"2026-05-12T05:44:59.209854Z","end":"2026-05-12T05:44:59.420596Z","steps":["trace[1433473090] 'process raft request' (duration: 121.24347ms)","trace[1433473090] 'compare' (duration: 89.307932ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:44:59.420779Z","caller":"traceutil/trace.go:171","msg":"trace[793523990] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1365; }","duration":"155.061844ms","start":"2026-05-12T05:44:59.265686Z","end":"2026-05-12T05:44:59.420748Z","steps":["trace[793523990] 'read index received' (duration: 33.881845ms)","trace[793523990] 'applied index is now lower than readState.Index' (duration: 121.178139ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:44:59.420827Z","caller":"traceutil/trace.go:171","msg":"trace[2111628351] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"210.785785ms","start":"2026-05-12T05:44:59.210022Z","end":"2026-05-12T05:44:59.420808Z","steps":["trace[2111628351] 'process raft request' (duration: 210.500981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:44:59.421002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.303066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-12T05:44:59.421062Z","caller":"traceutil/trace.go:171","msg":"trace[1866153591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1313; }","duration":"155.413737ms","start":"2026-05-12T05:44:59.265631Z","end":"2026-05-12T05:44:59.421045Z","steps":["trace[1866153591] 'agreement among raft nodes before linearized reading' (duration: 155.230576ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:44:59.629719Z","caller":"traceutil/trace.go:171","msg":"trace[246742991] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"134.296638ms","start":"2026-05-12T05:44:59.495389Z","end":"2026-05-12T05:44:59.629685Z","steps":["trace[246742991] 'process raft request' (duration: 73.938472ms)","trace[246742991] 'compare' (duration: 60.189315ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:45:00.310106Z","caller":"traceutil/trace.go:171","msg":"trace[512517865] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"171.215951ms","start":"2026-05-12T05:45:00.138864Z","end":"2026-05-12T05:45:00.31008Z","steps":["trace[512517865] 'process raft request' (duration: 171.06759ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:00.70297Z","caller":"traceutil/trace.go:171","msg":"trace[343619855] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"231.554277ms","start":"2026-05-12T05:45:00.471386Z","end":"2026-05-12T05:45:00.70294Z","steps":["trace[343619855] 'process raft request' (duration: 214.265047ms)","trace[343619855] 'compare' (duration: 17.028767ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:45:00.997022Z","caller":"traceutil/trace.go:171","msg":"trace[967386972] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"169.497265ms","start":"2026-05-12T05:45:00.8275Z","end":"2026-05-12T05:45:00.996997Z","steps":["trace[967386972] 'process raft request' (duration: 169.131761ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:01.023442Z","caller":"traceutil/trace.go:171","msg":"trace[1101345172] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1379; }","duration":"165.184742ms","start":"2026-05-12T05:45:00.85823Z","end":"2026-05-12T05:45:01.023414Z","steps":["trace[1101345172] 'read index received' (duration: 138.453788ms)","trace[1101345172] 'applied index is now lower than readState.Index' (duration: 26.729834ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:45:01.02353Z","caller":"traceutil/trace.go:171","msg":"trace[611719573] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"185.534294ms","start":"2026-05-12T05:45:00.837963Z","end":"2026-05-12T05:45:01.023498Z","steps":["trace[611719573] 'process raft request' (duration: 185.313782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:01.02361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.358304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T05:45:01.023653Z","caller":"traceutil/trace.go:171","msg":"trace[1672363870] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1325; }","duration":"165.461874ms","start":"2026-05-12T05:45:00.85818Z","end":"2026-05-12T05:45:01.023642Z","steps":["trace[1672363870] 'agreement among raft nodes before linearized reading' (duration: 165.357843ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:01.209088Z","caller":"traceutil/trace.go:171","msg":"trace[994205812] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"111.42334ms","start":"2026-05-12T05:45:01.097639Z","end":"2026-05-12T05:45:01.209062Z","steps":["trace[994205812] 'read index received' (duration: 95.356461ms)","trace[994205812] 'applied index is now lower than readState.Index' (duration: 16.065759ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:45:01.209165Z","caller":"traceutil/trace.go:171","msg":"trace[489828324] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"131.824322ms","start":"2026-05-12T05:45:01.077297Z","end":"2026-05-12T05:45:01.209122Z","steps":["trace[489828324] 'process raft request' (duration: 115.641151ms)","trace[489828324] 'compare' (duration: 15.955068ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:45:01.209423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.723553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-05-12T05:45:01.209528Z","caller":"traceutil/trace.go:171","msg":"trace[1712625694] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1327; }","duration":"111.912874ms","start":"2026-05-12T05:45:01.097596Z","end":"2026-05-12T05:45:01.209509Z","steps":["trace[1712625694] 'agreement among raft nodes before linearized reading' (duration: 111.563601ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:03.872667Z","caller":"traceutil/trace.go:171","msg":"trace[1588280433] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1393; }","duration":"437.172627ms","start":"2026-05-12T05:45:03.43547Z","end":"2026-05-12T05:45:03.872643Z","steps":["trace[1588280433] 'read index received' (duration: 436.940815ms)","trace[1588280433] 'applied index is now lower than readState.Index' (duration: 228.922µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:45:03.872779Z","caller":"traceutil/trace.go:171","msg":"trace[1679818758] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"463.76243ms","start":"2026-05-12T05:45:03.409002Z","end":"2026-05-12T05:45:03.872765Z","steps":["trace[1679818758] 'process raft request' (duration: 463.462787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:03.872898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:03.408991Z","time spent":"463.837251ms","remote":"127.0.0.1:37280","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-05-12T05:45:03.873101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.611171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-12T05:45:03.873183Z","caller":"traceutil/trace.go:171","msg":"trace[1153008223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1337; }","duration":"437.735722ms","start":"2026-05-12T05:45:03.435426Z","end":"2026-05-12T05:45:03.873161Z","steps":["trace[1153008223] 'agreement among raft nodes before linearized reading' (duration: 437.488009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:03.873229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:03.435411Z","time spent":"437.807563ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T05:45:03.873546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.31891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-12T05:45:03.873613Z","caller":"traceutil/trace.go:171","msg":"trace[1038429947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1337; }","duration":"428.409181ms","start":"2026-05-12T05:45:03.445173Z","end":"2026-05-12T05:45:03.873582Z","steps":["trace[1038429947] 'agreement among raft nodes before linearized reading' (duration: 428.234649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:03.873641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:03.445139Z","time spent":"428.495641ms","remote":"127.0.0.1:37280","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-05-12T05:45:03.87391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.443846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T05:45:03.874001Z","caller":"traceutil/trace.go:171","msg":"trace[756125649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1337; }","duration":"388.579489ms","start":"2026-05-12T05:45:03.485404Z","end":"2026-05-12T05:45:03.873984Z","steps":["trace[756125649] 'agreement among raft nodes before linearized reading' (duration: 388.074894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:03.874073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:03.485375Z","time spent":"388.685369ms","remote":"127.0.0.1:37280","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-05-12T05:45:12.401306Z","caller":"traceutil/trace.go:171","msg":"trace[310972545] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1427; }","duration":"484.048534ms","start":"2026-05-12T05:45:11.917235Z","end":"2026-05-12T05:45:12.401283Z","steps":["trace[310972545] 'read index received' (duration: 483.847632ms)","trace[310972545] 'applied index is now lower than readState.Index' (duration: 199.952µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:45:12.401368Z","caller":"traceutil/trace.go:171","msg":"trace[564133757] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"486.556537ms","start":"2026-05-12T05:45:11.914776Z","end":"2026-05-12T05:45:12.401333Z","steps":["trace[564133757] 'process raft request' (duration: 486.357335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:12.401521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:11.914759Z","time spent":"486.679969ms","remote":"127.0.0.1:37280","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-05-12T05:45:12.401573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.34691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T05:45:12.401592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.575069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-12T05:45:12.40165Z","caller":"traceutil/trace.go:171","msg":"trace[1685962530] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1370; }","duration":"468.457291ms","start":"2026-05-12T05:45:11.933175Z","end":"2026-05-12T05:45:12.401632Z","steps":["trace[1685962530] 'agreement among raft nodes before linearized reading' (duration: 468.33257ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:12.401662Z","caller":"traceutil/trace.go:171","msg":"trace[1065865645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1370; }","duration":"482.66408ms","start":"2026-05-12T05:45:11.918984Z","end":"2026-05-12T05:45:12.401648Z","steps":["trace[1065865645] 'agreement among raft nodes before linearized reading' (duration: 482.479538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:12.401702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:11.933162Z","time spent":"468.527892ms","remote":"127.0.0.1:56596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-12T05:45:12.401705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:11.918973Z","time spent":"482.723621ms","remote":"127.0.0.1:37280","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-05-12T05:45:12.401886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.823601ms","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-05-12T05:45:12.40191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.656359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-12T05:45:12.401978Z","caller":"traceutil/trace.go:171","msg":"trace[273815941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1370; }","duration":"482.938322ms","start":"2026-05-12T05:45:11.919015Z","end":"2026-05-12T05:45:12.401954Z","steps":["trace[273815941] 'agreement among raft nodes before linearized reading' (duration: 482.651919ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:12.40197Z","caller":"traceutil/trace.go:171","msg":"trace[1260045319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1370; }","duration":"484.781651ms","start":"2026-05-12T05:45:11.917177Z","end":"2026-05-12T05:45:12.401958Z","steps":["trace[1260045319] 'agreement among raft nodes before linearized reading' (duration: 484.230116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:12.402031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:11.91901Z","time spent":"483.011204ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T05:45:12.402084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:11.917155Z","time spent":"484.915612ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T05:45:12.509426Z","caller":"traceutil/trace.go:171","msg":"trace[1190926919] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"102.725744ms","start":"2026-05-12T05:45:12.406681Z","end":"2026-05-12T05:45:12.509407Z","steps":["trace[1190926919] 'process raft request' (duration: 102.658294ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:12.509605Z","caller":"traceutil/trace.go:171","msg":"trace[1423956014] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"103.206679ms","start":"2026-05-12T05:45:12.40626Z","end":"2026-05-12T05:45:12.509466Z","steps":["trace[1423956014] 'process raft request' (duration: 68.829053ms)","trace[1423956014] 'compare' (duration: 33.930652ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:45:30.963957Z","caller":"traceutil/trace.go:171","msg":"trace[1503197262] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"235.461414ms","start":"2026-05-12T05:45:30.728468Z","end":"2026-05-12T05:45:30.96393Z","steps":["trace[1503197262] 'process raft request' (duration: 235.324832ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:31.002049Z","caller":"traceutil/trace.go:171","msg":"trace[2111226965] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"273.4581ms","start":"2026-05-12T05:45:30.728562Z","end":"2026-05-12T05:45:31.00202Z","steps":["trace[2111226965] 'process raft request' (duration: 273.295058ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:31.002306Z","caller":"traceutil/trace.go:171","msg":"trace[1092830363] linearizableReadLoop","detail":"{readStateIndex:1501; appliedIndex:1498; }","duration":"270.945157ms","start":"2026-05-12T05:45:30.73123Z","end":"2026-05-12T05:45:31.002175Z","steps":["trace[1092830363] 'read index received' (duration: 232.556697ms)","trace[1092830363] 'applied index is now lower than readState.Index' (duration: 38.38646ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:45:31.002477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.23388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/\" range_end:\"/registry/events/default0\" limit:500 ","response":"range_response_count:21 size:14376"} {"level":"info","ts":"2026-05-12T05:45:31.002527Z","caller":"traceutil/trace.go:171","msg":"trace[1779729281] range","detail":"{range_begin:/registry/events/default/; range_end:/registry/events/default0; response_count:21; response_revision:1438; }","duration":"271.34798ms","start":"2026-05-12T05:45:30.731172Z","end":"2026-05-12T05:45:31.00252Z","steps":["trace[1779729281] 'agreement among raft nodes before linearized reading' (duration: 271.121608ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:31.002272Z","caller":"traceutil/trace.go:171","msg":"trace[295280059] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"273.666872ms","start":"2026-05-12T05:45:30.728579Z","end":"2026-05-12T05:45:31.002246Z","steps":["trace[295280059] 'process raft request' (duration: 273.40495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:31.00332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.761395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/envoy-gateway-system/\" range_end:\"/registry/events/envoy-gateway-system0\" limit:500 ","response":"range_response_count:15 size:12285"} {"level":"info","ts":"2026-05-12T05:45:31.003388Z","caller":"traceutil/trace.go:171","msg":"trace[1633290294] range","detail":"{range_begin:/registry/events/envoy-gateway-system/; range_end:/registry/events/envoy-gateway-system0; response_count:15; response_revision:1438; }","duration":"271.877815ms","start":"2026-05-12T05:45:30.731493Z","end":"2026-05-12T05:45:31.003371Z","steps":["trace[1633290294] 'agreement among raft nodes before linearized reading' (duration: 271.604113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:31.003693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.159921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/envoy-gateway-system/\" range_end:\"/registry/networkpolicies/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:45:31.00374Z","caller":"traceutil/trace.go:171","msg":"trace[946336020] range","detail":"{range_begin:/registry/networkpolicies/envoy-gateway-system/; range_end:/registry/networkpolicies/envoy-gateway-system0; response_count:0; response_revision:1438; }","duration":"205.239632ms","start":"2026-05-12T05:45:30.798487Z","end":"2026-05-12T05:45:31.003726Z","steps":["trace[946336020] 'agreement among raft nodes before linearized reading' (duration: 205.163661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:31.003746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.334387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/\" range_end:\"/registry/pods/envoy-gateway-system0\" limit:500 ","response":"range_response_count:1 size:5484"} {"level":"info","ts":"2026-05-12T05:45:31.003861Z","caller":"traceutil/trace.go:171","msg":"trace[1332042250] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/; range_end:/registry/pods/envoy-gateway-system0; response_count:1; response_revision:1438; }","duration":"103.52227ms","start":"2026-05-12T05:45:30.90033Z","end":"2026-05-12T05:45:31.003852Z","steps":["trace[1332042250] 'agreement among raft nodes before linearized reading' (duration: 103.299108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:33.162573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.572124ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-12T05:45:33.162738Z","caller":"traceutil/trace.go:171","msg":"trace[1033876679] linearizableReadLoop","detail":"{readStateIndex:1505; appliedIndex:1504; }","duration":"335.862948ms","start":"2026-05-12T05:45:32.826815Z","end":"2026-05-12T05:45:33.162677Z","steps":["trace[1033876679] 'read index received' (duration: 167.096711ms)","trace[1033876679] 'applied index is now lower than readState.Index' (duration: 168.764107ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:45:33.162853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.02307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"warn","ts":"2026-05-12T05:45:33.162907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.759386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:45:33.162917Z","caller":"traceutil/trace.go:171","msg":"trace[526447707] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1441; }","duration":"336.113031ms","start":"2026-05-12T05:45:32.826786Z","end":"2026-05-12T05:45:33.162899Z","steps":["trace[526447707] 'agreement among raft nodes before linearized reading' (duration: 335.985758ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:33.162963Z","caller":"traceutil/trace.go:171","msg":"trace[741277237] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:1441; }","duration":"314.842567ms","start":"2026-05-12T05:45:32.8481Z","end":"2026-05-12T05:45:33.162943Z","steps":["trace[741277237] 'agreement among raft nodes before linearized reading' (duration: 314.752766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:33.16299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:32.826771Z","time spent":"336.204992ms","remote":"127.0.0.1:37214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":195,"request content":"key:\"/registry/serviceaccounts/default/default\" "} {"level":"warn","ts":"2026-05-12T05:45:33.163014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:32.848085Z","time spent":"314.913528ms","remote":"127.0.0.1:37166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 "} {"level":"warn","ts":"2026-05-12T05:45:33.163141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.69856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T05:45:33.16325Z","caller":"traceutil/trace.go:171","msg":"trace[228502285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1441; }","duration":"156.837362ms","start":"2026-05-12T05:45:33.006393Z","end":"2026-05-12T05:45:33.163231Z","steps":["trace[228502285] 'agreement among raft nodes before linearized reading' (duration: 156.567969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:33.163481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.843375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-12T05:45:33.163427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.496295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-12T05:45:33.163485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.132095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-12T05:45:33.163535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.626344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" ","response":"range_response_count:1 size:1036301"} {"level":"info","ts":"2026-05-12T05:45:33.163553Z","caller":"traceutil/trace.go:171","msg":"trace[1811306801] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1441; }","duration":"195.678036ms","start":"2026-05-12T05:45:32.967849Z","end":"2026-05-12T05:45:33.163527Z","steps":["trace[1811306801] 'agreement among raft nodes before linearized reading' (duration: 195.294933ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:33.163575Z","caller":"traceutil/trace.go:171","msg":"trace[242062416] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1; range_end:; response_count:1; response_revision:1441; }","duration":"284.701416ms","start":"2026-05-12T05:45:32.878864Z","end":"2026-05-12T05:45:33.163565Z","steps":["trace[242062416] 'agreement among raft nodes before linearized reading' (duration: 284.387973ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:33.16357Z","caller":"traceutil/trace.go:171","msg":"trace[1617402480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1441; }","duration":"158.246746ms","start":"2026-05-12T05:45:33.005304Z","end":"2026-05-12T05:45:33.163551Z","steps":["trace[1617402480] 'agreement among raft nodes before linearized reading' (duration: 158.050394ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:33.163513Z","caller":"traceutil/trace.go:171","msg":"trace[1483888946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1441; }","duration":"148.924255ms","start":"2026-05-12T05:45:33.014581Z","end":"2026-05-12T05:45:33.163505Z","steps":["trace[1483888946] 'agreement among raft nodes before linearized reading' (duration: 148.837374ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:33.339754Z","caller":"traceutil/trace.go:171","msg":"trace[26956889] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"172.279271ms","start":"2026-05-12T05:45:33.167442Z","end":"2026-05-12T05:45:33.339721Z","steps":["trace[26956889] 'process raft request' (duration: 171.964588ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:33.4282Z","caller":"traceutil/trace.go:171","msg":"trace[816526524] linearizableReadLoop","detail":"{readStateIndex:1509; appliedIndex:1505; }","duration":"192.219882ms","start":"2026-05-12T05:45:33.235961Z","end":"2026-05-12T05:45:33.428181Z","steps":["trace[816526524] 'read index received' (duration: 103.437927ms)","trace[816526524] 'applied index is now lower than readState.Index' (duration: 88.781135ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T05:45:33.428256Z","caller":"traceutil/trace.go:171","msg":"trace[732654598] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"133.416146ms","start":"2026-05-12T05:45:33.294826Z","end":"2026-05-12T05:45:33.428242Z","steps":["trace[732654598] 'process raft request' (duration: 133.323965ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:33.428312Z","caller":"traceutil/trace.go:171","msg":"trace[1253911502] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"260.133648ms","start":"2026-05-12T05:45:33.168169Z","end":"2026-05-12T05:45:33.428303Z","steps":["trace[1253911502] 'process raft request' (duration: 259.921576ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:33.428264Z","caller":"traceutil/trace.go:171","msg":"trace[334925248] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"259.533062ms","start":"2026-05-12T05:45:33.168706Z","end":"2026-05-12T05:45:33.428239Z","steps":["trace[334925248] 'process raft request' (duration: 259.410111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:33.428526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.070893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-05-12T05:45:33.428566Z","caller":"traceutil/trace.go:171","msg":"trace[1772219575] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1446; }","duration":"186.140514ms","start":"2026-05-12T05:45:33.242417Z","end":"2026-05-12T05:45:33.428557Z","steps":["trace[1772219575] 'agreement among raft nodes before linearized reading' (duration: 186.038963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:33.428615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.639606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" ","response":"range_response_count:1 size:1036301"} {"level":"info","ts":"2026-05-12T05:45:33.428675Z","caller":"traceutil/trace.go:171","msg":"trace[106655202] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1; range_end:; response_count:1; response_revision:1446; }","duration":"192.739638ms","start":"2026-05-12T05:45:33.235924Z","end":"2026-05-12T05:45:33.428663Z","steps":["trace[106655202] 'agreement among raft nodes before linearized reading' (duration: 192.364784ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:33.428181Z","caller":"traceutil/trace.go:171","msg":"trace[1733244058] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"260.38688ms","start":"2026-05-12T05:45:33.167757Z","end":"2026-05-12T05:45:33.428144Z","steps":["trace[1733244058] 'process raft request' (duration: 204.903345ms)","trace[1733244058] 'compare' (duration: 55.351744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:45:33.428688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.836496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-node-lease/\" range_end:\"/registry/rolebindings/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T05:45:33.428931Z","caller":"traceutil/trace.go:171","msg":"trace[1965824418] range","detail":"{range_begin:/registry/rolebindings/kube-node-lease/; range_end:/registry/rolebindings/kube-node-lease0; response_count:0; response_revision:1446; }","duration":"174.160929ms","start":"2026-05-12T05:45:33.254712Z","end":"2026-05-12T05:45:33.428873Z","steps":["trace[1965824418] 'agreement among raft nodes before linearized reading' (duration: 173.797755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:33.429129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.639635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/\" range_end:\"/registry/rolebindings/kube-public0\" limit:500 ","response":"range_response_count:2 size:1230"} {"level":"info","ts":"2026-05-12T05:45:33.429175Z","caller":"traceutil/trace.go:171","msg":"trace[1461165072] range","detail":"{range_begin:/registry/rolebindings/kube-public/; range_end:/registry/rolebindings/kube-public0; response_count:2; response_revision:1446; }","duration":"171.703425ms","start":"2026-05-12T05:45:33.257459Z","end":"2026-05-12T05:45:33.429162Z","steps":["trace[1461165072] 'agreement among raft nodes before linearized reading' (duration: 171.597864ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:37.766122Z","caller":"traceutil/trace.go:171","msg":"trace[1485283208] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"341.227842ms","start":"2026-05-12T05:45:37.424862Z","end":"2026-05-12T05:45:37.76609Z","steps":["trace[1485283208] 'process raft request' (duration: 341.03691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:37.766248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.601497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kube-proxy\" ","response":"range_response_count:1 size:317"} {"level":"warn","ts":"2026-05-12T05:45:37.766799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:37.424844Z","time spent":"341.862998ms","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T05:45:37.766854Z","caller":"traceutil/trace.go:171","msg":"trace[838091705] range","detail":"{range_begin:/registry/roles/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1454; }","duration":"312.209603ms","start":"2026-05-12T05:45:37.454616Z","end":"2026-05-12T05:45:37.766825Z","steps":["trace[838091705] 'agreement among raft nodes before linearized reading' (duration: 311.561577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:37.766969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:37.454573Z","time spent":"312.359084ms","remote":"127.0.0.1:37338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":341,"request content":"key:\"/registry/roles/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-05-12T05:45:37.766075Z","caller":"traceutil/trace.go:171","msg":"trace[1791259602] linearizableReadLoop","detail":"{readStateIndex:1518; appliedIndex:1517; }","duration":"311.424386ms","start":"2026-05-12T05:45:37.454626Z","end":"2026-05-12T05:45:37.766051Z","steps":["trace[1791259602] 'read index received' (duration: 311.300574ms)","trace[1791259602] 'applied index is now lower than readState.Index' (duration: 122.782µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T05:45:37.796687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.620648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-12T05:45:37.796836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.424171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-12T05:45:37.796842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.044703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.244\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-12T05:45:37.796862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.743905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-12T05:45:37.796878Z","caller":"traceutil/trace.go:171","msg":"trace[28698641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1455; }","duration":"269.490432ms","start":"2026-05-12T05:45:37.527377Z","end":"2026-05-12T05:45:37.796867Z","steps":["trace[28698641] 'agreement among raft nodes before linearized reading' (duration: 269.213209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:37.796858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.46884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-12T05:45:37.796908Z","caller":"traceutil/trace.go:171","msg":"trace[1729065992] range","detail":"{range_begin:/registry/masterleases/199.19.213.244; range_end:; response_count:1; response_revision:1455; }","duration":"148.151105ms","start":"2026-05-12T05:45:37.64874Z","end":"2026-05-12T05:45:37.796891Z","steps":["trace[1729065992] 'agreement among raft nodes before linearized reading' (duration: 147.883573ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:37.796924Z","caller":"traceutil/trace.go:171","msg":"trace[1734801732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1455; }","duration":"269.829056ms","start":"2026-05-12T05:45:37.527078Z","end":"2026-05-12T05:45:37.796907Z","steps":["trace[1734801732] 'agreement among raft nodes before linearized reading' (duration: 269.538114ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:37.796966Z","caller":"traceutil/trace.go:171","msg":"trace[2050240770] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config; range_end:; response_count:1; response_revision:1455; }","duration":"327.601902ms","start":"2026-05-12T05:45:37.469345Z","end":"2026-05-12T05:45:37.796946Z","steps":["trace[2050240770] 'agreement among raft nodes before linearized reading' (duration: 327.4015ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:37.796695Z","caller":"traceutil/trace.go:171","msg":"trace[1490479590] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"269.231009ms","start":"2026-05-12T05:45:37.527435Z","end":"2026-05-12T05:45:37.796666Z","steps":["trace[1490479590] 'process raft request' (duration: 268.990247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T05:45:37.79675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.284799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-12T05:45:37.79706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T05:45:37.469329Z","time spent":"327.715123ms","remote":"127.0.0.1:37354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":580,"request content":"key:\"/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config\" "} {"level":"info","ts":"2026-05-12T05:45:37.797134Z","caller":"traceutil/trace.go:171","msg":"trace[1317821217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1455; }","duration":"269.684663ms","start":"2026-05-12T05:45:37.527433Z","end":"2026-05-12T05:45:37.797118Z","steps":["trace[1317821217] 'agreement among raft nodes before linearized reading' (duration: 269.154568ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:37.79723Z","caller":"traceutil/trace.go:171","msg":"trace[1357777008] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1455; }","duration":"206.81771ms","start":"2026-05-12T05:45:37.590032Z","end":"2026-05-12T05:45:37.79685Z","steps":["trace[1357777008] 'agreement among raft nodes before linearized reading' (duration: 206.598548ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:37.926376Z","caller":"traceutil/trace.go:171","msg":"trace[1649259072] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"124.958642ms","start":"2026-05-12T05:45:37.801405Z","end":"2026-05-12T05:45:37.926364Z","steps":["trace[1649259072] 'process raft request' (duration: 124.65453ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:37.926679Z","caller":"traceutil/trace.go:171","msg":"trace[88320413] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"125.165404ms","start":"2026-05-12T05:45:37.80149Z","end":"2026-05-12T05:45:37.926655Z","steps":["trace[88320413] 'process raft request' (duration: 124.640889ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T05:45:37.926876Z","caller":"traceutil/trace.go:171","msg":"trace[806547581] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"124.357746ms","start":"2026-05-12T05:45:37.802508Z","end":"2026-05-12T05:45:37.926866Z","steps":["trace[806547581] 'process raft request' (duration: 123.640849ms)"],"step_count":1}