{"level":"warn","ts":"2026-04-20T04:47:28.407478Z","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-20T04:47:28.40758Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-20T04:47:28.407646Z","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-20T04:47:28.40766Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-20T04:47:28.407679Z","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-20T04:47:28.408337Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-04-20T04:47:28.408472Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-20T04:47:28.412439Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.762241ms"} {"level":"info","ts":"2026-04-20T04:47:28.423484Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-04-20T04:47:28.423548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T04:47:28.423568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-04-20T04:47:28.423578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T04:47:28.423587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-04-20T04:47:28.423621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-04-20T04:47:28.43736Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T04:47:28.441724Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T04:47:28.44284Z","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-20T04:47:28.444576Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T04:47:28.444978Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T04:47:28.445181Z","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-20T04:47:28.44523Z","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-20T04:47:28.445241Z","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-20T04:47:28.446375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-04-20T04:47:28.446699Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-20T04:47:28.451641Z","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-20T04:47:28.451912Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T04:47:28.451962Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T04:47:28.452128Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-20T04:47:28.452144Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-20T04:47:29.424402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T04:47:29.424496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T04:47:29.424531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-04-20T04:47:29.424554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-04-20T04:47:29.424635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-20T04:47:29.424657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-04-20T04:47:29.424749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-20T04:47:29.426021Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T04:47:29.426066Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:47:29.426117Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:47:29.426293Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:47:29.426744Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T04:47:29.426811Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T04:47:29.427668Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:47:29.427836Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:47:29.427885Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:47:29.429599Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-04-20T04:47:29.430201Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T04:47:30.867253Z","caller":"traceutil/trace.go:171","msg":"trace[733933275] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"151.477688ms","start":"2026-04-20T04:47:30.715757Z","end":"2026-04-20T04:47:30.867234Z","steps":["trace[733933275] 'process raft request' (duration: 151.337177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:30.867934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.580723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T04:47:30.868072Z","caller":"traceutil/trace.go:171","msg":"trace[1043679503] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"149.746824ms","start":"2026-04-20T04:47:30.718277Z","end":"2026-04-20T04:47:30.868024Z","steps":["trace[1043679503] 'agreement among raft nodes before linearized reading' (duration: 149.464122ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:30.86771Z","caller":"traceutil/trace.go:171","msg":"trace[1595978464] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:5; }","duration":"149.385381ms","start":"2026-04-20T04:47:30.718292Z","end":"2026-04-20T04:47:30.867677Z","steps":["trace[1595978464] 'read index received' (duration: 149.249929ms)","trace[1595978464] 'applied index is now lower than readState.Index' (duration: 23.801µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:47:31.368575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744134652733311,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T04:47:31.397332Z","caller":"traceutil/trace.go:171","msg":"trace[1605841558] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"678.829719ms","start":"2026-04-20T04:47:30.718459Z","end":"2026-04-20T04:47:31.397289Z","steps":["trace[1605841558] 'process raft request' (duration: 678.511046ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:31.397354Z","caller":"traceutil/trace.go:171","msg":"trace[295927623] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"678.703288ms","start":"2026-04-20T04:47:30.718614Z","end":"2026-04-20T04:47:31.397317Z","steps":["trace[295927623] 'process raft request' (duration: 678.566187ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:31.397523Z","caller":"traceutil/trace.go:171","msg":"trace[1482934703] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"599.152172ms","start":"2026-04-20T04:47:30.798336Z","end":"2026-04-20T04:47:31.397488Z","steps":["trace[1482934703] 'process raft request' (duration: 598.97909ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:31.397677Z","caller":"traceutil/trace.go:171","msg":"trace[809432660] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"599.054981ms","start":"2026-04-20T04:47:30.798611Z","end":"2026-04-20T04:47:31.397666Z","steps":["trace[809432660] 'process raft request' (duration: 598.730358ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:31.3976Z","caller":"traceutil/trace.go:171","msg":"trace[1971125490] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"600.841316ms","start":"2026-04-20T04:47:30.796549Z","end":"2026-04-20T04:47:31.397391Z","steps":["trace[1971125490] 'process raft request' (duration: 600.694295ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:31.397875Z","caller":"traceutil/trace.go:171","msg":"trace[1030887696] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"598.505186ms","start":"2026-04-20T04:47:30.79933Z","end":"2026-04-20T04:47:31.397835Z","steps":["trace[1030887696] 'process raft request' (duration: 598.035733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:31.39808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.718609Z","time spent":"678.836159ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:47:31.398235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.799295Z","time spent":"598.661238ms","remote":"127.0.0.1:59162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:47:31.398281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.798329Z","time spent":"599.238122ms","remote":"127.0.0.1:59230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:47:31.398506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.798607Z","time spent":"599.100941ms","remote":"127.0.0.1:59230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:47:31.398514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.796539Z","time spent":"601.36464ms","remote":"127.0.0.1:59230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:47:31.398655Z","caller":"traceutil/trace.go:171","msg":"trace[1304544295] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"599.255593ms","start":"2026-04-20T04:47:30.798261Z","end":"2026-04-20T04:47:31.397516Z","steps":["trace[1304544295] 'process raft request' (duration: 599.021331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:31.398446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.718447Z","time spent":"678.96113ms","remote":"127.0.0.1:58746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:47:31.399237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.798249Z","time spent":"600.824145ms","remote":"127.0.0.1:59230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:47:31.399752Z","caller":"traceutil/trace.go:171","msg":"trace[2045043408] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"531.245611ms","start":"2026-04-20T04:47:30.868476Z","end":"2026-04-20T04:47:31.399721Z","steps":["trace[2045043408] 'read index received' (duration: 528.521329ms)","trace[2045043408] 'applied index is now lower than readState.Index' (duration: 2.722912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:47:31.399885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.455309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T04:47:31.400683Z","caller":"traceutil/trace.go:171","msg":"trace[807672226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"677.261445ms","start":"2026-04-20T04:47:30.723401Z","end":"2026-04-20T04:47:31.400663Z","steps":["trace[807672226] 'agreement among raft nodes before linearized reading' (duration: 676.381147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:31.399927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.516579ms","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-04-20T04:47:31.400825Z","caller":"traceutil/trace.go:171","msg":"trace[1182379759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"677.431686ms","start":"2026-04-20T04:47:30.723381Z","end":"2026-04-20T04:47:31.400813Z","steps":["trace[1182379759] 'agreement among raft nodes before linearized reading' (duration: 676.488149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:31.400916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.723375Z","time spent":"677.525847ms","remote":"127.0.0.1:58970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T04:47:31.399957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.105005ms","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-20T04:47:31.401151Z","caller":"traceutil/trace.go:171","msg":"trace[1131225248] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"604.294264ms","start":"2026-04-20T04:47:30.796836Z","end":"2026-04-20T04:47:31.40113Z","steps":["trace[1131225248] 'agreement among raft nodes before linearized reading' (duration: 603.097005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:31.401238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.796829Z","time spent":"604.391525ms","remote":"127.0.0.1:58770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-20T04:47:31.40098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.723399Z","time spent":"677.360886ms","remote":"127.0.0.1:58970","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-04-20T04:47:31.399989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.869047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T04:47:31.401463Z","caller":"traceutil/trace.go:171","msg":"trace[792955706] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:0; response_revision:11; }","duration":"607.344608ms","start":"2026-04-20T04:47:30.794103Z","end":"2026-04-20T04:47:31.401447Z","steps":["trace[792955706] 'agreement among raft nodes before linearized reading' (duration: 605.867947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:31.401509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.794096Z","time spent":"607.403229ms","remote":"127.0.0.1:58720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"info","ts":"2026-04-20T04:47:31.400037Z","caller":"traceutil/trace.go:171","msg":"trace[392277315] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"595.130228ms","start":"2026-04-20T04:47:30.804894Z","end":"2026-04-20T04:47:31.400024Z","steps":["trace[392277315] 'process raft request' (duration: 594.775045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:31.401702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.804886Z","time spent":"596.787312ms","remote":"127.0.0.1:58868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:47:31.40008Z","caller":"traceutil/trace.go:171","msg":"trace[868060683] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"600.553484ms","start":"2026-04-20T04:47:30.799518Z","end":"2026-04-20T04:47:31.400072Z","steps":["trace[868060683] 'process raft request' (duration: 600.064369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:31.401879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.799505Z","time spent":"602.338237ms","remote":"127.0.0.1:59230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:47:31.400133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.623704ms","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-20T04:47:31.402071Z","caller":"traceutil/trace.go:171","msg":"trace[1124300209] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"551.565989ms","start":"2026-04-20T04:47:30.850495Z","end":"2026-04-20T04:47:31.402061Z","steps":["trace[1124300209] 'agreement among raft nodes before linearized reading' (duration: 549.619064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:31.402106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.85049Z","time spent":"551.606639ms","remote":"127.0.0.1:58812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-20T04:47:31.400145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.42077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T04:47:31.402374Z","caller":"traceutil/trace.go:171","msg":"trace[1997554867] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"683.655808ms","start":"2026-04-20T04:47:30.718707Z","end":"2026-04-20T04:47:31.402363Z","steps":["trace[1997554867] 'agreement among raft nodes before linearized reading' (duration: 681.41398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:31.40242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.718703Z","time spent":"683.706878ms","remote":"127.0.0.1:59104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-20T04:47:31.400209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.095477ms","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-20T04:47:31.40266Z","caller":"traceutil/trace.go:171","msg":"trace[2100987443] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"643.596748ms","start":"2026-04-20T04:47:30.759052Z","end":"2026-04-20T04:47:31.402649Z","steps":["trace[2100987443] 'agreement among raft nodes before linearized reading' (duration: 641.134958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:31.402695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.759023Z","time spent":"643.662218ms","remote":"127.0.0.1:58812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-20T04:47:31.399861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.828324ms","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-20T04:47:31.402861Z","caller":"traceutil/trace.go:171","msg":"trace[1922662577] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"604.846098ms","start":"2026-04-20T04:47:30.798006Z","end":"2026-04-20T04:47:31.402852Z","steps":["trace[1922662577] 'agreement among raft nodes before linearized reading' (duration: 601.729072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:31.402902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:30.798Z","time spent":"604.886709ms","remote":"127.0.0.1:58812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-20T04:47:58.419051Z","caller":"traceutil/trace.go:171","msg":"trace[1931985758] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"435.37321ms","start":"2026-04-20T04:47:57.983648Z","end":"2026-04-20T04:47:58.419021Z","steps":["trace[1931985758] 'process raft request' (duration: 435.247859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:58.419303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:57.983621Z","time spent":"435.591841ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:47:58.475502Z","caller":"traceutil/trace.go:171","msg":"trace[922567455] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"478.869266ms","start":"2026-04-20T04:47:57.996613Z","end":"2026-04-20T04:47:58.475483Z","steps":["trace[922567455] 'process raft request' (duration: 478.751765ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:58.475583Z","caller":"traceutil/trace.go:171","msg":"trace[1601949401] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"478.373763ms","start":"2026-04-20T04:47:57.997196Z","end":"2026-04-20T04:47:58.47557Z","steps":["trace[1601949401] 'process raft request' (duration: 478.220212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:58.475734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:57.996602Z","time spent":"479.033967ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:47:58.475801Z","caller":"traceutil/trace.go:171","msg":"trace[161204224] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"476.551139ms","start":"2026-04-20T04:47:57.999236Z","end":"2026-04-20T04:47:58.475787Z","steps":["trace[161204224] 'process raft request' (duration: 476.262507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:58.4763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:57.999229Z","time spent":"477.038822ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:47:58.476635Z","caller":"traceutil/trace.go:171","msg":"trace[604176628] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"469.652089ms","start":"2026-04-20T04:47:58.006923Z","end":"2026-04-20T04:47:58.476575Z","steps":["trace[604176628] 'process raft request' (duration: 468.813044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:58.476807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:58.00691Z","time spent":"469.76523ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:47:58.476678Z","caller":"traceutil/trace.go:171","msg":"trace[640319352] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"490.217256ms","start":"2026-04-20T04:47:57.985211Z","end":"2026-04-20T04:47:58.475428Z","steps":["trace[640319352] 'process raft request' (duration: 490.006784ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:58.477034Z","caller":"traceutil/trace.go:171","msg":"trace[572040773] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"478.839405ms","start":"2026-04-20T04:47:57.996665Z","end":"2026-04-20T04:47:58.475505Z","steps":["trace[572040773] 'process raft request' (duration: 478.727774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:58.477103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:57.99666Z","time spent":"480.406256ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:47:58.47711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:57.985181Z","time spent":"491.874468ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:47:58.475979Z","caller":"traceutil/trace.go:171","msg":"trace[1717516674] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"470.917319ms","start":"2026-04-20T04:47:58.00505Z","end":"2026-04-20T04:47:58.475967Z","steps":["trace[1717516674] 'process raft request' (duration: 470.581347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:58.475726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:57.997188Z","time spent":"478.474333ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:47:58.475495Z","caller":"traceutil/trace.go:171","msg":"trace[1498100379] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"486.55317ms","start":"2026-04-20T04:47:57.988906Z","end":"2026-04-20T04:47:58.47546Z","steps":["trace[1498100379] 'process raft request' (duration: 486.425639ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:58.475675Z","caller":"traceutil/trace.go:171","msg":"trace[231471789] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"478.337303ms","start":"2026-04-20T04:47:57.997243Z","end":"2026-04-20T04:47:58.47558Z","steps":["trace[231471789] 'process raft request' (duration: 478.223732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:58.477833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:57.997238Z","time spent":"480.516248ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:47:58.478359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:58.005028Z","time spent":"473.236056ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:47:58.478374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:57.98889Z","time spent":"489.43873ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:47:58.480266Z","caller":"traceutil/trace.go:171","msg":"trace[1184466483] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"456.518767ms","start":"2026-04-20T04:47:58.023715Z","end":"2026-04-20T04:47:58.480234Z","steps":["trace[1184466483] 'process raft request' (duration: 453.554606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:58.480361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:58.023695Z","time spent":"456.619068ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:47:58.485556Z","caller":"traceutil/trace.go:171","msg":"trace[885396126] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"335.799917ms","start":"2026-04-20T04:47:58.149737Z","end":"2026-04-20T04:47:58.485537Z","steps":["trace[885396126] 'process raft request' (duration: 330.569381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:58.485652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:58.149705Z","time spent":"335.906488ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:48:04.774684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.513862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:48:04.774861Z","caller":"traceutil/trace.go:171","msg":"trace[900395049] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"463.672318ms","start":"2026-04-20T04:48:04.311167Z","end":"2026-04-20T04:48:04.774839Z","steps":["trace[900395049] 'process raft request' (duration: 202.688517ms)","trace[900395049] 'compare' (duration: 260.255612ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:48:04.774945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:04.31114Z","time spent":"463.779442ms","remote":"127.0.0.1:59212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:48:05.506655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.201592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:48:05.507119Z","caller":"traceutil/trace.go:171","msg":"trace[920324912] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"574.097593ms","start":"2026-04-20T04:48:04.932985Z","end":"2026-04-20T04:48:05.507083Z","steps":["trace[920324912] 'read index received' (duration: 272.473354ms)","trace[920324912] 'applied index is now lower than readState.Index' (duration: 301.618028ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:48:05.507196Z","caller":"traceutil/trace.go:171","msg":"trace[1002508771] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"550.682046ms","start":"2026-04-20T04:48:04.956484Z","end":"2026-04-20T04:48:05.507166Z","steps":["trace[1002508771] 'process raft request' (duration: 550.277ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:48:05.507212Z","caller":"traceutil/trace.go:171","msg":"trace[1136343242] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"701.702671ms","start":"2026-04-20T04:48:04.80548Z","end":"2026-04-20T04:48:05.507183Z","steps":["trace[1136343242] 'process raft request' (duration: 399.890144ms)","trace[1136343242] 'compare' (duration: 301.069077ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:48:05.507298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.294381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:48:05.507344Z","caller":"traceutil/trace.go:171","msg":"trace[1166604019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"574.418766ms","start":"2026-04-20T04:48:04.932913Z","end":"2026-04-20T04:48:05.507332Z","steps":["trace[1166604019] 'agreement among raft nodes before linearized reading' (duration: 574.321202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:05.507347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:04.956466Z","time spent":"550.796581ms","remote":"127.0.0.1:58868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:48:05.507353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:04.805463Z","time spent":"701.813566ms","remote":"127.0.0.1:58970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:48:05.507375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:04.932891Z","time spent":"574.476478ms","remote":"127.0.0.1:58688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T04:48:05.507448Z","caller":"traceutil/trace.go:171","msg":"trace[1850410364] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"544.082626ms","start":"2026-04-20T04:48:04.963326Z","end":"2026-04-20T04:48:05.507409Z","steps":["trace[1850410364] 'process raft request' (duration: 543.505373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:05.507595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:04.963318Z","time spent":"544.20381ms","remote":"127.0.0.1:58970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:48:05.507675Z","caller":"traceutil/trace.go:171","msg":"trace[773486421] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"344.503192ms","start":"2026-04-20T04:48:05.163152Z","end":"2026-04-20T04:48:05.507655Z","steps":["trace[773486421] 'process raft request' (duration: 343.917158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:05.507709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.289141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-20T04:48:05.507756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:05.163128Z","time spent":"344.585335ms","remote":"127.0.0.1:58782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:48:05.50777Z","caller":"traceutil/trace.go:171","msg":"trace[1368044467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"217.382695ms","start":"2026-04-20T04:48:05.290368Z","end":"2026-04-20T04:48:05.507751Z","steps":["trace[1368044467] 'agreement among raft nodes before linearized reading' (duration: 217.211528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:06.164999Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744134652734414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T04:48:06.688428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744134652734414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T04:48:07.022345Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.444122432s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T04:48:07.022744Z","caller":"traceutil/trace.go:171","msg":"trace[916122623] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"1.358796863s","start":"2026-04-20T04:48:05.663925Z","end":"2026-04-20T04:48:07.022721Z","steps":["trace[916122623] 'read index received' (duration: 1.358542663s)","trace[916122623] 'applied index is now lower than readState.Index' (duration: 252.98µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:48:07.022799Z","caller":"traceutil/trace.go:171","msg":"trace[1391221801] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.444651023s","start":"2026-04-20T04:48:05.578126Z","end":"2026-04-20T04:48:07.022777Z","steps":["trace[1391221801] 'process raft request' (duration: 1.444396573s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:07.022905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:05.57811Z","time spent":"1.444739106s","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:48:07.023029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359098755s","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-20T04:48:07.023073Z","caller":"traceutil/trace.go:171","msg":"trace[1463632865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"1.359179218s","start":"2026-04-20T04:48:05.663884Z","end":"2026-04-20T04:48:07.023063Z","steps":["trace[1463632865] 'agreement among raft nodes before linearized reading' (duration: 1.358923488s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:07.023161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:05.66387Z","time spent":"1.359261052s","remote":"127.0.0.1:58970","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-04-20T04:48:07.02318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088616015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:48:07.023278Z","caller":"traceutil/trace.go:171","msg":"trace[1506511224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"1.08874433s","start":"2026-04-20T04:48:05.934498Z","end":"2026-04-20T04:48:07.023242Z","steps":["trace[1506511224] 'agreement among raft nodes before linearized reading' (duration: 1.088602005s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:07.023332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:05.93448Z","time spent":"1.088840754s","remote":"127.0.0.1:58680","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-04-20T04:48:07.023405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271230084s","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-04-20T04:48:07.023516Z","caller":"traceutil/trace.go:171","msg":"trace[918789922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"1.271468844s","start":"2026-04-20T04:48:05.752028Z","end":"2026-04-20T04:48:07.023496Z","steps":["trace[918789922] 'agreement among raft nodes before linearized reading' (duration: 1.270995426s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:07.023593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:05.752006Z","time spent":"1.271574537s","remote":"127.0.0.1:58970","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":"info","ts":"2026-04-20T04:48:08.671349Z","caller":"traceutil/trace.go:171","msg":"trace[1086932274] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"254.256301ms","start":"2026-04-20T04:48:08.417066Z","end":"2026-04-20T04:48:08.671322Z","steps":["trace[1086932274] 'read index received' (duration: 254.133056ms)","trace[1086932274] 'applied index is now lower than readState.Index' (duration: 121.875µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:48:08.671492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.39973ms","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":"warn","ts":"2026-04-20T04:48:08.671522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.639135ms","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-04-20T04:48:08.671513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.856364ms","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":"info","ts":"2026-04-20T04:48:08.671557Z","caller":"traceutil/trace.go:171","msg":"trace[1343957437] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:609; }","duration":"261.491474ms","start":"2026-04-20T04:48:08.41005Z","end":"2026-04-20T04:48:08.671542Z","steps":["trace[1343957437] 'agreement among raft nodes before linearized reading' (duration: 261.316738ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:48:08.671571Z","caller":"traceutil/trace.go:171","msg":"trace[1190228972] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:609; }","duration":"265.735379ms","start":"2026-04-20T04:48:08.405819Z","end":"2026-04-20T04:48:08.671555Z","steps":["trace[1190228972] 'agreement among raft nodes before linearized reading' (duration: 265.636915ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:48:08.671607Z","caller":"traceutil/trace.go:171","msg":"trace[597266685] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:609; }","duration":"255.977669ms","start":"2026-04-20T04:48:08.415601Z","end":"2026-04-20T04:48:08.671579Z","steps":["trace[597266685] 'agreement among raft nodes before linearized reading' (duration: 255.784321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:08.67175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.957826ms","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":"info","ts":"2026-04-20T04:48:08.671784Z","caller":"traceutil/trace.go:171","msg":"trace[221554907] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:609; }","duration":"257.996777ms","start":"2026-04-20T04:48:08.413778Z","end":"2026-04-20T04:48:08.671775Z","steps":["trace[221554907] 'agreement among raft nodes before linearized reading' (duration: 257.953906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:08.671748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.194014ms","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-04-20T04:48:08.671848Z","caller":"traceutil/trace.go:171","msg":"trace[1614446141] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"260.002056ms","start":"2026-04-20T04:48:08.411838Z","end":"2026-04-20T04:48:08.67184Z","steps":["trace[1614446141] 'process raft request' (duration: 259.270217ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:48:08.6719Z","caller":"traceutil/trace.go:171","msg":"trace[649228791] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:609; }","duration":"259.364741ms","start":"2026-04-20T04:48:08.412472Z","end":"2026-04-20T04:48:08.671837Z","steps":["trace[649228791] 'agreement among raft nodes before linearized reading' (duration: 259.156643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:08.671929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.817106ms","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-04-20T04:48:08.672135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.376613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:48:08.672154Z","caller":"traceutil/trace.go:171","msg":"trace[533735224] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:609; }","duration":"262.008325ms","start":"2026-04-20T04:48:08.41002Z","end":"2026-04-20T04:48:08.672028Z","steps":["trace[533735224] 'agreement among raft nodes before linearized reading' (duration: 261.716993ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:48:08.672213Z","caller":"traceutil/trace.go:171","msg":"trace[1544858509] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:609; }","duration":"259.468546ms","start":"2026-04-20T04:48:08.412724Z","end":"2026-04-20T04:48:08.672193Z","steps":["trace[1544858509] 'agreement among raft nodes before linearized reading' (duration: 258.630622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:21.425937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.641967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:48:21.426518Z","caller":"traceutil/trace.go:171","msg":"trace[1474386088] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"326.911731ms","start":"2026-04-20T04:48:21.099519Z","end":"2026-04-20T04:48:21.42643Z","steps":["trace[1474386088] 'process raft request' (duration: 224.704826ms)","trace[1474386088] 'compare' (duration: 100.465807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:48:21.427287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:21.099502Z","time spent":"327.103637ms","remote":"127.0.0.1:58970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:50:12.173691Z","caller":"traceutil/trace.go:171","msg":"trace[178199023] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"108.454244ms","start":"2026-04-20T04:50:12.06521Z","end":"2026-04-20T04:50:12.173664Z","steps":["trace[178199023] 'process raft request' (duration: 108.11189ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:50:12.323509Z","caller":"traceutil/trace.go:171","msg":"trace[299096560] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"145.530907ms","start":"2026-04-20T04:50:12.177949Z","end":"2026-04-20T04:50:12.32348Z","steps":["trace[299096560] 'process raft request' (duration: 138.162772ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:50:12.351063Z","caller":"traceutil/trace.go:171","msg":"trace[1700897378] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"171.730485ms","start":"2026-04-20T04:50:12.179294Z","end":"2026-04-20T04:50:12.351025Z","steps":["trace[1700897378] 'process raft request' (duration: 171.539412ms)"],"step_count":1}