{"level":"warn","ts":"2026-04-20T20:56:46.245368Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T20:56:46.245504Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-04-20T20:56:46.245604Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T20:56:46.245617Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-04-20T20:56:46.245636Z","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-04-20T20:56:46.246374Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-04-20T20:56:46.24646Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-04-20T20:56:46.250678Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.931964ms"} {"level":"info","ts":"2026-04-20T20:56:46.261439Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-04-20T20:56:46.261589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T20:56:46.261628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-04-20T20:56:46.261649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T20:56:46.261673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-04-20T20:56:46.261734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-04-20T20:56:46.272363Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T20:56:46.274127Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T20:56:46.27624Z","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-04-20T20:56:46.278556Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T20:56:46.279492Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T20:56:46.280331Z","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-04-20T20:56:46.280461Z","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-04-20T20:56:46.280477Z","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-04-20T20:56:46.280987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-04-20T20:56:46.282168Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-04-20T20:56:46.284383Z","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-04-20T20:56:46.284642Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T20:56:46.284679Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T20:56:46.284768Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-04-20T20:56:46.284869Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-04-20T20:56:46.562991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T20:56:46.563079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T20:56:46.563131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-04-20T20:56:46.563163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-04-20T20:56:46.563182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-04-20T20:56:46.563207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-04-20T20:56:46.563224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-04-20T20:56:46.565002Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:56:46.565937Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T20:56:46.56593Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T20:56:46.566195Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T20:56:46.566217Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T20:56:46.570234Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:56:46.570377Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:56:46.570501Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:56:46.573081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-04-20T20:56:46.565906Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T20:56:46.575865Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T20:56:48.191958Z","caller":"traceutil/trace.go:171","msg":"trace[2056992977] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"125.776444ms","start":"2026-04-20T20:56:48.066153Z","end":"2026-04-20T20:56:48.191929Z","steps":["trace[2056992977] 'read index received' (duration: 125.548607ms)","trace[2056992977] 'applied index is now lower than readState.Index' (duration: 226.377µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:48.191976Z","caller":"traceutil/trace.go:171","msg":"trace[379999046] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"127.420162ms","start":"2026-04-20T20:56:48.064522Z","end":"2026-04-20T20:56:48.191942Z","steps":["trace[379999046] 'process raft request' (duration: 127.129794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:48.192306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.982769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T20:56:48.192497Z","caller":"traceutil/trace.go:171","msg":"trace[930236503] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:2; }","duration":"126.287548ms","start":"2026-04-20T20:56:48.066118Z","end":"2026-04-20T20:56:48.192406Z","steps":["trace[930236503] 'agreement among raft nodes before linearized reading' (duration: 125.920668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:48.531028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.647105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-20T20:56:48.531518Z","caller":"traceutil/trace.go:171","msg":"trace[1107654868] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"398.435605ms","start":"2026-04-20T20:56:48.133012Z","end":"2026-04-20T20:56:48.531448Z","steps":["trace[1107654868] 'process raft request' (duration: 398.128556ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:48.53154Z","caller":"traceutil/trace.go:171","msg":"trace[2076276234] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"399.851047ms","start":"2026-04-20T20:56:48.131668Z","end":"2026-04-20T20:56:48.531519Z","steps":["trace[2076276234] 'process raft request' (duration: 255.518763ms)","trace[2076276234] 'compare' (duration: 141.387288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:56:48.531621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.131637Z","time spent":"399.94634ms","remote":"127.0.0.1:40604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:56:48.531664Z","caller":"traceutil/trace.go:171","msg":"trace[676677654] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"338.061497ms","start":"2026-04-20T20:56:48.193587Z","end":"2026-04-20T20:56:48.531649Z","steps":["trace[676677654] 'process raft request' (duration: 337.985385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:48.53172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.132998Z","time spent":"398.57391ms","remote":"127.0.0.1:40682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:56:48.531988Z","caller":"traceutil/trace.go:171","msg":"trace[1759232135] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"398.638401ms","start":"2026-04-20T20:56:48.133331Z","end":"2026-04-20T20:56:48.531969Z","steps":["trace[1759232135] 'process raft request' (duration: 398.025873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:48.532082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.133318Z","time spent":"398.733834ms","remote":"127.0.0.1:40682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:56:48.532085Z","caller":"traceutil/trace.go:171","msg":"trace[1397131038] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"398.482327ms","start":"2026-04-20T20:56:48.133514Z","end":"2026-04-20T20:56:48.531996Z","steps":["trace[1397131038] 'process raft request' (duration: 397.874649ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:48.532188Z","caller":"traceutil/trace.go:171","msg":"trace[28385632] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"385.726695ms","start":"2026-04-20T20:56:48.146449Z","end":"2026-04-20T20:56:48.532176Z","steps":["trace[28385632] 'process raft request' (duration: 385.003414ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:48.532243Z","caller":"traceutil/trace.go:171","msg":"trace[243657032] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"399.166137ms","start":"2026-04-20T20:56:48.133066Z","end":"2026-04-20T20:56:48.532232Z","steps":["trace[243657032] 'process raft request' (duration: 398.144867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:48.532251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.146441Z","time spent":"385.781036ms","remote":"127.0.0.1:40314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3157,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:56:48.532292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.13305Z","time spent":"399.218828ms","remote":"127.0.0.1:40682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:56:48.532316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.133495Z","time spent":"398.705943ms","remote":"127.0.0.1:40682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:56:48.532406Z","caller":"traceutil/trace.go:171","msg":"trace[246045137] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"399.31245ms","start":"2026-04-20T20:56:48.133083Z","end":"2026-04-20T20:56:48.532396Z","steps":["trace[246045137] 'process raft request' (duration: 398.183458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:48.53245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.133055Z","time spent":"399.376322ms","remote":"127.0.0.1:40682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:56:48.53253Z","caller":"traceutil/trace.go:171","msg":"trace[2074714828] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"340.428226ms","start":"2026-04-20T20:56:48.19209Z","end":"2026-04-20T20:56:48.532518Z","steps":["trace[2074714828] 'read index received' (duration: 195.045352ms)","trace[2074714828] 'applied index is now lower than readState.Index' (duration: 145.381784ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:56:48.532663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.071382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T20:56:48.532624Z","caller":"traceutil/trace.go:171","msg":"trace[59148706] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"368.840463ms","start":"2026-04-20T20:56:48.163682Z","end":"2026-04-20T20:56:48.532523Z","steps":["trace[59148706] 'process raft request' (duration: 367.813723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:48.532697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.765232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T20:56:48.532732Z","caller":"traceutil/trace.go:171","msg":"trace[168047983] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:0; response_revision:11; }","duration":"402.817764ms","start":"2026-04-20T20:56:48.129904Z","end":"2026-04-20T20:56:48.532722Z","steps":["trace[168047983] 'agreement among raft nodes before linearized reading' (duration: 402.649029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:48.532738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.655728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T20:56:48.532764Z","caller":"traceutil/trace.go:171","msg":"trace[1794581351] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"415.682779ms","start":"2026-04-20T20:56:48.117074Z","end":"2026-04-20T20:56:48.532757Z","steps":["trace[1794581351] 'agreement among raft nodes before linearized reading' (duration: 415.643438ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:48.532701Z","caller":"traceutil/trace.go:171","msg":"trace[150690358] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"459.147484ms","start":"2026-04-20T20:56:48.073543Z","end":"2026-04-20T20:56:48.532691Z","steps":["trace[150690358] 'agreement among raft nodes before linearized reading' (duration: 459.01322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:48.532791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.073521Z","time spent":"459.264307ms","remote":"127.0.0.1:40544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-20T20:56:48.532794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.117053Z","time spent":"415.735011ms","remote":"127.0.0.1:40228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-20T20:56:48.532901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.163668Z","time spent":"369.054371ms","remote":"127.0.0.1:40400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:56:48.532926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.759557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-20T20:56:48.532947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.072834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T20:56:48.532953Z","caller":"traceutil/trace.go:171","msg":"trace[1034927345] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"350.785758ms","start":"2026-04-20T20:56:48.182159Z","end":"2026-04-20T20:56:48.532945Z","steps":["trace[1034927345] 'agreement among raft nodes before linearized reading' (duration: 350.742907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:48.532766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.129891Z","time spent":"402.868035ms","remote":"127.0.0.1:40148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-04-20T20:56:48.533022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.511117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T20:56:48.533052Z","caller":"traceutil/trace.go:171","msg":"trace[696531896] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"399.574699ms","start":"2026-04-20T20:56:48.133468Z","end":"2026-04-20T20:56:48.533042Z","steps":["trace[696531896] 'agreement among raft nodes before linearized reading' (duration: 399.525697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:48.53308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.133456Z","time spent":"399.617391ms","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-20T20:56:48.533107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.352143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T20:56:48.532974Z","caller":"traceutil/trace.go:171","msg":"trace[476515580] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"422.098895ms","start":"2026-04-20T20:56:48.110867Z","end":"2026-04-20T20:56:48.532966Z","steps":["trace[476515580] 'agreement among raft nodes before linearized reading' (duration: 422.058924ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:48.533134Z","caller":"traceutil/trace.go:171","msg":"trace[538435315] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"422.402884ms","start":"2026-04-20T20:56:48.110724Z","end":"2026-04-20T20:56:48.533127Z","steps":["trace[538435315] 'agreement among raft nodes before linearized reading' (duration: 422.362053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:48.533206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.110819Z","time spent":"422.330382ms","remote":"127.0.0.1:40228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-20T20:56:48.533252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.431114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T20:56:48.533284Z","caller":"traceutil/trace.go:171","msg":"trace[1977168828] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"401.484185ms","start":"2026-04-20T20:56:48.131791Z","end":"2026-04-20T20:56:48.533275Z","steps":["trace[1977168828] 'agreement among raft nodes before linearized reading' (duration: 401.436223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:48.533314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.131779Z","time spent":"401.524806ms","remote":"127.0.0.1:40228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-20T20:56:48.532978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.18215Z","time spent":"350.82266ms","remote":"127.0.0.1:40228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-20T20:56:48.534189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.193571Z","time spent":"338.16191ms","remote":"127.0.0.1:40172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:56:48.533162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:56:48.1107Z","time spent":"422.456335ms","remote":"127.0.0.1:40228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-20T20:57:21.968469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.778003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9bfw9\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-04-20T20:57:21.968585Z","caller":"traceutil/trace.go:171","msg":"trace[1955653413] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9bfw9; range_end:; response_count:1; response_revision:569; }","duration":"107.969167ms","start":"2026-04-20T20:57:21.860591Z","end":"2026-04-20T20:57:21.968561Z","steps":["trace[1955653413] 'range keys from in-memory index tree' (duration: 107.638698ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:22.337917Z","caller":"traceutil/trace.go:171","msg":"trace[42200667] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"259.6106ms","start":"2026-04-20T20:57:22.078277Z","end":"2026-04-20T20:57:22.337888Z","steps":["trace[42200667] 'process raft request' (duration: 257.822146ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:22.342854Z","caller":"traceutil/trace.go:171","msg":"trace[68185935] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:587; }","duration":"197.118724ms","start":"2026-04-20T20:57:22.145658Z","end":"2026-04-20T20:57:22.342776Z","steps":["trace[68185935] 'read index received' (duration: 189.675252ms)","trace[68185935] 'applied index is now lower than readState.Index' (duration: 7.441482ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:57:22.343263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.587305ms","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-04-20T20:57:22.343319Z","caller":"traceutil/trace.go:171","msg":"trace[392573401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"197.732548ms","start":"2026-04-20T20:57:22.145567Z","end":"2026-04-20T20:57:22.3433Z","steps":["trace[392573401] 'agreement among raft nodes before linearized reading' (duration: 197.406741ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:22.343534Z","caller":"traceutil/trace.go:171","msg":"trace[1948655685] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"264.153869ms","start":"2026-04-20T20:57:22.079341Z","end":"2026-04-20T20:57:22.343495Z","steps":["trace[1948655685] 'process raft request' (duration: 263.177476ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:22.528944Z","caller":"traceutil/trace.go:171","msg":"trace[2096748178] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"172.917842ms","start":"2026-04-20T20:57:22.35598Z","end":"2026-04-20T20:57:22.528897Z","steps":["trace[2096748178] 'process raft request' (duration: 158.030289ms)","trace[2096748178] 'compare' (duration: 14.536625ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:57:22.529073Z","caller":"traceutil/trace.go:171","msg":"trace[412713309] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"127.164674ms","start":"2026-04-20T20:57:22.401862Z","end":"2026-04-20T20:57:22.529027Z","steps":["trace[412713309] 'process raft request' (duration: 126.924489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:23.017194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.373882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:57:23.017487Z","caller":"traceutil/trace.go:171","msg":"trace[1510272979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"363.798073ms","start":"2026-04-20T20:57:22.653662Z","end":"2026-04-20T20:57:23.017461Z","steps":["trace[1510272979] 'range keys from in-memory index tree' (duration: 363.237389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:23.017638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:57:22.653638Z","time spent":"363.984156ms","remote":"127.0.0.1:40136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T20:57:23.017205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.274094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T20:57:23.017993Z","caller":"traceutil/trace.go:171","msg":"trace[1326019046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"288.107355ms","start":"2026-04-20T20:57:22.72982Z","end":"2026-04-20T20:57:23.017927Z","steps":["trace[1326019046] 'range keys from in-memory index tree' (duration: 287.024718ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:13.482627Z","caller":"traceutil/trace.go:171","msg":"trace[1788133005] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"137.283178ms","start":"2026-04-20T20:58:13.345316Z","end":"2026-04-20T20:58:13.482599Z","steps":["trace[1788133005] 'process raft request' (duration: 137.105383ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:13.610556Z","caller":"traceutil/trace.go:171","msg":"trace[1885495461] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"240.186117ms","start":"2026-04-20T20:58:13.370325Z","end":"2026-04-20T20:58:13.610511Z","steps":["trace[1885495461] 'process raft request' (duration: 231.01924ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:34.031872Z","caller":"traceutil/trace.go:171","msg":"trace[1944017947] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"451.338966ms","start":"2026-04-20T20:58:33.580515Z","end":"2026-04-20T20:58:34.031854Z","steps":["trace[1944017947] 'process raft request' (duration: 451.223093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:34.031988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:33.5805Z","time spent":"451.427138ms","remote":"127.0.0.1:40414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:58:34.19199Z","caller":"traceutil/trace.go:171","msg":"trace[1985938953] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1053; }","duration":"442.667651ms","start":"2026-04-20T20:58:33.749285Z","end":"2026-04-20T20:58:34.191953Z","steps":["trace[1985938953] 'read index received' (duration: 282.48759ms)","trace[1985938953] 'applied index is now lower than readState.Index' (duration: 160.178691ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:58:34.192135Z","caller":"traceutil/trace.go:171","msg":"trace[1631121889] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"512.68051ms","start":"2026-04-20T20:58:33.679412Z","end":"2026-04-20T20:58:34.192093Z","steps":["trace[1631121889] 'process raft request' (duration: 512.370632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:34.192272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.530363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T20:58:34.19216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.852347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:58:34.192315Z","caller":"traceutil/trace.go:171","msg":"trace[1706940133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1022; }","duration":"278.628517ms","start":"2026-04-20T20:58:33.913676Z","end":"2026-04-20T20:58:34.192304Z","steps":["trace[1706940133] 'agreement among raft nodes before linearized reading' (duration: 278.474672ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:34.192363Z","caller":"traceutil/trace.go:171","msg":"trace[273006676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"443.088434ms","start":"2026-04-20T20:58:33.749241Z","end":"2026-04-20T20:58:34.19233Z","steps":["trace[273006676] 'agreement among raft nodes before linearized reading' (duration: 442.852307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:34.192395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.061195ms","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-04-20T20:58:34.192433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:33.74922Z","time spent":"443.178356ms","remote":"127.0.0.1:40136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T20:58:34.192475Z","caller":"traceutil/trace.go:171","msg":"trace[1751932111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1022; }","duration":"277.218489ms","start":"2026-04-20T20:58:33.915239Z","end":"2026-04-20T20:58:34.192458Z","steps":["trace[1751932111] 'agreement among raft nodes before linearized reading' (duration: 276.968211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:58:34.192854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:58:33.679395Z","time spent":"512.873136ms","remote":"127.0.0.1:40414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:58:34.313129Z","caller":"traceutil/trace.go:171","msg":"trace[1474696482] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"117.931581ms","start":"2026-04-20T20:58:34.195168Z","end":"2026-04-20T20:58:34.313099Z","steps":["trace[1474696482] 'process raft request' (duration: 103.057409ms)","trace[1474696482] 'compare' (duration: 14.750269ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:58:34.31691Z","caller":"traceutil/trace.go:171","msg":"trace[2032549746] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"119.393601ms","start":"2026-04-20T20:58:34.197493Z","end":"2026-04-20T20:58:34.316887Z","steps":["trace[2032549746] 'process raft request' (duration: 119.34133ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:58:34.317041Z","caller":"traceutil/trace.go:171","msg":"trace[2101396431] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"120.847001ms","start":"2026-04-20T20:58:34.196168Z","end":"2026-04-20T20:58:34.317015Z","steps":["trace[2101396431] 'process raft request' (duration: 120.556233ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:06.447459Z","caller":"traceutil/trace.go:171","msg":"trace[1092236382] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"139.386759ms","start":"2026-04-20T20:59:06.308039Z","end":"2026-04-20T20:59:06.447426Z","steps":["trace[1092236382] 'process raft request' (duration: 139.204224ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:06.595095Z","caller":"traceutil/trace.go:171","msg":"trace[1092119410] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1247; }","duration":"125.941368ms","start":"2026-04-20T20:59:06.469101Z","end":"2026-04-20T20:59:06.595042Z","steps":["trace[1092119410] 'read index received' (duration: 113.420171ms)","trace[1092119410] 'applied index is now lower than readState.Index' (duration: 12.519097ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:06.595158Z","caller":"traceutil/trace.go:171","msg":"trace[624540039] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"164.60185ms","start":"2026-04-20T20:59:06.430497Z","end":"2026-04-20T20:59:06.595099Z","steps":["trace[624540039] 'process raft request' (duration: 151.94715ms)","trace[624540039] 'compare' (duration: 11.770087ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:59:06.595493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.342768ms","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-04-20T20:59:06.59554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.092708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T20:59:06.595628Z","caller":"traceutil/trace.go:171","msg":"trace[552642572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1206; }","duration":"126.556174ms","start":"2026-04-20T20:59:06.469037Z","end":"2026-04-20T20:59:06.595593Z","steps":["trace[552642572] 'agreement among raft nodes before linearized reading' (duration: 126.158903ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:06.595639Z","caller":"traceutil/trace.go:171","msg":"trace[469386199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1206; }","duration":"117.237012ms","start":"2026-04-20T20:59:06.478377Z","end":"2026-04-20T20:59:06.595614Z","steps":["trace[469386199] 'agreement among raft nodes before linearized reading' (duration: 116.999106ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:06.763647Z","caller":"traceutil/trace.go:171","msg":"trace[1028964213] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"162.463323ms","start":"2026-04-20T20:59:06.601142Z","end":"2026-04-20T20:59:06.763605Z","steps":["trace[1028964213] 'process raft request' (duration: 122.550617ms)","trace[1028964213] 'compare' (duration: 39.735551ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:06.767725Z","caller":"traceutil/trace.go:171","msg":"trace[1845561407] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1248; }","duration":"132.385441ms","start":"2026-04-20T20:59:06.635307Z","end":"2026-04-20T20:59:06.767692Z","steps":["trace[1845561407] 'read index received' (duration: 88.490137ms)","trace[1845561407] 'applied index is now lower than readState.Index' (duration: 43.893944ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:06.767863Z","caller":"traceutil/trace.go:171","msg":"trace[37858681] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"165.770572ms","start":"2026-04-20T20:59:06.602049Z","end":"2026-04-20T20:59:06.76782Z","steps":["trace[37858681] 'process raft request' (duration: 165.474904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:06.767932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.581596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:59:06.76805Z","caller":"traceutil/trace.go:171","msg":"trace[1643621945] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1208; }","duration":"132.745111ms","start":"2026-04-20T20:59:06.635236Z","end":"2026-04-20T20:59:06.767981Z","steps":["trace[1643621945] 'agreement among raft nodes before linearized reading' (duration: 132.571706ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:08.218496Z","caller":"traceutil/trace.go:171","msg":"trace[1055085930] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1255; }","duration":"106.654737ms","start":"2026-04-20T20:59:08.111806Z","end":"2026-04-20T20:59:08.218461Z","steps":["trace[1055085930] 'read index received' (duration: 106.325158ms)","trace[1055085930] 'applied index is now lower than readState.Index' (duration: 328.179µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:59:08.218536Z","caller":"traceutil/trace.go:171","msg":"trace[468095022] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"122.330181ms","start":"2026-04-20T20:59:08.096159Z","end":"2026-04-20T20:59:08.21849Z","steps":["trace[468095022] 'process raft request' (duration: 122.004032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:59:08.218751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.919774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:59:08.218813Z","caller":"traceutil/trace.go:171","msg":"trace[1845165289] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1214; }","duration":"107.028627ms","start":"2026-04-20T20:59:08.111767Z","end":"2026-04-20T20:59:08.218795Z","steps":["trace[1845165289] 'agreement among raft nodes before linearized reading' (duration: 106.815711ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:08.400182Z","caller":"traceutil/trace.go:171","msg":"trace[1119687954] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"135.425644ms","start":"2026-04-20T20:59:08.264734Z","end":"2026-04-20T20:59:08.40016Z","steps":["trace[1119687954] 'process raft request' (duration: 135.370142ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:08.400245Z","caller":"traceutil/trace.go:171","msg":"trace[670220207] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"148.905778ms","start":"2026-04-20T20:59:08.25128Z","end":"2026-04-20T20:59:08.400185Z","steps":["trace[670220207] 'process raft request' (duration: 133.78082ms)","trace[670220207] 'compare' (duration: 14.877501ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:59:21.273252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.134366ms","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-04-20T20:59:21.273335Z","caller":"traceutil/trace.go:171","msg":"trace[149711336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1301; }","duration":"178.269339ms","start":"2026-04-20T20:59:21.095047Z","end":"2026-04-20T20:59:21.273316Z","steps":["trace[149711336] 'range keys from in-memory index tree' (duration: 177.890389ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:59:21.389531Z","caller":"traceutil/trace.go:171","msg":"trace[253172559] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"110.908631ms","start":"2026-04-20T20:59:21.278399Z","end":"2026-04-20T20:59:21.389307Z","steps":["trace[253172559] 'process raft request' (duration: 110.746947ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T21:02:37.995441Z","caller":"traceutil/trace.go:171","msg":"trace[1119721630] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"103.268751ms","start":"2026-04-20T21:02:37.892152Z","end":"2026-04-20T21:02:37.995421Z","steps":["trace[1119721630] 'process raft request' (duration: 103.160338ms)"],"step_count":1}