{"level":"warn","ts":"2026-03-31T10:55:25.670314Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T10:55:25.670434Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-03-31T10:55:25.670526Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T10:55:25.670544Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-03-31T10:55:25.670576Z","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-03-31T10:55:25.671353Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-03-31T10:55:25.671462Z","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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-03-31T10:55:25.676855Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.107937ms"} {"level":"info","ts":"2026-03-31T10:55:25.683162Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-03-31T10:55:25.683253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T10:55:25.683282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-03-31T10:55:25.683297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T10:55:25.683315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-03-31T10:55:25.683355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-03-31T10:55:25.690333Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T10:55:25.691686Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T10:55:25.692773Z","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-03-31T10:55:25.697238Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T10:55:25.698016Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T10:55:25.69799Z","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-03-31T10:55:25.698399Z","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-03-31T10:55:25.698424Z","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-03-31T10:55:25.698152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-03-31T10:55:25.700846Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-03-31T10:55:25.703549Z","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-03-31T10:55:25.703775Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-03-31T10:55:25.703831Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-03-31T10:55:25.704605Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T10:55:25.704658Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T10:55:26.084998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T10:55:26.085058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T10:55:26.085079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-03-31T10:55:26.08511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-03-31T10:55:26.085144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-03-31T10:55:26.085162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-03-31T10:55:26.085183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-03-31T10:55:26.086922Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T10:55:26.086971Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T10:55:26.087428Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T10:55:26.087564Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T10:55:26.089431Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T10:55:26.089829Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T10:55:26.08985Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T10:55:26.091512Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-03-31T10:55:26.093933Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T10:55:26.094001Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T10:55:26.094022Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T10:55:27.782582Z","caller":"traceutil/trace.go:171","msg":"trace[1037468280] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"218.576167ms","start":"2026-03-31T10:55:27.563963Z","end":"2026-03-31T10:55:27.782539Z","steps":["trace[1037468280] 'process raft request' (duration: 218.251961ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:27.789921Z","caller":"traceutil/trace.go:171","msg":"trace[175661582] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"171.595715ms","start":"2026-03-31T10:55:27.618254Z","end":"2026-03-31T10:55:27.789849Z","steps":["trace[175661582] 'process raft request' (duration: 171.486179ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:27.790024Z","caller":"traceutil/trace.go:171","msg":"trace[424372306] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"171.630388ms","start":"2026-03-31T10:55:27.618353Z","end":"2026-03-31T10:55:27.789984Z","steps":["trace[424372306] 'process raft request' (duration: 171.414937ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:27.790058Z","caller":"traceutil/trace.go:171","msg":"trace[1631414552] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"215.305347ms","start":"2026-03-31T10:55:27.574726Z","end":"2026-03-31T10:55:27.790031Z","steps":["trace[1631414552] 'read index received' (duration: 207.510053ms)","trace[1631414552] 'applied index is now lower than readState.Index' (duration: 7.794604ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:55:27.790157Z","caller":"traceutil/trace.go:171","msg":"trace[919302052] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"171.751914ms","start":"2026-03-31T10:55:27.618397Z","end":"2026-03-31T10:55:27.790149Z","steps":["trace[919302052] 'process raft request' (duration: 171.391916ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:27.789995Z","caller":"traceutil/trace.go:171","msg":"trace[635494305] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"225.853236ms","start":"2026-03-31T10:55:27.564091Z","end":"2026-03-31T10:55:27.789944Z","steps":["trace[635494305] 'process raft request' (duration: 225.53504ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:27.790438Z","caller":"traceutil/trace.go:171","msg":"trace[300222598] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"171.824076ms","start":"2026-03-31T10:55:27.618591Z","end":"2026-03-31T10:55:27.790415Z","steps":["trace[300222598] 'process raft request' (duration: 171.358773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:55:27.790551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.811821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T10:55:27.790604Z","caller":"traceutil/trace.go:171","msg":"trace[504966137] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"215.898385ms","start":"2026-03-31T10:55:27.574698Z","end":"2026-03-31T10:55:27.790596Z","steps":["trace[504966137] 'agreement among raft nodes before linearized reading' (duration: 215.765629ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:27.790621Z","caller":"traceutil/trace.go:171","msg":"trace[1202346120] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"172.065088ms","start":"2026-03-31T10:55:27.618543Z","end":"2026-03-31T10:55:27.790608Z","steps":["trace[1202346120] 'process raft request' (duration: 171.366523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:55:27.79072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.953418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T10:55:27.790753Z","caller":"traceutil/trace.go:171","msg":"trace[1719636287] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"192.022001ms","start":"2026-03-31T10:55:27.598723Z","end":"2026-03-31T10:55:27.790745Z","steps":["trace[1719636287] 'agreement among raft nodes before linearized reading' (duration: 191.983159ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:27.79078Z","caller":"traceutil/trace.go:171","msg":"trace[1371686890] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"172.214335ms","start":"2026-03-31T10:55:27.618552Z","end":"2026-03-31T10:55:27.790766Z","steps":["trace[1371686890] 'process raft request' (duration: 171.381324ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:27.790793Z","caller":"traceutil/trace.go:171","msg":"trace[259035519] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"157.629888ms","start":"2026-03-31T10:55:27.633148Z","end":"2026-03-31T10:55:27.790778Z","steps":["trace[259035519] 'process raft request' (duration: 156.831559ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:27.790447Z","caller":"traceutil/trace.go:171","msg":"trace[285986472] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"152.108196ms","start":"2026-03-31T10:55:27.638331Z","end":"2026-03-31T10:55:27.790439Z","steps":["trace[285986472] 'process raft request' (duration: 151.678775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:55:27.79099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.69784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T10:55:27.791023Z","caller":"traceutil/trace.go:171","msg":"trace[1661813071] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"121.759852ms","start":"2026-03-31T10:55:27.669256Z","end":"2026-03-31T10:55:27.791016Z","steps":["trace[1661813071] 'agreement among raft nodes before linearized reading' (duration: 121.662998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:55:27.791148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.189254ms","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-03-31T10:55:27.79118Z","caller":"traceutil/trace.go:171","msg":"trace[1823896208] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"172.239977ms","start":"2026-03-31T10:55:27.618927Z","end":"2026-03-31T10:55:27.791167Z","steps":["trace[1823896208] 'agreement among raft nodes before linearized reading' (duration: 172.164523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:55:27.791284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.613233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T10:55:27.791307Z","caller":"traceutil/trace.go:171","msg":"trace[737648666] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"174.656225ms","start":"2026-03-31T10:55:27.616646Z","end":"2026-03-31T10:55:27.791302Z","steps":["trace[737648666] 'agreement among raft nodes before linearized reading' (duration: 174.623693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:55:27.791461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.710632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T10:55:27.791513Z","caller":"traceutil/trace.go:171","msg":"trace[984137843] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"183.804446ms","start":"2026-03-31T10:55:27.607695Z","end":"2026-03-31T10:55:27.791499Z","steps":["trace[984137843] 'agreement among raft nodes before linearized reading' (duration: 183.67755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:55:27.791529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.719246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T10:55:27.791551Z","caller":"traceutil/trace.go:171","msg":"trace[1543809886] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"192.776239ms","start":"2026-03-31T10:55:27.598768Z","end":"2026-03-31T10:55:27.791544Z","steps":["trace[1543809886] 'agreement among raft nodes before linearized reading' (duration: 192.739017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:55:27.791626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.16041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T10:55:27.791646Z","caller":"traceutil/trace.go:171","msg":"trace[808351881] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:0; response_revision:11; }","duration":"175.223313ms","start":"2026-03-31T10:55:27.616418Z","end":"2026-03-31T10:55:27.791642Z","steps":["trace[808351881] 'agreement among raft nodes before linearized reading' (duration: 175.193672ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:54.760731Z","caller":"traceutil/trace.go:171","msg":"trace[840625110] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"124.646013ms","start":"2026-03-31T10:55:54.636064Z","end":"2026-03-31T10:55:54.76071Z","steps":["trace[840625110] 'process raft request' (duration: 124.557889ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:54.880827Z","caller":"traceutil/trace.go:171","msg":"trace[1255279275] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"224.230055ms","start":"2026-03-31T10:55:54.656573Z","end":"2026-03-31T10:55:54.880803Z","steps":["trace[1255279275] 'process raft request' (duration: 223.904453ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:54.881038Z","caller":"traceutil/trace.go:171","msg":"trace[1744918463] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"238.688851ms","start":"2026-03-31T10:55:54.642335Z","end":"2026-03-31T10:55:54.881024Z","steps":["trace[1744918463] 'process raft request' (duration: 225.556274ms)","trace[1744918463] 'compare' (duration: 12.231754ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:55:54.881106Z","caller":"traceutil/trace.go:171","msg":"trace[513636537] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"212.896045ms","start":"2026-03-31T10:55:54.668168Z","end":"2026-03-31T10:55:54.881064Z","steps":["trace[513636537] 'process raft request' (duration: 212.445919ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:54.881248Z","caller":"traceutil/trace.go:171","msg":"trace[352276425] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"224.667221ms","start":"2026-03-31T10:55:54.656498Z","end":"2026-03-31T10:55:54.881165Z","steps":["trace[352276425] 'process raft request' (duration: 223.937374ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:54.881406Z","caller":"traceutil/trace.go:171","msg":"trace[1795763158] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"224.609519ms","start":"2026-03-31T10:55:54.65678Z","end":"2026-03-31T10:55:54.88139Z","steps":["trace[1795763158] 'process raft request' (duration: 223.744266ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:54.881416Z","caller":"traceutil/trace.go:171","msg":"trace[708162122] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"211.716461ms","start":"2026-03-31T10:55:54.669679Z","end":"2026-03-31T10:55:54.881396Z","steps":["trace[708162122] 'process raft request' (duration: 210.982384ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:54.881394Z","caller":"traceutil/trace.go:171","msg":"trace[901282232] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"223.604792ms","start":"2026-03-31T10:55:54.657733Z","end":"2026-03-31T10:55:54.881338Z","steps":["trace[901282232] 'process raft request' (duration: 222.842804ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:54.881152Z","caller":"traceutil/trace.go:171","msg":"trace[1319813600] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"236.311162ms","start":"2026-03-31T10:55:54.644829Z","end":"2026-03-31T10:55:54.88114Z","steps":["trace[1319813600] 'process raft request' (duration: 235.43692ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:54.881528Z","caller":"traceutil/trace.go:171","msg":"trace[1760113431] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"230.680155ms","start":"2026-03-31T10:55:54.65084Z","end":"2026-03-31T10:55:54.88152Z","steps":["trace[1760113431] 'process raft request' (duration: 229.47544ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:54.881717Z","caller":"traceutil/trace.go:171","msg":"trace[2129885112] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"171.323314ms","start":"2026-03-31T10:55:54.710329Z","end":"2026-03-31T10:55:54.881652Z","steps":["trace[2129885112] 'process raft request' (duration: 170.383559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:55:55.008923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.065236ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-31T10:55:55.009352Z","caller":"traceutil/trace.go:171","msg":"trace[204271850] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"242.762119ms","start":"2026-03-31T10:55:54.766544Z","end":"2026-03-31T10:55:55.009306Z","steps":["trace[204271850] 'process raft request' (duration: 242.586753ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:55.009398Z","caller":"traceutil/trace.go:171","msg":"trace[432430578] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:480; }","duration":"173.772762ms","start":"2026-03-31T10:55:54.835611Z","end":"2026-03-31T10:55:55.009384Z","steps":["trace[432430578] 'read index received' (duration: 32.379132ms)","trace[432430578] 'applied index is now lower than readState.Index' (duration: 141.39302ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:55:55.009427Z","caller":"traceutil/trace.go:171","msg":"trace[1853703182] transaction","detail":"{read_only:false; number_of_response:1; response_revision:478; }","duration":"240.29961ms","start":"2026-03-31T10:55:54.769096Z","end":"2026-03-31T10:55:55.009396Z","steps":["trace[1853703182] 'process raft request' (duration: 240.06745ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:55.009502Z","caller":"traceutil/trace.go:171","msg":"trace[462598123] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"171.31233ms","start":"2026-03-31T10:55:54.838182Z","end":"2026-03-31T10:55:55.009494Z","steps":["trace[462598123] 'process raft request' (duration: 171.106982ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:55.009404Z","caller":"traceutil/trace.go:171","msg":"trace[1322343782] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"242.909075ms","start":"2026-03-31T10:55:54.766454Z","end":"2026-03-31T10:55:55.009363Z","steps":["trace[1322343782] 'process raft request' (duration: 242.566882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:55:55.009476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.865795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T10:55:55.010248Z","caller":"traceutil/trace.go:171","msg":"trace[645117887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"174.655844ms","start":"2026-03-31T10:55:54.835574Z","end":"2026-03-31T10:55:55.010229Z","steps":["trace[645117887] 'agreement among raft nodes before linearized reading' (duration: 173.851895ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:55.02325Z","caller":"traceutil/trace.go:171","msg":"trace[647274095] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"127.74821ms","start":"2026-03-31T10:55:54.895479Z","end":"2026-03-31T10:55:55.023227Z","steps":["trace[647274095] 'process raft request' (duration: 127.47119ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:55.023732Z","caller":"traceutil/trace.go:171","msg":"trace[1756868907] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"126.042066ms","start":"2026-03-31T10:55:54.897679Z","end":"2026-03-31T10:55:55.023721Z","steps":["trace[1756868907] 'process raft request' (duration: 125.415183ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:55.025951Z","caller":"traceutil/trace.go:171","msg":"trace[1158808672] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"128.234477ms","start":"2026-03-31T10:55:54.897699Z","end":"2026-03-31T10:55:55.025934Z","steps":["trace[1158808672] 'process raft request' (duration: 125.448724ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:55.026102Z","caller":"traceutil/trace.go:171","msg":"trace[1162912919] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"128.355421ms","start":"2026-03-31T10:55:54.897739Z","end":"2026-03-31T10:55:55.026094Z","steps":["trace[1162912919] 'process raft request' (duration: 127.690837ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:55.026265Z","caller":"traceutil/trace.go:171","msg":"trace[598690379] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"127.429877ms","start":"2026-03-31T10:55:54.898829Z","end":"2026-03-31T10:55:55.026259Z","steps":["trace[598690379] 'process raft request' (duration: 126.639608ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:55.026479Z","caller":"traceutil/trace.go:171","msg":"trace[2048019533] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"122.298137ms","start":"2026-03-31T10:55:54.904023Z","end":"2026-03-31T10:55:55.026321Z","steps":["trace[2048019533] 'process raft request' (duration: 121.574811ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:55.026628Z","caller":"traceutil/trace.go:171","msg":"trace[155129906] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"124.432666ms","start":"2026-03-31T10:55:54.902187Z","end":"2026-03-31T10:55:55.02662Z","steps":["trace[155129906] 'process raft request' (duration: 123.317385ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:55:55.026719Z","caller":"traceutil/trace.go:171","msg":"trace[426219439] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"123.597354ms","start":"2026-03-31T10:55:54.903111Z","end":"2026-03-31T10:55:55.026708Z","steps":["trace[426219439] 'process raft request' (duration: 122.448352ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:56:01.290501Z","caller":"traceutil/trace.go:171","msg":"trace[1353463300] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"159.438332ms","start":"2026-03-31T10:56:01.131037Z","end":"2026-03-31T10:56:01.290475Z","steps":["trace[1353463300] 'process raft request' (duration: 151.199336ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:56:01.487655Z","caller":"traceutil/trace.go:171","msg":"trace[690566339] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"152.79041ms","start":"2026-03-31T10:56:01.334839Z","end":"2026-03-31T10:56:01.48763Z","steps":["trace[690566339] 'process raft request' (duration: 152.241711ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:56:02.032622Z","caller":"traceutil/trace.go:171","msg":"trace[1406542782] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"481.692233ms","start":"2026-03-31T10:56:01.550907Z","end":"2026-03-31T10:56:02.032599Z","steps":["trace[1406542782] 'process raft request' (duration: 481.548289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:56:02.033025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:56:01.550884Z","time spent":"481.804187ms","remote":"127.0.0.1:60966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T10:56:02.384426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.77557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T10:56:02.384573Z","caller":"traceutil/trace.go:171","msg":"trace[1352914769] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:596; }","duration":"655.036256ms","start":"2026-03-31T10:56:01.729519Z","end":"2026-03-31T10:56:02.384555Z","steps":["trace[1352914769] 'read index received' (duration: 303.033706ms)","trace[1352914769] 'applied index is now lower than readState.Index' (duration: 352.00131ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:56:02.384662Z","caller":"traceutil/trace.go:171","msg":"trace[30070364] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"697.323172ms","start":"2026-03-31T10:56:01.687328Z","end":"2026-03-31T10:56:02.384651Z","steps":["trace[30070364] 'process raft request' (duration: 487.052894ms)","trace[30070364] 'compare' (duration: 209.575024ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T10:56:02.384799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:56:01.6873Z","time spent":"697.460167ms","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:56:02.385048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.642373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T10:56:02.385128Z","caller":"traceutil/trace.go:171","msg":"trace[1409521475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"550.793088ms","start":"2026-03-31T10:56:01.834313Z","end":"2026-03-31T10:56:02.385107Z","steps":["trace[1409521475] 'agreement among raft nodes before linearized reading' (duration: 550.682234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:56:02.385166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:56:01.834296Z","time spent":"550.862311ms","remote":"127.0.0.1:60736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T10:56:02.385334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.294479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-31T10:56:02.385399Z","caller":"traceutil/trace.go:171","msg":"trace[1885355415] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:578; }","duration":"345.391552ms","start":"2026-03-31T10:56:02.039988Z","end":"2026-03-31T10:56:02.385379Z","steps":["trace[1885355415] 'agreement among raft nodes before linearized reading' (duration: 345.261988ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:56:02.385394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.388082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-31T10:56:02.385434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:56:02.039969Z","time spent":"345.457395ms","remote":"127.0.0.1:60918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-03-31T10:56:02.385455Z","caller":"traceutil/trace.go:171","msg":"trace[533674186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"322.521626ms","start":"2026-03-31T10:56:02.062917Z","end":"2026-03-31T10:56:02.385439Z","steps":["trace[533674186] 'agreement among raft nodes before linearized reading' (duration: 322.320499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:56:02.385436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.896975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T10:56:02.385453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.687291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qkcd4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-31T10:56:02.385517Z","caller":"traceutil/trace.go:171","msg":"trace[40972749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"656.026289ms","start":"2026-03-31T10:56:01.729471Z","end":"2026-03-31T10:56:02.385498Z","steps":["trace[40972749] 'agreement among raft nodes before linearized reading' (duration: 655.769661ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:56:02.385534Z","caller":"traceutil/trace.go:171","msg":"trace[146566951] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qkcd4; range_end:; response_count:1; response_revision:578; }","duration":"445.815105ms","start":"2026-03-31T10:56:01.939697Z","end":"2026-03-31T10:56:02.385512Z","steps":["trace[146566951] 'agreement among raft nodes before linearized reading' (duration: 445.558157ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:56:02.385565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:56:01.729451Z","time spent":"656.105412ms","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T10:56:02.385569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:56:01.939675Z","time spent":"445.888608ms","remote":"127.0.0.1:60912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-qkcd4\" "} {"level":"warn","ts":"2026-03-31T10:56:02.385487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:56:02.0629Z","time spent":"322.580668ms","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T10:56:18.727936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.942883ms","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-03-31T10:56:18.728084Z","caller":"traceutil/trace.go:171","msg":"trace[2009677897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:740; }","duration":"268.173752ms","start":"2026-03-31T10:56:18.459877Z","end":"2026-03-31T10:56:18.728051Z","steps":["trace[2009677897] 'range keys from in-memory index tree' (duration: 267.587433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:56:18.727936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.399487ms","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-03-31T10:56:18.728446Z","caller":"traceutil/trace.go:171","msg":"trace[930511078] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"262.962826ms","start":"2026-03-31T10:56:18.46544Z","end":"2026-03-31T10:56:18.728403Z","steps":["trace[930511078] 'range keys from in-memory index tree' (duration: 262.180301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:31.66253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531406972780619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T10:58:32.163568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531406972780619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T10:58:32.402331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000359602s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-31T10:58:32.402408Z","caller":"traceutil/trace.go:171","msg":"trace[1382827159] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000460343s","start":"2026-03-31T10:58:30.40193Z","end":"2026-03-31T10:58:32.40239Z","steps":["trace[1382827159] 'agreement among raft nodes before linearized reading' (duration: 2.000371712s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:32.402497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:30.401918Z","time spent":"2.000566266s","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/31 10:58:32 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-31T10:58:32.664437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531406972780619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T10:58:33.184817Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531406972780619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T10:58:33.685996Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531406972780619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T10:58:34.186498Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531406972780619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T10:58:34.365995Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.968204994s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T10:58:34.366453Z","caller":"traceutil/trace.go:171","msg":"trace[1532564734] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"3.968729993s","start":"2026-03-31T10:58:30.397689Z","end":"2026-03-31T10:58:34.366419Z","steps":["trace[1532564734] 'process raft request' (duration: 3.968496339s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:34.367027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:30.397673Z","time spent":"3.968884205s","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:58:34.373045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:30.398035Z","time spent":"3.974992913s","remote":"127.0.0.1:60754","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-31T10:58:34.373274Z","caller":"traceutil/trace.go:171","msg":"trace[1412806507] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1397; }","duration":"3.975337228s","start":"2026-03-31T10:58:30.397907Z","end":"2026-03-31T10:58:34.373245Z","steps":["trace[1412806507] 'read index received' (duration: 3.968314705s)","trace[1412806507] 'applied index is now lower than readState.Index' (duration: 7.021463ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T10:58:34.373299Z","caller":"traceutil/trace.go:171","msg":"trace[185994406] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"3.971278853s","start":"2026-03-31T10:58:30.401953Z","end":"2026-03-31T10:58:34.373231Z","steps":["trace[185994406] 'process raft request' (duration: 3.970979589s)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:34.373402Z","caller":"traceutil/trace.go:171","msg":"trace[378851870] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"3.97040388s","start":"2026-03-31T10:58:30.402984Z","end":"2026-03-31T10:58:34.373387Z","steps":["trace[378851870] 'process raft request' (duration: 3.970174306s)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:34.373434Z","caller":"traceutil/trace.go:171","msg":"trace[1128897478] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"3.971420486s","start":"2026-03-31T10:58:30.40198Z","end":"2026-03-31T10:58:34.3734Z","steps":["trace[1128897478] 'process raft request' (duration: 3.97107907s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:34.373559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:30.402969Z","time spent":"3.970482621s","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:58:34.37356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:30.401974Z","time spent":"3.971512658s","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T10:58:34.373696Z","caller":"traceutil/trace.go:171","msg":"trace[30814630] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"3.728957951s","start":"2026-03-31T10:58:30.644648Z","end":"2026-03-31T10:58:34.373606Z","steps":["trace[30814630] 'process raft request' (duration: 3.728553654s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:34.373788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.917291571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:763"} {"level":"warn","ts":"2026-03-31T10:58:34.373847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.975887487s","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-03-31T10:58:34.373914Z","caller":"traceutil/trace.go:171","msg":"trace[64629484] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:1348; }","duration":"3.917437003s","start":"2026-03-31T10:58:30.45642Z","end":"2026-03-31T10:58:34.373857Z","steps":["trace[64629484] 'agreement among raft nodes before linearized reading' (duration: 3.91723281s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:34.37397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:30.456393Z","time spent":"3.917568666s","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":787,"request content":"key:\"/registry/clusterrolebindings/system:controller:root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-03-31T10:58:34.373991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.021504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T10:58:34.373977Z","caller":"traceutil/trace.go:171","msg":"trace[1138300740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1348; }","duration":"3.97608062s","start":"2026-03-31T10:58:30.39787Z","end":"2026-03-31T10:58:34.373951Z","steps":["trace[1138300740] 'agreement among raft nodes before linearized reading' (duration: 3.975557063s)"],"step_count":1} {"level":"info","ts":"2026-03-31T10:58:34.374048Z","caller":"traceutil/trace.go:171","msg":"trace[1637753044] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1348; }","duration":"271.113444ms","start":"2026-03-31T10:58:34.102912Z","end":"2026-03-31T10:58:34.374026Z","steps":["trace[1637753044] 'agreement among raft nodes before linearized reading' (duration: 271.029303ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:34.373904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.75699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T10:58:34.374146Z","caller":"traceutil/trace.go:171","msg":"trace[2011097208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1348; }","duration":"777.073066ms","start":"2026-03-31T10:58:33.597061Z","end":"2026-03-31T10:58:34.374134Z","steps":["trace[2011097208] 'agreement among raft nodes before linearized reading' (duration: 776.75056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:34.374215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:33.597038Z","time spent":"777.166427ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T10:58:34.374248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.97153353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T10:58:34.374074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:30.397856Z","time spent":"3.976207242s","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T10:58:34.374288Z","caller":"traceutil/trace.go:171","msg":"trace[97202247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1348; }","duration":"1.971618952s","start":"2026-03-31T10:58:32.402659Z","end":"2026-03-31T10:58:34.374278Z","steps":["trace[97202247] 'agreement among raft nodes before linearized reading' (duration: 1.971548111s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:34.374328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:32.402642Z","time spent":"1.971679103s","remote":"127.0.0.1:60736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T10:58:34.374192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:30.644613Z","time spent":"3.729205525s","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:58:34.374519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.898152943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" ","response":"range_response_count:1 size:739"} {"level":"info","ts":"2026-03-31T10:58:34.374572Z","caller":"traceutil/trace.go:171","msg":"trace[268184447] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:route-controller; range_end:; response_count:1; response_revision:1348; }","duration":"3.898219663s","start":"2026-03-31T10:58:30.476342Z","end":"2026-03-31T10:58:34.374562Z","steps":["trace[268184447] 'agreement among raft nodes before linearized reading' (duration: 3.898141532s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:34.374607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:30.476335Z","time spent":"3.898265564s","remote":"127.0.0.1:32866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":763,"request content":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" "} {"level":"info","ts":"2026-03-31T10:58:34.37482Z","caller":"traceutil/trace.go:171","msg":"trace[1250635617] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"3.971325414s","start":"2026-03-31T10:58:30.402035Z","end":"2026-03-31T10:58:34.373361Z","steps":["trace[1250635617] 'process raft request' (duration: 3.97107269s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T10:58:34.37504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:30.402023Z","time spent":"3.972861189s","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T10:58:34.374956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T10:58:30.401942Z","time spent":"3.972844799s","remote":"127.0.0.1:32768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}