{"level":"warn","ts":"2026-05-19T15:37:55.561127Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T15:37:55.561211Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.21:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.21:2380","--initial-cluster=instance=https://199.19.213.21:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.21:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.21:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-19T15:37:55.561333Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T15:37:55.561351Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-05-19T15:37:55.561399Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T15:37:55.56213Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"]} {"level":"info","ts":"2026-05-19T15:37:55.562274Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.21:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-19T15:37:55.565974Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.446407ms"} {"level":"info","ts":"2026-05-19T15:37:55.574521Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c39f03fd9ad57fdc","cluster-id":"3c95a04d00bbaaa4"} {"level":"info","ts":"2026-05-19T15:37:55.574662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T15:37:55.57474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 0"} {"level":"info","ts":"2026-05-19T15:37:55.574769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c39f03fd9ad57fdc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T15:37:55.574804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 1"} {"level":"info","ts":"2026-05-19T15:37:55.574883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"warn","ts":"2026-05-19T15:37:55.581417Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T15:37:55.586874Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T15:37:55.589762Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-19T15:37:55.591756Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c39f03fd9ad57fdc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T15:37:55.591982Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T15:37:55.592166Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T15:37:55.592202Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-19T15:37:55.592146Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c39f03fd9ad57fdc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T15:37:55.592676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"info","ts":"2026-05-19T15:37:55.592787Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","added-peer-id":"c39f03fd9ad57fdc","added-peer-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-05-19T15:37:55.594852Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-19T15:37:55.594964Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-05-19T15:37:55.594991Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-05-19T15:37:55.595128Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c39f03fd9ad57fdc","initial-advertise-peer-urls":["https://199.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T15:37:55.595174Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T15:37:55.77565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T15:37:55.775715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T15:37:55.775771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgPreVoteResp from c39f03fd9ad57fdc at term 1"} {"level":"info","ts":"2026-05-19T15:37:55.775787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became candidate at term 2"} {"level":"info","ts":"2026-05-19T15:37:55.775795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgVoteResp from c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-05-19T15:37:55.775807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became leader at term 2"} {"level":"info","ts":"2026-05-19T15:37:55.775816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c39f03fd9ad57fdc elected leader c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-05-19T15:37:55.776997Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c39f03fd9ad57fdc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.21:2379]}","request-path":"/0/members/c39f03fd9ad57fdc/attributes","cluster-id":"3c95a04d00bbaaa4","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T15:37:55.777125Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T15:37:55.77724Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T15:37:55.777469Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T15:37:55.777525Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T15:37:55.777608Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T15:37:55.778663Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T15:37:55.778848Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T15:37:55.778962Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T15:37:55.779799Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T15:37:55.782982Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.21:2379"} {"level":"info","ts":"2026-05-19T15:37:57.898658Z","caller":"traceutil/trace.go:171","msg":"trace[1105822548] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"134.660018ms","start":"2026-05-19T15:37:57.763935Z","end":"2026-05-19T15:37:57.898595Z","steps":["trace[1105822548] 'read index received' (duration: 134.412592ms)","trace[1105822548] 'applied index is now lower than readState.Index' (duration: 246.436µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T15:37:57.898765Z","caller":"traceutil/trace.go:171","msg":"trace[1483693941] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"136.866316ms","start":"2026-05-19T15:37:57.761848Z","end":"2026-05-19T15:37:57.898715Z","steps":["trace[1483693941] 'process raft request' (duration: 136.546809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:37:57.899148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.181699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T15:37:57.899226Z","caller":"traceutil/trace.go:171","msg":"trace[2013089299] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:2; }","duration":"135.290701ms","start":"2026-05-19T15:37:57.763919Z","end":"2026-05-19T15:37:57.899209Z","steps":["trace[2013089299] 'agreement among raft nodes before linearized reading' (duration: 134.868462ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:37:57.96369Z","caller":"traceutil/trace.go:171","msg":"trace[835438780] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"160.760798ms","start":"2026-05-19T15:37:57.802911Z","end":"2026-05-19T15:37:57.963672Z","steps":["trace[835438780] 'process raft request' (duration: 160.559203ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:37:57.964005Z","caller":"traceutil/trace.go:171","msg":"trace[2001439680] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"117.337182ms","start":"2026-05-19T15:37:57.846652Z","end":"2026-05-19T15:37:57.963989Z","steps":["trace[2001439680] 'process raft request' (duration: 116.945633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:37:57.96412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.104986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T15:37:57.964163Z","caller":"traceutil/trace.go:171","msg":"trace[495203240] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"117.169607ms","start":"2026-05-19T15:37:57.846982Z","end":"2026-05-19T15:37:57.964152Z","steps":["trace[495203240] 'agreement among raft nodes before linearized reading' (duration: 117.050575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:37:57.96434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.446564ms","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-05-19T15:37:57.964383Z","caller":"traceutil/trace.go:171","msg":"trace[688872228] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"117.500895ms","start":"2026-05-19T15:37:57.846868Z","end":"2026-05-19T15:37:57.964369Z","steps":["trace[688872228] 'agreement among raft nodes before linearized reading' (duration: 117.451063ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:37:57.964575Z","caller":"traceutil/trace.go:171","msg":"trace[989306538] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"117.632168ms","start":"2026-05-19T15:37:57.846929Z","end":"2026-05-19T15:37:57.964562Z","steps":["trace[989306538] 'process raft request' (duration: 116.699678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:37:57.96474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.04263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T15:37:57.96479Z","caller":"traceutil/trace.go:171","msg":"trace[1291833059] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:10; }","duration":"199.105481ms","start":"2026-05-19T15:37:57.765667Z","end":"2026-05-19T15:37:57.964773Z","steps":["trace[1291833059] 'agreement among raft nodes before linearized reading' (duration: 198.765184ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:37:57.963995Z","caller":"traceutil/trace.go:171","msg":"trace[1484500469] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"100.948647ms","start":"2026-05-19T15:37:57.863028Z","end":"2026-05-19T15:37:57.963976Z","steps":["trace[1484500469] 'process raft request' (duration: 100.746113ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:37:57.964959Z","caller":"traceutil/trace.go:171","msg":"trace[515747728] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"117.933464ms","start":"2026-05-19T15:37:57.847013Z","end":"2026-05-19T15:37:57.964947Z","steps":["trace[515747728] 'process raft request' (duration: 116.648966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:37:57.965031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.129496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"warn","ts":"2026-05-19T15:37:57.965078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.752855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.21\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T15:37:57.965079Z","caller":"traceutil/trace.go:171","msg":"trace[737981636] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"196.188708ms","start":"2026-05-19T15:37:57.768878Z","end":"2026-05-19T15:37:57.965067Z","steps":["trace[737981636] 'agreement among raft nodes before linearized reading' (duration: 196.118415ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:37:57.965134Z","caller":"traceutil/trace.go:171","msg":"trace[1185063055] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"118.027948ms","start":"2026-05-19T15:37:57.847056Z","end":"2026-05-19T15:37:57.965084Z","steps":["trace[1185063055] 'process raft request' (duration: 116.632637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:37:57.965218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.179301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T15:37:57.96525Z","caller":"traceutil/trace.go:171","msg":"trace[1968519650] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:10; }","duration":"198.222292ms","start":"2026-05-19T15:37:57.767018Z","end":"2026-05-19T15:37:57.965241Z","steps":["trace[1968519650] 'agreement among raft nodes before linearized reading' (duration: 198.170551ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:37:57.965348Z","caller":"traceutil/trace.go:171","msg":"trace[534141645] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"117.105306ms","start":"2026-05-19T15:37:57.848232Z","end":"2026-05-19T15:37:57.965338Z","steps":["trace[534141645] 'process raft request' (duration: 115.47619ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:37:57.965408Z","caller":"traceutil/trace.go:171","msg":"trace[760385833] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"102.40796ms","start":"2026-05-19T15:37:57.862947Z","end":"2026-05-19T15:37:57.965355Z","steps":["trace[760385833] 'process raft request' (duration: 100.792774ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:37:57.965117Z","caller":"traceutil/trace.go:171","msg":"trace[1288500712] range","detail":"{range_begin:/registry/masterleases/199.19.213.21; range_end:; response_count:0; response_revision:10; }","duration":"123.842806ms","start":"2026-05-19T15:37:57.841264Z","end":"2026-05-19T15:37:57.965107Z","steps":["trace[1288500712] 'agreement among raft nodes before linearized reading' (duration: 123.785745ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:38:24.148807Z","caller":"traceutil/trace.go:171","msg":"trace[1178122574] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"116.653308ms","start":"2026-05-19T15:38:24.032118Z","end":"2026-05-19T15:38:24.148772Z","steps":["trace[1178122574] 'process raft request' (duration: 116.447854ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:38:27.581443Z","caller":"traceutil/trace.go:171","msg":"trace[1829986244] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"396.752577ms","start":"2026-05-19T15:38:27.184402Z","end":"2026-05-19T15:38:27.581154Z","steps":["trace[1829986244] 'process raft request' (duration: 391.476895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:38:27.582274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:38:27.184378Z","time spent":"397.193675ms","remote":"127.0.0.1:50412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T15:38:27.585166Z","caller":"traceutil/trace.go:171","msg":"trace[509739566] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"307.273808ms","start":"2026-05-19T15:38:27.277867Z","end":"2026-05-19T15:38:27.585141Z","steps":["trace[509739566] 'process raft request' (duration: 307.207307ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:38:27.585334Z","caller":"traceutil/trace.go:171","msg":"trace[1462971228] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"390.386993ms","start":"2026-05-19T15:38:27.194927Z","end":"2026-05-19T15:38:27.585314Z","steps":["trace[1462971228] 'process raft request' (duration: 389.950343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:38:27.585361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:38:27.27784Z","time spent":"307.398953ms","remote":"127.0.0.1:50412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T15:38:27.585434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:38:27.194912Z","time spent":"390.480755ms","remote":"127.0.0.1:50412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T15:38:30.15232Z","caller":"traceutil/trace.go:171","msg":"trace[339623245] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"240.459106ms","start":"2026-05-19T15:38:29.91184Z","end":"2026-05-19T15:38:30.1523Z","steps":["trace[339623245] 'read index received' (duration: 240.369654ms)","trace[339623245] 'applied index is now lower than readState.Index' (duration: 88.102µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T15:38:30.152449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.59155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T15:38:30.152466Z","caller":"traceutil/trace.go:171","msg":"trace[19618588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"240.665401ms","start":"2026-05-19T15:38:29.911795Z","end":"2026-05-19T15:38:30.15246Z","steps":["trace[19618588] 'agreement among raft nodes before linearized reading' (duration: 240.607769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:38:30.152446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:38:29.484955Z","time spent":"667.484043ms","remote":"127.0.0.1:50392","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-19T15:38:30.152736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.684839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-19T15:38:30.152812Z","caller":"traceutil/trace.go:171","msg":"trace[1991869388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"103.810952ms","start":"2026-05-19T15:38:30.048983Z","end":"2026-05-19T15:38:30.152794Z","steps":["trace[1991869388] 'agreement among raft nodes before linearized reading' (duration: 103.564857ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:38:33.89245Z","caller":"traceutil/trace.go:171","msg":"trace[1991395253] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"152.259322ms","start":"2026-05-19T15:38:33.74016Z","end":"2026-05-19T15:38:33.89242Z","steps":["trace[1991395253] 'process raft request' (duration: 151.735901ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:38:34.190855Z","caller":"traceutil/trace.go:171","msg":"trace[468004255] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"240.850755ms","start":"2026-05-19T15:38:33.94997Z","end":"2026-05-19T15:38:34.19082Z","steps":["trace[468004255] 'process raft request' (duration: 172.922492ms)","trace[468004255] 'compare' (duration: 67.584485ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T15:38:35.411115Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213412939215829979,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T15:38:35.736462Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.445130249s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-19T15:38:35.952577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.385836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T15:38:35.952692Z","caller":"traceutil/trace.go:171","msg":"trace[985987963] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.661485805s","start":"2026-05-19T15:38:34.291191Z","end":"2026-05-19T15:38:35.952676Z","steps":["trace[985987963] 'process raft request' (duration: 1.445798342s)","trace[985987963] 'compare' (duration: 214.656101ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T15:38:35.952737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:38:34.29117Z","time spent":"1.661547506s","remote":"127.0.0.1:50562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T15:38:35.958843Z","caller":"traceutil/trace.go:171","msg":"trace[1530826989] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.328809008s","start":"2026-05-19T15:38:34.630006Z","end":"2026-05-19T15:38:35.958815Z","steps":["trace[1530826989] 'process raft request' (duration: 1.328289758s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:38:35.959003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:38:34.629981Z","time spent":"1.32893745s","remote":"127.0.0.1:50460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T15:38:35.976077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065546185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T15:38:35.976168Z","caller":"traceutil/trace.go:171","msg":"trace[529907249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"1.065683687s","start":"2026-05-19T15:38:34.910461Z","end":"2026-05-19T15:38:35.976145Z","steps":["trace[529907249] 'agreement among raft nodes before linearized reading' (duration: 1.065508303s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:38:35.976214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:38:34.910431Z","time spent":"1.065773129s","remote":"127.0.0.1:50366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T15:38:35.97593Z","caller":"traceutil/trace.go:171","msg":"trace[430003067] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:612; }","duration":"1.06534062s","start":"2026-05-19T15:38:34.910506Z","end":"2026-05-19T15:38:35.975846Z","steps":["trace[430003067] 'read index received' (duration: 826.555564ms)","trace[430003067] 'applied index is now lower than readState.Index' (duration: 238.782756ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T15:38:35.976953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.496738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T15:38:35.976994Z","caller":"traceutil/trace.go:171","msg":"trace[1978350793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"996.553379ms","start":"2026-05-19T15:38:34.980427Z","end":"2026-05-19T15:38:35.97698Z","steps":["trace[1978350793] 'agreement among raft nodes before linearized reading' (duration: 996.358985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:38:35.977025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:38:34.980421Z","time spent":"996.59732ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T15:38:35.97798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.730835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T15:38:35.97802Z","caller":"traceutil/trace.go:171","msg":"trace[1357816864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"997.887908ms","start":"2026-05-19T15:38:34.980119Z","end":"2026-05-19T15:38:35.978007Z","steps":["trace[1357816864] 'agreement among raft nodes before linearized reading' (duration: 996.56848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:38:35.978053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:38:34.980091Z","time spent":"997.95181ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T15:38:49.550789Z","caller":"traceutil/trace.go:171","msg":"trace[799071862] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"163.206633ms","start":"2026-05-19T15:38:49.387545Z","end":"2026-05-19T15:38:49.550752Z","steps":["trace[799071862] 'process raft request' (duration: 162.950574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:38:52.452838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.922368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-19T15:38:52.452921Z","caller":"traceutil/trace.go:171","msg":"trace[1728813254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"348.055162ms","start":"2026-05-19T15:38:52.104848Z","end":"2026-05-19T15:38:52.452903Z","steps":["trace[1728813254] 'range keys from in-memory index tree' (duration: 347.786353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:38:52.45298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:38:52.104828Z","time spent":"348.144195ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T15:38:52.452544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.53769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T15:38:52.453535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.623729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T15:38:52.453625Z","caller":"traceutil/trace.go:171","msg":"trace[972351967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"347.732522ms","start":"2026-05-19T15:38:52.105881Z","end":"2026-05-19T15:38:52.453613Z","steps":["trace[972351967] 'range keys from in-memory index tree' (duration: 347.466052ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:38:52.453642Z","caller":"traceutil/trace.go:171","msg":"trace[505142689] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"170.183296ms","start":"2026-05-19T15:38:52.283405Z","end":"2026-05-19T15:38:52.453588Z","steps":["trace[505142689] 'read index received' (duration: 165.431772ms)","trace[505142689] 'applied index is now lower than readState.Index' (duration: 4.749374ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T15:38:52.453694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:38:52.105873Z","time spent":"347.809404ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T15:38:52.453844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.452415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T15:38:52.453942Z","caller":"traceutil/trace.go:171","msg":"trace[1783242928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"170.606751ms","start":"2026-05-19T15:38:52.283324Z","end":"2026-05-19T15:38:52.453931Z","steps":["trace[1783242928] 'agreement among raft nodes before linearized reading' (duration: 170.427034ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:38:52.45384Z","caller":"traceutil/trace.go:171","msg":"trace[1530187158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"542.593675ms","start":"2026-05-19T15:38:51.91091Z","end":"2026-05-19T15:38:52.453504Z","steps":["trace[1530187158] 'range keys from in-memory index tree' (duration: 541.397017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:38:52.454093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:38:51.910874Z","time spent":"543.191535ms","remote":"127.0.0.1:50368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T15:38:52.454034Z","caller":"traceutil/trace.go:171","msg":"trace[470467096] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"251.225669ms","start":"2026-05-19T15:38:52.202798Z","end":"2026-05-19T15:38:52.454024Z","steps":["trace[470467096] 'process raft request' (duration: 245.944607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:38:52.461948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.06869ms","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-05-19T15:38:52.461991Z","caller":"traceutil/trace.go:171","msg":"trace[2014659190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"161.162434ms","start":"2026-05-19T15:38:52.300818Z","end":"2026-05-19T15:38:52.461981Z","steps":["trace[2014659190] 'agreement among raft nodes before linearized reading' (duration: 160.984308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:40:05.505261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213412939215831219,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T15:40:05.53736Z","caller":"traceutil/trace.go:171","msg":"trace[967954641] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1179; }","duration":"532.211785ms","start":"2026-05-19T15:40:05.005128Z","end":"2026-05-19T15:40:05.53734Z","steps":["trace[967954641] 'read index received' (duration: 531.489816ms)","trace[967954641] 'applied index is now lower than readState.Index' (duration: 720.789µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T15:40:05.537464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.316717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T15:40:05.537488Z","caller":"traceutil/trace.go:171","msg":"trace[1199461758] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1132; }","duration":"532.392289ms","start":"2026-05-19T15:40:05.005086Z","end":"2026-05-19T15:40:05.537479Z","steps":["trace[1199461758] 'agreement among raft nodes before linearized reading' (duration: 532.328557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:40:05.537511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:40:05.005068Z","time spent":"532.436711ms","remote":"127.0.0.1:40084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-05-19T15:40:05.537622Z","caller":"traceutil/trace.go:171","msg":"trace[1892678025] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"592.881176ms","start":"2026-05-19T15:40:04.944695Z","end":"2026-05-19T15:40:05.537576Z","steps":["trace[1892678025] 'process raft request' (duration: 591.972634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T15:40:05.537805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T15:40:04.94468Z","time spent":"593.013031ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T15:40:47.295305Z","caller":"traceutil/trace.go:171","msg":"trace[647529305] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1362; }","duration":"153.444137ms","start":"2026-05-19T15:40:47.141839Z","end":"2026-05-19T15:40:47.295283Z","steps":["trace[647529305] 'read index received' (duration: 3.79776ms)","trace[647529305] 'applied index is now lower than readState.Index' (duration: 149.645437ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T15:40:47.295361Z","caller":"traceutil/trace.go:171","msg":"trace[388601788] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"154.567693ms","start":"2026-05-19T15:40:47.140773Z","end":"2026-05-19T15:40:47.29534Z","steps":["trace[388601788] 'process raft request' (duration: 132.138485ms)","trace[388601788] 'compare' (duration: 22.194953ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T15:40:47.295416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.55765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T15:40:47.295441Z","caller":"traceutil/trace.go:171","msg":"trace[1816217263] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1307; }","duration":"153.643872ms","start":"2026-05-19T15:40:47.141789Z","end":"2026-05-19T15:40:47.295433Z","steps":["trace[1816217263] 'agreement among raft nodes before linearized reading' (duration: 153.57392ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T15:40:47.295476Z","caller":"traceutil/trace.go:171","msg":"trace[205526210] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"153.924999ms","start":"2026-05-19T15:40:47.141544Z","end":"2026-05-19T15:40:47.295469Z","steps":["trace[205526210] 'process raft request' (duration: 153.688403ms)"],"step_count":1}