{"level":"warn","ts":"2026-05-29T21:53:30.680962Z","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-29T21:53:30.681372Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-29T21:53:30.681559Z","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-29T21:53:30.681629Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-29T21:53:30.681698Z","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-29T21:53:30.683469Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-05-29T21:53:30.683673Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-29T21:53:30.69077Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.787382ms"} {"level":"info","ts":"2026-05-29T21:53:30.696928Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-05-29T21:53:30.69704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T21:53:30.697084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-05-29T21:53:30.69712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T21:53:30.697145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-05-29T21:53:30.697213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-05-29T21:53:30.704393Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T21:53:30.708403Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T21:53:30.709853Z","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-29T21:53:30.712083Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T21:53:30.714766Z","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-29T21:53:30.714849Z","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-29T21:53:30.714861Z","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-29T21:53:30.714942Z","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-29T21:53:30.714837Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T21:53:30.715075Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T21:53:30.715117Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T21:53:30.715172Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-29T21:53:30.715194Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-29T21:53:30.715957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-05-29T21:53:30.71616Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-29T21:53:31.398275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T21:53:31.398406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T21:53:31.398448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-05-29T21:53:31.398473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-05-29T21:53:31.398485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-29T21:53:31.398503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-05-29T21:53:31.398525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-29T21:53:31.400823Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:53:31.401732Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T21:53:31.401785Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:53:31.401893Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:53:31.402237Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:53:31.402363Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:53:31.402401Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:53:31.403037Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T21:53:31.403069Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T21:53:31.405014Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-05-29T21:53:31.40616Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-29T21:53:34.43616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341030050417174,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:53:34.487404Z","caller":"traceutil/trace.go:171","msg":"trace[1884243892] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"551.75911ms","start":"2026-05-29T21:53:33.935612Z","end":"2026-05-29T21:53:34.487371Z","steps":["trace[1884243892] 'read index received' (duration: 551.386352ms)","trace[1884243892] 'applied index is now lower than readState.Index' (duration: 371.418µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:34.487722Z","caller":"traceutil/trace.go:171","msg":"trace[470909688] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"552.907403ms","start":"2026-05-29T21:53:33.934789Z","end":"2026-05-29T21:53:34.487696Z","steps":["trace[470909688] 'process raft request' (duration: 552.411828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:34.487884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.859468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-29T21:53:34.487853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.145218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T21:53:34.48795Z","caller":"traceutil/trace.go:171","msg":"trace[97295201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:112; }","duration":"460.940685ms","start":"2026-05-29T21:53:34.026981Z","end":"2026-05-29T21:53:34.487922Z","steps":["trace[97295201] 'agreement among raft nodes before linearized reading' (duration: 460.858068ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:34.487979Z","caller":"traceutil/trace.go:171","msg":"trace[983383229] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:112; }","duration":"552.3858ms","start":"2026-05-29T21:53:33.935545Z","end":"2026-05-29T21:53:34.48793Z","steps":["trace[983383229] 'agreement among raft nodes before linearized reading' (duration: 552.021161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:34.488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:34.026967Z","time spent":"461.018803ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:53:34.488108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:33.935506Z","time spent":"552.510146ms","remote":"127.0.0.1:44380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"warn","ts":"2026-05-29T21:53:34.489229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:33.934704Z","time spent":"553.65593ms","remote":"127.0.0.1:44392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:53:34.996324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341030050417182,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:53:35.609926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.917978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-29T21:53:35.610045Z","caller":"traceutil/trace.go:171","msg":"trace[1089162750] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"1.114769064s","start":"2026-05-29T21:53:34.495253Z","end":"2026-05-29T21:53:35.610023Z","steps":["trace[1089162750] 'read index received' (duration: 516.329471ms)","trace[1089162750] 'applied index is now lower than readState.Index' (duration: 598.438423ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:35.610154Z","caller":"traceutil/trace.go:171","msg":"trace[551684808] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"1.11623263s","start":"2026-05-29T21:53:34.493871Z","end":"2026-05-29T21:53:35.610103Z","steps":["trace[551684808] 'process raft request' (duration: 517.797428ms)","trace[551684808] 'compare' (duration: 597.718333ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:53:35.610237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114983408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T21:53:35.6103Z","caller":"traceutil/trace.go:171","msg":"trace[881269427] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:113; }","duration":"1.11523319s","start":"2026-05-29T21:53:34.495057Z","end":"2026-05-29T21:53:35.61029Z","steps":["trace[881269427] 'agreement among raft nodes before linearized reading' (duration: 1.115068594s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:35.610371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:34.495046Z","time spent":"1.115312848s","remote":"127.0.0.1:44380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"warn","ts":"2026-05-29T21:53:35.610397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:34.49385Z","time spent":"1.116405325s","remote":"127.0.0.1:44392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:53:35.610438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.799588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-29T21:53:35.610658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.903627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T21:53:35.610773Z","caller":"traceutil/trace.go:171","msg":"trace[538013980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:113; }","duration":"483.262814ms","start":"2026-05-29T21:53:35.127469Z","end":"2026-05-29T21:53:35.610732Z","steps":["trace[538013980] 'agreement among raft nodes before linearized reading' (duration: 482.785378ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:35.610811Z","caller":"traceutil/trace.go:171","msg":"trace[297848566] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:113; }","duration":"239.143349ms","start":"2026-05-29T21:53:35.371634Z","end":"2026-05-29T21:53:35.610777Z","steps":["trace[297848566] 'agreement among raft nodes before linearized reading' (duration: 238.875297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:35.610833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:35.127425Z","time spent":"483.39521ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:53:36.11808Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341030050417187,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:53:36.619112Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341030050417187,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:53:36.662033Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.046153499s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T21:53:36.662799Z","caller":"traceutil/trace.go:171","msg":"trace[1791300294] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"1.046994396s","start":"2026-05-29T21:53:35.615775Z","end":"2026-05-29T21:53:36.662769Z","steps":["trace[1791300294] 'process raft request' (duration: 1.046680755s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.663023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:35.61576Z","time spent":"1.047145971s","remote":"127.0.0.1:44234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:36.66699Z","caller":"traceutil/trace.go:171","msg":"trace[776853480] linearizableReadLoop","detail":"{readStateIndex:121; appliedIndex:118; }","duration":"1.049349842s","start":"2026-05-29T21:53:35.617603Z","end":"2026-05-29T21:53:36.666953Z","steps":["trace[776853480] 'read index received' (duration: 1.044931475s)","trace[776853480] 'applied index is now lower than readState.Index' (duration: 4.417317ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:36.667012Z","caller":"traceutil/trace.go:171","msg":"trace[1257915244] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"627.377236ms","start":"2026-05-29T21:53:36.039607Z","end":"2026-05-29T21:53:36.666984Z","steps":["trace[1257915244] 'process raft request' (duration: 627.162662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.667211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:36.039576Z","time spent":"627.502103ms","remote":"127.0.0.1:44234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:36.667103Z","caller":"traceutil/trace.go:171","msg":"trace[1874952587] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"324.196811ms","start":"2026-05-29T21:53:36.342892Z","end":"2026-05-29T21:53:36.667089Z","steps":["trace[1874952587] 'process raft request' (duration: 324.020986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.667433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.691994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-29T21:53:36.667546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:36.34287Z","time spent":"324.59561ms","remote":"127.0.0.1:44234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:36.667582Z","caller":"traceutil/trace.go:171","msg":"trace[2032596465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:116; }","duration":"838.892489ms","start":"2026-05-29T21:53:35.828675Z","end":"2026-05-29T21:53:36.667568Z","steps":["trace[2032596465] 'agreement among raft nodes before linearized reading' (duration: 838.465831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.667359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.505968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T21:53:36.667723Z","caller":"traceutil/trace.go:171","msg":"trace[1107194114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:116; }","duration":"254.979275ms","start":"2026-05-29T21:53:36.412731Z","end":"2026-05-29T21:53:36.66771Z","steps":["trace[1107194114] 'agreement among raft nodes before linearized reading' (duration: 254.420941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.667682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:35.828614Z","time spent":"839.056024ms","remote":"127.0.0.1:44302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T21:53:36.667348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049710672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T21:53:36.667969Z","caller":"traceutil/trace.go:171","msg":"trace[29743206] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:116; }","duration":"1.050384513s","start":"2026-05-29T21:53:35.617576Z","end":"2026-05-29T21:53:36.667961Z","steps":["trace[29743206] 'agreement among raft nodes before linearized reading' (duration: 1.049673203s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.667997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:35.617557Z","time spent":"1.050431161s","remote":"127.0.0.1:44390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/system:discovery\" "} {"level":"warn","ts":"2026-05-29T21:53:36.667351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.412431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T21:53:36.668161Z","caller":"traceutil/trace.go:171","msg":"trace[187754958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:116; }","duration":"441.181661ms","start":"2026-05-29T21:53:36.226882Z","end":"2026-05-29T21:53:36.668064Z","steps":["trace[187754958] 'agreement among raft nodes before linearized reading' (duration: 440.410252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:36.668264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:36.226856Z","time spent":"441.371685ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:53:37.103223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.420035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:53:37.103355Z","caller":"traceutil/trace.go:171","msg":"trace[217491203] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"158.464048ms","start":"2026-05-29T21:53:36.944868Z","end":"2026-05-29T21:53:37.103332Z","steps":["trace[217491203] 'process raft request' (duration: 37.771181ms)","trace[217491203] 'compare' (duration: 120.185741ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:45.301721Z","caller":"traceutil/trace.go:171","msg":"trace[363807595] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"105.88402ms","start":"2026-05-29T21:53:45.195801Z","end":"2026-05-29T21:53:45.301685Z","steps":["trace[363807595] 'process raft request' (duration: 105.598444ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:05.684793Z","caller":"traceutil/trace.go:171","msg":"trace[743204238] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"155.250333ms","start":"2026-05-29T21:54:05.529522Z","end":"2026-05-29T21:54:05.684772Z","steps":["trace[743204238] 'read index received' (duration: 155.122183ms)","trace[743204238] 'applied index is now lower than readState.Index' (duration: 125.18µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:05.68489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.356254ms","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-29T21:54:05.684862Z","caller":"traceutil/trace.go:171","msg":"trace[1987943523] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"204.820664ms","start":"2026-05-29T21:54:05.48001Z","end":"2026-05-29T21:54:05.68483Z","steps":["trace[1987943523] 'process raft request' (duration: 204.649694ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:05.684917Z","caller":"traceutil/trace.go:171","msg":"trace[187893914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"155.438644ms","start":"2026-05-29T21:54:05.529469Z","end":"2026-05-29T21:54:05.684908Z","steps":["trace[187893914] 'agreement among raft nodes before linearized reading' (duration: 155.375374ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:05.912171Z","caller":"traceutil/trace.go:171","msg":"trace[1589142337] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"122.693126ms","start":"2026-05-29T21:54:05.789455Z","end":"2026-05-29T21:54:05.912148Z","steps":["trace[1589142337] 'read index received' (duration: 122.574556ms)","trace[1589142337] 'applied index is now lower than readState.Index' (duration: 117.53µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:05.91247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.991437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:05.912569Z","caller":"traceutil/trace.go:171","msg":"trace[95094663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"123.149207ms","start":"2026-05-29T21:54:05.7894Z","end":"2026-05-29T21:54:05.91255Z","steps":["trace[95094663] 'agreement among raft nodes before linearized reading' (duration: 122.959637ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:05.912412Z","caller":"traceutil/trace.go:171","msg":"trace[1203284082] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"224.095976ms","start":"2026-05-29T21:54:05.688265Z","end":"2026-05-29T21:54:05.912361Z","steps":["trace[1203284082] 'process raft request' (duration: 223.704775ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:11.979017Z","caller":"traceutil/trace.go:171","msg":"trace[643471273] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"262.856095ms","start":"2026-05-29T21:54:11.716125Z","end":"2026-05-29T21:54:11.978982Z","steps":["trace[643471273] 'process raft request' (duration: 262.432212ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:14.499944Z","caller":"traceutil/trace.go:171","msg":"trace[299032169] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"112.270379ms","start":"2026-05-29T21:54:14.387636Z","end":"2026-05-29T21:54:14.499907Z","steps":["trace[299032169] 'read index received' (duration: 111.915995ms)","trace[299032169] 'applied index is now lower than readState.Index' (duration: 353.064µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:14.500357Z","caller":"traceutil/trace.go:171","msg":"trace[2076530767] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"177.512297ms","start":"2026-05-29T21:54:14.322832Z","end":"2026-05-29T21:54:14.500345Z","steps":["trace[2076530767] 'process raft request' (duration: 176.81537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:14.500623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.960215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:14.500662Z","caller":"traceutil/trace.go:171","msg":"trace[1425993772] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:602; }","duration":"113.083836ms","start":"2026-05-29T21:54:14.387566Z","end":"2026-05-29T21:54:14.50065Z","steps":["trace[1425993772] 'agreement among raft nodes before linearized reading' (duration: 112.978045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:14.50086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.205057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:14.50089Z","caller":"traceutil/trace.go:171","msg":"trace[639698392] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:602; }","duration":"113.285227ms","start":"2026-05-29T21:54:14.387595Z","end":"2026-05-29T21:54:14.500881Z","steps":["trace[639698392] 'agreement among raft nodes before linearized reading' (duration: 113.236547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:15.586875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.273564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:54:15.587463Z","caller":"traceutil/trace.go:171","msg":"trace[645487501] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"612.361461ms","start":"2026-05-29T21:54:14.97505Z","end":"2026-05-29T21:54:15.587411Z","steps":["trace[645487501] 'process raft request' (duration: 262.908454ms)","trace[645487501] 'compare' (duration: 347.921901ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:15.587628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.838084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:15.587663Z","caller":"traceutil/trace.go:171","msg":"trace[522409598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"544.950584ms","start":"2026-05-29T21:54:15.042702Z","end":"2026-05-29T21:54:15.587653Z","steps":["trace[522409598] 'agreement among raft nodes before linearized reading' (duration: 544.855393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:15.587687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:15.042653Z","time spent":"545.029186ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:54:15.587679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:14.975029Z","time spent":"612.540202ms","remote":"127.0.0.1:44302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:54:15.587742Z","caller":"traceutil/trace.go:171","msg":"trace[1641296822] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"482.446089ms","start":"2026-05-29T21:54:15.105266Z","end":"2026-05-29T21:54:15.587712Z","steps":["trace[1641296822] 'process raft request' (duration: 482.100175ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:15.587521Z","caller":"traceutil/trace.go:171","msg":"trace[1684563199] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"544.717373ms","start":"2026-05-29T21:54:15.042783Z","end":"2026-05-29T21:54:15.587501Z","steps":["trace[1684563199] 'read index received' (duration: 195.73989ms)","trace[1684563199] 'applied index is now lower than readState.Index' (duration: 348.975883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:15.58785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:15.105237Z","time spent":"482.562981ms","remote":"127.0.0.1:44094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:54:23.277305Z","caller":"traceutil/trace.go:171","msg":"trace[1479207887] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"241.655799ms","start":"2026-05-29T21:54:23.035605Z","end":"2026-05-29T21:54:23.277261Z","steps":["trace[1479207887] 'process raft request' (duration: 241.458646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:25.986572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.500095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:54:25.986693Z","caller":"traceutil/trace.go:171","msg":"trace[1425832438] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"700.776889ms","start":"2026-05-29T21:54:25.285895Z","end":"2026-05-29T21:54:25.986672Z","steps":["trace[1425832438] 'read index received' (duration: 67.070391ms)","trace[1425832438] 'applied index is now lower than readState.Index' (duration: 633.705178ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:25.986849Z","caller":"traceutil/trace.go:171","msg":"trace[117133185] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.208252961s","start":"2026-05-29T21:54:24.778543Z","end":"2026-05-29T21:54:25.986796Z","steps":["trace[117133185] 'process raft request' (duration: 574.435501ms)","trace[117133185] 'compare' (duration: 633.272741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:25.987096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:24.778492Z","time spent":"1.208461565s","remote":"127.0.0.1:44302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:54:25.98726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.104326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T21:54:25.987307Z","caller":"traceutil/trace.go:171","msg":"trace[1140722398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"324.211207ms","start":"2026-05-29T21:54:25.663084Z","end":"2026-05-29T21:54:25.987295Z","steps":["trace[1140722398] 'agreement among raft nodes before linearized reading' (duration: 324.076395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:25.987122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.227556ms","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-29T21:54:25.987343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:25.663054Z","time spent":"324.281248ms","remote":"127.0.0.1:44302","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-29T21:54:25.987392Z","caller":"traceutil/trace.go:171","msg":"trace[97903675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"701.540052ms","start":"2026-05-29T21:54:25.285835Z","end":"2026-05-29T21:54:25.987375Z","steps":["trace[97903675] 'agreement among raft nodes before linearized reading' (duration: 701.116175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:25.987471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:25.285809Z","time spent":"701.646443ms","remote":"127.0.0.1:44302","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":"warn","ts":"2026-05-29T21:54:25.987627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.167915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:25.987657Z","caller":"traceutil/trace.go:171","msg":"trace[1007557923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"197.250396ms","start":"2026-05-29T21:54:25.790397Z","end":"2026-05-29T21:54:25.987648Z","steps":["trace[1007557923] 'agreement among raft nodes before linearized reading' (duration: 197.200605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:27.16333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.327077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:27.163442Z","caller":"traceutil/trace.go:171","msg":"trace[585532355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"118.501159ms","start":"2026-05-29T21:54:27.044916Z","end":"2026-05-29T21:54:27.163417Z","steps":["trace[585532355] 'range keys from in-memory index tree' (duration: 118.219564ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:28.309284Z","caller":"traceutil/trace.go:171","msg":"trace[1538180141] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"352.093734ms","start":"2026-05-29T21:54:27.957137Z","end":"2026-05-29T21:54:28.309231Z","steps":["trace[1538180141] 'read index received' (duration: 351.390242ms)","trace[1538180141] 'applied index is now lower than readState.Index' (duration: 701.402µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:28.309485Z","caller":"traceutil/trace.go:171","msg":"trace[274068841] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"352.494953ms","start":"2026-05-29T21:54:27.956967Z","end":"2026-05-29T21:54:28.309462Z","steps":["trace[274068841] 'process raft request' (duration: 351.627407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:28.309589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.43312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:54:28.309669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.956933Z","time spent":"352.591395ms","remote":"127.0.0.1:44302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:54:28.309683Z","caller":"traceutil/trace.go:171","msg":"trace[277252995] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"352.530272ms","start":"2026-05-29T21:54:27.95713Z","end":"2026-05-29T21:54:28.30966Z","steps":["trace[277252995] 'agreement among raft nodes before linearized reading' (duration: 352.39911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:28.30973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.957093Z","time spent":"352.627584ms","remote":"127.0.0.1:44094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-05-29T21:54:28.310104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.147715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:28.310134Z","caller":"traceutil/trace.go:171","msg":"trace[1891534432] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"327.240597ms","start":"2026-05-29T21:54:27.982887Z","end":"2026-05-29T21:54:28.310127Z","steps":["trace[1891534432] 'agreement among raft nodes before linearized reading' (duration: 327.192116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:28.310154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.982832Z","time spent":"327.317718ms","remote":"127.0.0.1:44094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-29T21:54:28.310266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.973643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:54:28.310222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.985621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:28.310336Z","caller":"traceutil/trace.go:171","msg":"trace[769062904] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"353.137345ms","start":"2026-05-29T21:54:27.957169Z","end":"2026-05-29T21:54:28.310306Z","steps":["trace[769062904] 'agreement among raft nodes before linearized reading' (duration: 352.907171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:28.310384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.161805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:54:28.310396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.957165Z","time spent":"353.220916ms","remote":"127.0.0.1:44094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-05-29T21:54:28.31044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.316292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:54:28.310423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.623781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-29T21:54:28.310492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.177325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:28.310514Z","caller":"traceutil/trace.go:171","msg":"trace[1532559204] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"353.244687ms","start":"2026-05-29T21:54:27.957264Z","end":"2026-05-29T21:54:28.310509Z","steps":["trace[1532559204] 'agreement among raft nodes before linearized reading' (duration: 353.213406ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:28.310505Z","caller":"traceutil/trace.go:171","msg":"trace[1907936558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"235.795055ms","start":"2026-05-29T21:54:28.074688Z","end":"2026-05-29T21:54:28.310483Z","steps":["trace[1907936558] 'agreement among raft nodes before linearized reading' (duration: 235.568901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:28.310533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.95723Z","time spent":"353.298858ms","remote":"127.0.0.1:44094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-05-29T21:54:28.310605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.372459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:28.310629Z","caller":"traceutil/trace.go:171","msg":"trace[1205084262] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"353.39768ms","start":"2026-05-29T21:54:27.957221Z","end":"2026-05-29T21:54:28.310619Z","steps":["trace[1205084262] 'agreement among raft nodes before linearized reading' (duration: 353.363679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:28.310651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.957218Z","time spent":"353.42767ms","remote":"127.0.0.1:44094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-05-29T21:54:28.310769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.818074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:54:28.310801Z","caller":"traceutil/trace.go:171","msg":"trace[474857865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:675; }","duration":"235.902986ms","start":"2026-05-29T21:54:28.074888Z","end":"2026-05-29T21:54:28.310791Z","steps":["trace[474857865] 'agreement among raft nodes before linearized reading' (duration: 235.810234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:28.310829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.002361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-29T21:54:28.310879Z","caller":"traceutil/trace.go:171","msg":"trace[1838378350] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:675; }","duration":"131.089093ms","start":"2026-05-29T21:54:28.179764Z","end":"2026-05-29T21:54:28.310853Z","steps":["trace[1838378350] 'agreement among raft nodes before linearized reading' (duration: 130.975991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:28.310896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.36587ms","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-29T21:54:28.310408Z","caller":"traceutil/trace.go:171","msg":"trace[1223093098] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"353.192086ms","start":"2026-05-29T21:54:27.95721Z","end":"2026-05-29T21:54:28.310402Z","steps":["trace[1223093098] 'agreement among raft nodes before linearized reading' (duration: 353.156355ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:28.310925Z","caller":"traceutil/trace.go:171","msg":"trace[396056127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:675; }","duration":"138.39439ms","start":"2026-05-29T21:54:28.172521Z","end":"2026-05-29T21:54:28.310916Z","steps":["trace[396056127] 'agreement among raft nodes before linearized reading' (duration: 138.318769ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:28.310461Z","caller":"traceutil/trace.go:171","msg":"trace[1021922406] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"351.347853ms","start":"2026-05-29T21:54:27.959107Z","end":"2026-05-29T21:54:28.310455Z","steps":["trace[1021922406] 'agreement among raft nodes before linearized reading' (duration: 351.317952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:28.310957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.727426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:54:28.310979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.785566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:54:28.310988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.9591Z","time spent":"351.881553ms","remote":"127.0.0.1:44094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-05-29T21:54:28.310975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.957207Z","time spent":"353.742496ms","remote":"127.0.0.1:44094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-05-29T21:54:28.311014Z","caller":"traceutil/trace.go:171","msg":"trace[2010485604] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"353.825607ms","start":"2026-05-29T21:54:27.957177Z","end":"2026-05-29T21:54:28.311003Z","steps":["trace[2010485604] 'agreement among raft nodes before linearized reading' (duration: 353.766316ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:28.311014Z","caller":"traceutil/trace.go:171","msg":"trace[1288931663] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"353.795967ms","start":"2026-05-29T21:54:27.957202Z","end":"2026-05-29T21:54:28.310998Z","steps":["trace[1288931663] 'agreement among raft nodes before linearized reading' (duration: 353.698855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:28.311042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.957172Z","time spent":"353.863218ms","remote":"127.0.0.1:44094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-05-29T21:54:28.31106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.957199Z","time spent":"353.849528ms","remote":"127.0.0.1:44094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-29T21:54:28.310287Z","caller":"traceutil/trace.go:171","msg":"trace[1893654812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"232.014203ms","start":"2026-05-29T21:54:28.078265Z","end":"2026-05-29T21:54:28.310279Z","steps":["trace[1893654812] 'agreement among raft nodes before linearized reading' (duration: 231.980453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:28.311179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.95885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:28.311235Z","caller":"traceutil/trace.go:171","msg":"trace[1493544024] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"354.026081ms","start":"2026-05-29T21:54:27.957191Z","end":"2026-05-29T21:54:28.311217Z","steps":["trace[1493544024] 'agreement among raft nodes before linearized reading' (duration: 353.911839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:28.31127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:27.957189Z","time spent":"354.073681ms","remote":"127.0.0.1:44094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-05-29T21:54:28.31014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.384066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:28.312793Z","caller":"traceutil/trace.go:171","msg":"trace[1824654485] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:675; }","duration":"158.104556ms","start":"2026-05-29T21:54:28.154668Z","end":"2026-05-29T21:54:28.312772Z","steps":["trace[1824654485] 'agreement among raft nodes before linearized reading' (duration: 155.417157ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:37.368183Z","caller":"traceutil/trace.go:171","msg":"trace[859998121] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"168.623838ms","start":"2026-05-29T21:54:37.199513Z","end":"2026-05-29T21:54:37.368137Z","steps":["trace[859998121] 'process raft request' (duration: 110.306259ms)","trace[859998121] 'compare' (duration: 58.182336ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:37.509205Z","caller":"traceutil/trace.go:171","msg":"trace[1181363613] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"134.57758ms","start":"2026-05-29T21:54:37.374594Z","end":"2026-05-29T21:54:37.509171Z","steps":["trace[1181363613] 'process raft request' (duration: 108.351744ms)","trace[1181363613] 'compare' (duration: 26.084102ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:37.512328Z","caller":"traceutil/trace.go:171","msg":"trace[1979305855] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"127.860221ms","start":"2026-05-29T21:54:37.384427Z","end":"2026-05-29T21:54:37.512287Z","steps":["trace[1979305855] 'process raft request' (duration: 127.648856ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:39.180406Z","caller":"traceutil/trace.go:171","msg":"trace[773083165] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"133.973797ms","start":"2026-05-29T21:54:39.046406Z","end":"2026-05-29T21:54:39.18038Z","steps":["trace[773083165] 'read index received' (duration: 133.852415ms)","trace[773083165] 'applied index is now lower than readState.Index' (duration: 119.762µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:39.180576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.166813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:39.180611Z","caller":"traceutil/trace.go:171","msg":"trace[1932080466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"134.285705ms","start":"2026-05-29T21:54:39.046313Z","end":"2026-05-29T21:54:39.180599Z","steps":["trace[1932080466] 'agreement among raft nodes before linearized reading' (duration: 134.179032ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:39.180639Z","caller":"traceutil/trace.go:171","msg":"trace[1124064565] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"281.653719ms","start":"2026-05-29T21:54:38.898915Z","end":"2026-05-29T21:54:39.180569Z","steps":["trace[1124064565] 'process raft request' (duration: 281.26291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:43.025185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.368026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:43.025244Z","caller":"traceutil/trace.go:171","msg":"trace[1776604380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"979.480479ms","start":"2026-05-29T21:54:42.045749Z","end":"2026-05-29T21:54:43.025229Z","steps":["trace[1776604380] 'range keys from in-memory index tree' (duration: 979.240212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:43.025274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:42.045725Z","time spent":"979.539811ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:54:43.02524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.234086ms","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-29T21:54:43.025325Z","caller":"traceutil/trace.go:171","msg":"trace[45690338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:816; }","duration":"641.3881ms","start":"2026-05-29T21:54:42.383918Z","end":"2026-05-29T21:54:43.025306Z","steps":["trace[45690338] 'range keys from in-memory index tree' (duration: 641.01031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:43.025378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:42.383896Z","time spent":"641.472662ms","remote":"127.0.0.1:44302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T21:54:43.025465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.989378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-29T21:54:43.025733Z","caller":"traceutil/trace.go:171","msg":"trace[1656211981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:816; }","duration":"617.321808ms","start":"2026-05-29T21:54:42.408383Z","end":"2026-05-29T21:54:43.025705Z","steps":["trace[1656211981] 'range keys from in-memory index tree' (duration: 616.734431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:43.025863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:42.408373Z","time spent":"617.472942ms","remote":"127.0.0.1:44302","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-29T21:54:43.025893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.982106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-29T21:54:43.025888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.07935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-29T21:54:43.025921Z","caller":"traceutil/trace.go:171","msg":"trace[2103606216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:816; }","duration":"625.032397ms","start":"2026-05-29T21:54:42.400883Z","end":"2026-05-29T21:54:43.025915Z","steps":["trace[2103606216] 'range keys from in-memory index tree' (duration: 624.895653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:43.025943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:42.400872Z","time spent":"625.067518ms","remote":"127.0.0.1:44302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T21:54:43.025945Z","caller":"traceutil/trace.go:171","msg":"trace[736035383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:816; }","duration":"627.175053ms","start":"2026-05-29T21:54:42.398756Z","end":"2026-05-29T21:54:43.025931Z","steps":["trace[736035383] 'range keys from in-memory index tree' (duration: 626.891425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:43.025981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:42.398734Z","time spent":"627.237344ms","remote":"127.0.0.1:44302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T21:54:43.025667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.52543ms","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-29T21:54:43.02613Z","caller":"traceutil/trace.go:171","msg":"trace[1799848457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:816; }","duration":"616.017444ms","start":"2026-05-29T21:54:42.4101Z","end":"2026-05-29T21:54:43.026118Z","steps":["trace[1799848457] 'range keys from in-memory index tree' (duration: 615.366476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:43.026167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:42.410081Z","time spent":"616.077215ms","remote":"127.0.0.1:44302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T21:54:43.031357Z","caller":"traceutil/trace.go:171","msg":"trace[653250495] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"114.919247ms","start":"2026-05-29T21:54:42.916427Z","end":"2026-05-29T21:54:43.031346Z","steps":["trace[653250495] 'process raft request' (duration: 114.830353ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:15.850052Z","caller":"traceutil/trace.go:171","msg":"trace[244157076] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"110.383728ms","start":"2026-05-29T21:56:15.739649Z","end":"2026-05-29T21:56:15.850033Z","steps":["trace[244157076] 'process raft request' (duration: 109.846282ms)"],"step_count":1}