{"level":"warn","ts":"2026-05-01T02:35:49.621859Z","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-01T02:35:49.621966Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.130: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.130:2380","--initial-cluster=instance=https://199.19.213.130:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.130:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.130: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-01T02:35:49.622037Z","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-01T02:35:49.62205Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.130:2380"]} {"level":"info","ts":"2026-05-01T02:35:49.622071Z","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-01T02:35:49.622737Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"]} {"level":"info","ts":"2026-05-01T02:35:49.622903Z","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.130:2380"],"listen-peer-urls":["https://199.19.213.130:2380"],"advertise-client-urls":["https://199.19.213.130:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.130: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-01T02:35:49.629673Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.546941ms"} {"level":"info","ts":"2026-05-01T02:35:49.638701Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a727d7e7ce5fe36a","cluster-id":"db8a5e7d54164af4"} {"level":"info","ts":"2026-05-01T02:35:49.638792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=()"} {"level":"info","ts":"2026-05-01T02:35:49.638817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became follower at term 0"} {"level":"info","ts":"2026-05-01T02:35:49.638835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a727d7e7ce5fe36a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-01T02:35:49.638853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became follower at term 1"} {"level":"info","ts":"2026-05-01T02:35:49.638893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=(12044833119025488746)"} {"level":"warn","ts":"2026-05-01T02:35:49.646298Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-01T02:35:49.647548Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-01T02:35:49.648761Z","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-01T02:35:49.651274Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a727d7e7ce5fe36a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-01T02:35:49.65163Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a727d7e7ce5fe36a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-01T02:35:49.651717Z","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-01T02:35:49.65182Z","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-01T02:35:49.653901Z","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-01T02:35:49.657191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=(12044833119025488746)"} {"level":"info","ts":"2026-05-01T02:35:49.65734Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"db8a5e7d54164af4","local-member-id":"a727d7e7ce5fe36a","added-peer-id":"a727d7e7ce5fe36a","added-peer-peer-urls":["https://199.19.213.130:2380"]} {"level":"info","ts":"2026-05-01T02:35:49.658725Z","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-01T02:35:49.658927Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.130:2380"} {"level":"info","ts":"2026-05-01T02:35:49.65899Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.130:2380"} {"level":"info","ts":"2026-05-01T02:35:49.659788Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a727d7e7ce5fe36a","initial-advertise-peer-urls":["https://199.19.213.130:2380"],"listen-peer-urls":["https://199.19.213.130:2380"],"advertise-client-urls":["https://199.19.213.130:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-01T02:35:49.659818Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-01T02:35:49.739121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a is starting a new election at term 1"} {"level":"info","ts":"2026-05-01T02:35:49.739182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-01T02:35:49.739216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a received MsgPreVoteResp from a727d7e7ce5fe36a at term 1"} {"level":"info","ts":"2026-05-01T02:35:49.739242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became candidate at term 2"} {"level":"info","ts":"2026-05-01T02:35:49.739259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a received MsgVoteResp from a727d7e7ce5fe36a at term 2"} {"level":"info","ts":"2026-05-01T02:35:49.739333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became leader at term 2"} {"level":"info","ts":"2026-05-01T02:35:49.739388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a727d7e7ce5fe36a elected leader a727d7e7ce5fe36a at term 2"} {"level":"info","ts":"2026-05-01T02:35:49.740323Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a727d7e7ce5fe36a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.130:2379]}","request-path":"/0/members/a727d7e7ce5fe36a/attributes","cluster-id":"db8a5e7d54164af4","publish-timeout":"7s"} {"level":"info","ts":"2026-05-01T02:35:49.740367Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T02:35:49.740638Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T02:35:49.740643Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T02:35:49.7416Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-01T02:35:49.741758Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-01T02:35:49.741884Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"db8a5e7d54164af4","local-member-id":"a727d7e7ce5fe36a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T02:35:49.742079Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T02:35:49.742209Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T02:35:49.743884Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.130:2379"} {"level":"info","ts":"2026-05-01T02:35:49.745928Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-01T02:35:57.470528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.495216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-01T02:35:57.470736Z","caller":"traceutil/trace.go:171","msg":"trace[1836567985] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:329; }","duration":"231.773303ms","start":"2026-05-01T02:35:57.238938Z","end":"2026-05-01T02:35:57.470711Z","steps":["trace[1836567985] 'range keys from in-memory index tree' (duration: 231.305401ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:35:57.597145Z","caller":"traceutil/trace.go:171","msg":"trace[1786422591] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"122.381471ms","start":"2026-05-01T02:35:57.474728Z","end":"2026-05-01T02:35:57.597109Z","steps":["trace[1786422591] 'process raft request' (duration: 122.126065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:35:57.900498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.254496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T02:35:57.900673Z","caller":"traceutil/trace.go:171","msg":"trace[2031399655] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"298.958192ms","start":"2026-05-01T02:35:57.601692Z","end":"2026-05-01T02:35:57.90065Z","steps":["trace[2031399655] 'read index received' (duration: 177.185436ms)","trace[2031399655] 'applied index is now lower than readState.Index' (duration: 121.771366ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T02:35:57.900778Z","caller":"traceutil/trace.go:171","msg":"trace[965942474] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"299.515337ms","start":"2026-05-01T02:35:57.60123Z","end":"2026-05-01T02:35:57.900745Z","steps":["trace[965942474] 'process raft request' (duration: 177.670419ms)","trace[965942474] 'compare' (duration: 120.044361ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T02:35:57.900804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.107676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T02:35:57.900932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.373444ms","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-05-01T02:35:57.901028Z","caller":"traceutil/trace.go:171","msg":"trace[1037763992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:331; }","duration":"155.536219ms","start":"2026-05-01T02:35:57.745469Z","end":"2026-05-01T02:35:57.901005Z","steps":["trace[1037763992] 'agreement among raft nodes before linearized reading' (duration: 155.347285ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:35:57.900971Z","caller":"traceutil/trace.go:171","msg":"trace[1516766749] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:331; }","duration":"299.317651ms","start":"2026-05-01T02:35:57.601634Z","end":"2026-05-01T02:35:57.900951Z","steps":["trace[1516766749] 'agreement among raft nodes before linearized reading' (duration: 299.129446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:35:57.90126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.506476ms","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-01T02:35:57.901364Z","caller":"traceutil/trace.go:171","msg":"trace[661229491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:331; }","duration":"148.706ms","start":"2026-05-01T02:35:57.752635Z","end":"2026-05-01T02:35:57.901341Z","steps":["trace[661229491] 'agreement among raft nodes before linearized reading' (duration: 148.444833ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:35:58.138485Z","caller":"traceutil/trace.go:171","msg":"trace[1696758090] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"233.064397ms","start":"2026-05-01T02:35:57.905373Z","end":"2026-05-01T02:35:58.138437Z","steps":["trace[1696758090] 'process raft request' (duration: 138.39381ms)","trace[1696758090] 'compare' (duration: 94.521513ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T02:35:58.170125Z","caller":"traceutil/trace.go:171","msg":"trace[174395750] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"264.679283ms","start":"2026-05-01T02:35:57.905433Z","end":"2026-05-01T02:35:58.170113Z","steps":["trace[174395750] 'process raft request' (duration: 264.577151ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:35:58.170391Z","caller":"traceutil/trace.go:171","msg":"trace[504352603] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"264.487099ms","start":"2026-05-01T02:35:57.905884Z","end":"2026-05-01T02:35:58.170371Z","steps":["trace[504352603] 'process raft request' (duration: 264.203312ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:35:58.170468Z","caller":"traceutil/trace.go:171","msg":"trace[897163038] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"263.186275ms","start":"2026-05-01T02:35:57.907265Z","end":"2026-05-01T02:35:58.170452Z","steps":["trace[897163038] 'process raft request' (duration: 263.00172ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:36:20.146627Z","caller":"traceutil/trace.go:171","msg":"trace[1937031877] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"137.617146ms","start":"2026-05-01T02:36:20.008983Z","end":"2026-05-01T02:36:20.1466Z","steps":["trace[1937031877] 'read index received' (duration: 137.536624ms)","trace[1937031877] 'applied index is now lower than readState.Index' (duration: 79.022µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T02:36:20.146723Z","caller":"traceutil/trace.go:171","msg":"trace[1839961343] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"145.658586ms","start":"2026-05-01T02:36:20.001037Z","end":"2026-05-01T02:36:20.146695Z","steps":["trace[1839961343] 'process raft request' (duration: 145.342928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:36:20.1471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.066898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10078"} {"level":"info","ts":"2026-05-01T02:36:20.147196Z","caller":"traceutil/trace.go:171","msg":"trace[10868593] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:528; }","duration":"138.233182ms","start":"2026-05-01T02:36:20.008947Z","end":"2026-05-01T02:36:20.14718Z","steps":["trace[10868593] 'agreement among raft nodes before linearized reading' (duration: 137.844422ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:36:20.153506Z","caller":"traceutil/trace.go:171","msg":"trace[1125722638] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"144.375613ms","start":"2026-05-01T02:36:20.009104Z","end":"2026-05-01T02:36:20.15348Z","steps":["trace[1125722638] 'process raft request' (duration: 144.033094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:36:20.153637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.898748ms","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-01T02:36:20.153713Z","caller":"traceutil/trace.go:171","msg":"trace[395714210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"142.024211ms","start":"2026-05-01T02:36:20.011673Z","end":"2026-05-01T02:36:20.153697Z","steps":["trace[395714210] 'agreement among raft nodes before linearized reading' (duration: 141.888907ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:36:20.153783Z","caller":"traceutil/trace.go:171","msg":"trace[39505104] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"132.318237ms","start":"2026-05-01T02:36:20.02145Z","end":"2026-05-01T02:36:20.153768Z","steps":["trace[39505104] 'process raft request' (duration: 131.808014ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:36:20.153768Z","caller":"traceutil/trace.go:171","msg":"trace[959696026] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"126.640768ms","start":"2026-05-01T02:36:20.027105Z","end":"2026-05-01T02:36:20.153746Z","steps":["trace[959696026] 'process raft request' (duration: 126.237998ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:36:20.153932Z","caller":"traceutil/trace.go:171","msg":"trace[323435205] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"118.990378ms","start":"2026-05-01T02:36:20.034932Z","end":"2026-05-01T02:36:20.153922Z","steps":["trace[323435205] 'process raft request' (duration: 118.515855ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:36:20.153932Z","caller":"traceutil/trace.go:171","msg":"trace[1217393409] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"131.875336ms","start":"2026-05-01T02:36:20.022048Z","end":"2026-05-01T02:36:20.153924Z","steps":["trace[1217393409] 'process raft request' (duration: 131.25232ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:36:26.367905Z","caller":"traceutil/trace.go:171","msg":"trace[705418560] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"238.352775ms","start":"2026-05-01T02:36:26.129528Z","end":"2026-05-01T02:36:26.367881Z","steps":["trace[705418560] 'process raft request' (duration: 238.16133ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:36:26.367879Z","caller":"traceutil/trace.go:171","msg":"trace[1830574723] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"179.579895ms","start":"2026-05-01T02:36:26.188275Z","end":"2026-05-01T02:36:26.367855Z","steps":["trace[1830574723] 'read index received' (duration: 179.35887ms)","trace[1830574723] 'applied index is now lower than readState.Index' (duration: 220.145µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T02:36:26.368112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.807761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-01T02:36:26.368163Z","caller":"traceutil/trace.go:171","msg":"trace[2138399344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"179.929864ms","start":"2026-05-01T02:36:26.188222Z","end":"2026-05-01T02:36:26.368151Z","steps":["trace[2138399344] 'agreement among raft nodes before linearized reading' (duration: 179.720739ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:36:26.698812Z","caller":"traceutil/trace.go:171","msg":"trace[1253325743] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"209.248592ms","start":"2026-05-01T02:36:26.48953Z","end":"2026-05-01T02:36:26.698778Z","steps":["trace[1253325743] 'process raft request' (duration: 208.668997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:36:27.347214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16387083785519369224,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-01T02:36:27.374634Z","caller":"traceutil/trace.go:171","msg":"trace[681765226] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"528.071086ms","start":"2026-05-01T02:36:26.846541Z","end":"2026-05-01T02:36:27.374612Z","steps":["trace[681765226] 'read index received' (duration: 527.881121ms)","trace[681765226] 'applied index is now lower than readState.Index' (duration: 189.145µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T02:36:27.374749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.193638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T02:36:27.37478Z","caller":"traceutil/trace.go:171","msg":"trace[1853982758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"528.296681ms","start":"2026-05-01T02:36:26.846474Z","end":"2026-05-01T02:36:27.374771Z","steps":["trace[1853982758] 'agreement among raft nodes before linearized reading' (duration: 528.202298ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:36:27.374751Z","caller":"traceutil/trace.go:171","msg":"trace[898318700] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"616.638985ms","start":"2026-05-01T02:36:26.75808Z","end":"2026-05-01T02:36:27.374719Z","steps":["trace[898318700] 'process raft request' (duration: 616.396639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:36:27.37481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T02:36:26.846447Z","time spent":"528.356883ms","remote":"127.0.0.1:54594","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-05-01T02:36:27.374919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T02:36:26.758048Z","time spent":"616.79358ms","remote":"127.0.0.1:54800","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-05-01T02:36:47.978522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.744908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T02:36:47.978689Z","caller":"traceutil/trace.go:171","msg":"trace[1870707415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"132.914592ms","start":"2026-05-01T02:36:47.845699Z","end":"2026-05-01T02:36:47.978613Z","steps":["trace[1870707415] 'range keys from in-memory index tree' (duration: 132.634655ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:37:22.557403Z","caller":"traceutil/trace.go:171","msg":"trace[907148444] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"144.108158ms","start":"2026-05-01T02:37:22.413274Z","end":"2026-05-01T02:37:22.557382Z","steps":["trace[907148444] 'process raft request' (duration: 143.991185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:37:22.880113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.250194ms","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-01T02:37:22.880184Z","caller":"traceutil/trace.go:171","msg":"trace[1829080693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"157.362787ms","start":"2026-05-01T02:37:22.722806Z","end":"2026-05-01T02:37:22.880169Z","steps":["trace[1829080693] 'range keys from in-memory index tree' (duration: 157.105839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:37:22.880254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.194483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-01T02:37:22.880234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.494122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-01T02:37:22.880235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.687893ms","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-01T02:37:22.880295Z","caller":"traceutil/trace.go:171","msg":"trace[368554878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"109.274515ms","start":"2026-05-01T02:37:22.771007Z","end":"2026-05-01T02:37:22.880281Z","steps":["trace[368554878] 'range keys from in-memory index tree' (duration: 109.0863ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:37:22.880306Z","caller":"traceutil/trace.go:171","msg":"trace[808691181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"114.621415ms","start":"2026-05-01T02:37:22.765669Z","end":"2026-05-01T02:37:22.88029Z","steps":["trace[808691181] 'range keys from in-memory index tree' (duration: 114.346189ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:37:22.880329Z","caller":"traceutil/trace.go:171","msg":"trace[264978868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"110.799656ms","start":"2026-05-01T02:37:22.769509Z","end":"2026-05-01T02:37:22.880309Z","steps":["trace[264978868] 'range keys from in-memory index tree' (duration: 110.465576ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:37:46.757472Z","caller":"traceutil/trace.go:171","msg":"trace[127303584] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"106.106412ms","start":"2026-05-01T02:37:46.651331Z","end":"2026-05-01T02:37:46.757437Z","steps":["trace[127303584] 'process raft request' (duration: 105.952858ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:37:59.198013Z","caller":"traceutil/trace.go:171","msg":"trace[558299168] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"350.529581ms","start":"2026-05-01T02:37:58.847458Z","end":"2026-05-01T02:37:59.197988Z","steps":["trace[558299168] 'read index received' (duration: 350.452359ms)","trace[558299168] 'applied index is now lower than readState.Index' (duration: 76.122µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T02:37:59.198143Z","caller":"traceutil/trace.go:171","msg":"trace[1240937883] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"393.56354ms","start":"2026-05-01T02:37:58.804548Z","end":"2026-05-01T02:37:59.198112Z","steps":["trace[1240937883] 'process raft request' (duration: 393.213051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:37:59.198175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.700146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T02:37:59.198215Z","caller":"traceutil/trace.go:171","msg":"trace[709275625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1180; }","duration":"350.784007ms","start":"2026-05-01T02:37:58.847419Z","end":"2026-05-01T02:37:59.198203Z","steps":["trace[709275625] 'agreement among raft nodes before linearized reading' (duration: 350.706545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:37:59.198277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.882437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-01T02:37:59.198281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T02:37:58.804512Z","time spent":"393.696893ms","remote":"127.0.0.1:54830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T02:37:59.198305Z","caller":"traceutil/trace.go:171","msg":"trace[492928599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1180; }","duration":"105.958749ms","start":"2026-05-01T02:37:59.092339Z","end":"2026-05-01T02:37:59.198298Z","steps":["trace[492928599] 'agreement among raft nodes before linearized reading' (duration: 105.841036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:37:59.198344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.965973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-01T02:37:59.19839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.968574ms","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-01T02:37:59.198417Z","caller":"traceutil/trace.go:171","msg":"trace[1271873645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1180; }","duration":"118.065686ms","start":"2026-05-01T02:37:59.080329Z","end":"2026-05-01T02:37:59.198395Z","steps":["trace[1271873645] 'agreement among raft nodes before linearized reading' (duration: 117.909972ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:37:59.198434Z","caller":"traceutil/trace.go:171","msg":"trace[1385204756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1180; }","duration":"139.051616ms","start":"2026-05-01T02:37:59.059369Z","end":"2026-05-01T02:37:59.19842Z","steps":["trace[1385204756] 'agreement among raft nodes before linearized reading' (duration: 138.803199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:37:59.198595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T02:37:58.847404Z","time spent":"351.180848ms","remote":"127.0.0.1:54594","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-05-01T02:37:59.198603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.298621ms","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-01T02:37:59.198641Z","caller":"traceutil/trace.go:171","msg":"trace[505098258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1180; }","duration":"105.364842ms","start":"2026-05-01T02:37:59.093266Z","end":"2026-05-01T02:37:59.19863Z","steps":["trace[505098258] 'agreement among raft nodes before linearized reading' (duration: 104.940421ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:38:15.43391Z","caller":"traceutil/trace.go:171","msg":"trace[1917876292] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"248.564256ms","start":"2026-05-01T02:38:15.185289Z","end":"2026-05-01T02:38:15.433853Z","steps":["trace[1917876292] 'process raft request' (duration: 248.455903ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T02:38:15.545155Z","caller":"traceutil/trace.go:171","msg":"trace[1584173107] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1301; }","duration":"167.222897ms","start":"2026-05-01T02:38:15.37791Z","end":"2026-05-01T02:38:15.545133Z","steps":["trace[1584173107] 'read index received' (duration: 55.847665ms)","trace[1584173107] 'applied index is now lower than readState.Index' (duration: 111.374212ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T02:38:15.545261Z","caller":"traceutil/trace.go:171","msg":"trace[2135612746] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"233.95493ms","start":"2026-05-01T02:38:15.311295Z","end":"2026-05-01T02:38:15.54525Z","steps":["trace[2135612746] 'process raft request' (duration: 233.067777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:38:15.545377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.104089ms","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-01T02:38:15.545435Z","caller":"traceutil/trace.go:171","msg":"trace[527527822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1255; }","duration":"160.190642ms","start":"2026-05-01T02:38:15.38523Z","end":"2026-05-01T02:38:15.545421Z","steps":["trace[527527822] 'agreement among raft nodes before linearized reading' (duration: 160.040758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:38:15.545569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.590149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-01T02:38:15.545601Z","caller":"traceutil/trace.go:171","msg":"trace[1355442854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1255; }","duration":"158.65099ms","start":"2026-05-01T02:38:15.386941Z","end":"2026-05-01T02:38:15.545592Z","steps":["trace[1355442854] 'agreement among raft nodes before linearized reading' (duration: 158.565298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:38:15.545639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.193795ms","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-05-01T02:38:15.545676Z","caller":"traceutil/trace.go:171","msg":"trace[1279826980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1255; }","duration":"159.246326ms","start":"2026-05-01T02:38:15.38642Z","end":"2026-05-01T02:38:15.545666Z","steps":["trace[1279826980] 'agreement among raft nodes before linearized reading' (duration: 159.155844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:38:15.545801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.888635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-01T02:38:15.545831Z","caller":"traceutil/trace.go:171","msg":"trace[869396582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1255; }","duration":"167.954046ms","start":"2026-05-01T02:38:15.377869Z","end":"2026-05-01T02:38:15.545823Z","steps":["trace[869396582] 'agreement among raft nodes before linearized reading' (duration: 167.872864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:38:15.545911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.758563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-01T02:38:15.545933Z","caller":"traceutil/trace.go:171","msg":"trace[926170027] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1255; }","duration":"108.814304ms","start":"2026-05-01T02:38:15.437113Z","end":"2026-05-01T02:38:15.545927Z","steps":["trace[926170027] 'agreement among raft nodes before linearized reading' (duration: 108.769543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:38:15.546123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.828778ms","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-01T02:38:15.546163Z","caller":"traceutil/trace.go:171","msg":"trace[744316298] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1255; }","duration":"160.883349ms","start":"2026-05-01T02:38:15.38527Z","end":"2026-05-01T02:38:15.546154Z","steps":["trace[744316298] 'agreement among raft nodes before linearized reading' (duration: 160.785457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T02:38:28.652868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.510605ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-01T02:38:28.8489Z","caller":"traceutil/trace.go:171","msg":"trace[35313585] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"108.217177ms","start":"2026-05-01T02:38:28.74065Z","end":"2026-05-01T02:38:28.848867Z","steps":["trace[35313585] 'process raft request' (duration: 56.116051ms)","trace[35313585] 'compare' (duration: 51.773928ms)"],"step_count":2}