{"level":"warn","ts":"2026-02-27T01:19:09.026491Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T01:19:09.026595Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209: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://162.253.55.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209: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-02-27T01:19:09.026673Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T01:19:09.026688Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-27T01:19:09.02672Z","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-02-27T01:19:09.027566Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-02-27T01:19:09.027713Z","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://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209: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-02-27T01:19:09.034612Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.556268ms"} {"level":"info","ts":"2026-02-27T01:19:09.051156Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-02-27T01:19:09.051268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T01:19:09.051395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-02-27T01:19:09.051426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T01:19:09.051435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-02-27T01:19:09.051585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-02-27T01:19:09.059585Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T01:19:09.060843Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T01:19:09.061996Z","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-02-27T01:19:09.064524Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T01:19:09.064667Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T01:19:09.064805Z","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-02-27T01:19:09.065545Z","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-02-27T01:19:09.065626Z","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-02-27T01:19:09.066911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-02-27T01:19:09.06708Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-27T01:19:09.073251Z","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-02-27T01:19:09.073369Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-27T01:19:09.073409Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-27T01:19:09.073653Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T01:19:09.07369Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T01:19:09.952328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T01:19:09.952378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T01:19:09.952401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-02-27T01:19:09.952417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-02-27T01:19:09.952424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-27T01:19:09.952436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-02-27T01:19:09.952446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-27T01:19:09.953204Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:19:09.953944Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T01:19:09.953965Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T01:19:09.954394Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T01:19:09.954469Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T01:19:09.957425Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T01:19:09.957509Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-02-27T01:19:09.958578Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:19:09.958688Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:19:09.958726Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:19:09.953901Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T01:19:13.478092Z","caller":"traceutil/trace.go:171","msg":"trace[1200692931] linearizableReadLoop","detail":"{readStateIndex:243; appliedIndex:242; }","duration":"139.989798ms","start":"2026-02-27T01:19:13.338069Z","end":"2026-02-27T01:19:13.478058Z","steps":["trace[1200692931] 'read index received' (duration: 99.045647ms)","trace[1200692931] 'applied index is now lower than readState.Index' (duration: 40.943021ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:19:13.478173Z","caller":"traceutil/trace.go:171","msg":"trace[387421677] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"149.63929ms","start":"2026-02-27T01:19:13.328502Z","end":"2026-02-27T01:19:13.478141Z","steps":["trace[387421677] 'process raft request' (duration: 108.650557ms)","trace[387421677] 'compare' (duration: 40.747073ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:19:13.478324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.195616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"info","ts":"2026-02-27T01:19:13.478395Z","caller":"traceutil/trace.go:171","msg":"trace[1000252393] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:238; }","duration":"140.343402ms","start":"2026-02-27T01:19:13.338039Z","end":"2026-02-27T01:19:13.478383Z","steps":["trace[1000252393] 'agreement among raft nodes before linearized reading' (duration: 140.147374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:15.25798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.7533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:19:15.258296Z","caller":"traceutil/trace.go:171","msg":"trace[1058491802] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"241.005306ms","start":"2026-02-27T01:19:15.017228Z","end":"2026-02-27T01:19:15.258233Z","steps":["trace[1058491802] 'process raft request' (duration: 84.277328ms)","trace[1058491802] 'compare' (duration: 155.622784ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:19:15.258361Z","caller":"traceutil/trace.go:171","msg":"trace[995967494] linearizableReadLoop","detail":"{readStateIndex:286; appliedIndex:285; }","duration":"233.512349ms","start":"2026-02-27T01:19:15.02482Z","end":"2026-02-27T01:19:15.258333Z","steps":["trace[995967494] 'read index received' (duration: 76.697927ms)","trace[995967494] 'applied index is now lower than readState.Index' (duration: 156.812061ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:19:15.258534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.689626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:19:15.25865Z","caller":"traceutil/trace.go:171","msg":"trace[1885636772] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:280; }","duration":"233.835652ms","start":"2026-02-27T01:19:15.024786Z","end":"2026-02-27T01:19:15.258621Z","steps":["trace[1885636772] 'agreement among raft nodes before linearized reading' (duration: 233.634184ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:15.259074Z","caller":"traceutil/trace.go:171","msg":"trace[1826014263] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"191.730904ms","start":"2026-02-27T01:19:15.06733Z","end":"2026-02-27T01:19:15.259061Z","steps":["trace[1826014263] 'process raft request' (duration: 191.643081ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:15.324775Z","caller":"traceutil/trace.go:171","msg":"trace[169345741] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"140.292066ms","start":"2026-02-27T01:19:15.184463Z","end":"2026-02-27T01:19:15.324755Z","steps":["trace[169345741] 'process raft request' (duration: 140.13517ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:15.324888Z","caller":"traceutil/trace.go:171","msg":"trace[321552180] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"139.961504ms","start":"2026-02-27T01:19:15.184875Z","end":"2026-02-27T01:19:15.324837Z","steps":["trace[321552180] 'process raft request' (duration: 139.846289ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:15.324922Z","caller":"traceutil/trace.go:171","msg":"trace[1623034813] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"140.108709ms","start":"2026-02-27T01:19:15.184788Z","end":"2026-02-27T01:19:15.324897Z","steps":["trace[1623034813] 'process raft request' (duration: 139.897621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:15.32505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.926816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-27T01:19:15.325132Z","caller":"traceutil/trace.go:171","msg":"trace[67334628] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:284; }","duration":"258.034881ms","start":"2026-02-27T01:19:15.067081Z","end":"2026-02-27T01:19:15.325116Z","steps":["trace[67334628] 'agreement among raft nodes before linearized reading' (duration: 257.850703ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:15.32502Z","caller":"traceutil/trace.go:171","msg":"trace[1640275899] transaction","detail":"{read_only:false; number_of_response:0; response_revision:284; }","duration":"139.548917ms","start":"2026-02-27T01:19:15.185405Z","end":"2026-02-27T01:19:15.324954Z","steps":["trace[1640275899] 'process raft request' (duration: 139.382811ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:15.325078Z","caller":"traceutil/trace.go:171","msg":"trace[910762649] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"139.981435ms","start":"2026-02-27T01:19:15.185086Z","end":"2026-02-27T01:19:15.325067Z","steps":["trace[910762649] 'process raft request' (duration: 139.673773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:15.325749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.661335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:19:15.325832Z","caller":"traceutil/trace.go:171","msg":"trace[1382308551] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:284; }","duration":"258.775989ms","start":"2026-02-27T01:19:15.067044Z","end":"2026-02-27T01:19:15.32582Z","steps":["trace[1382308551] 'agreement among raft nodes before linearized reading' (duration: 258.467557ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:15.813548Z","caller":"traceutil/trace.go:171","msg":"trace[290620061] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"154.869884ms","start":"2026-02-27T01:19:15.658643Z","end":"2026-02-27T01:19:15.813513Z","steps":["trace[290620061] 'process raft request' (duration: 80.672006ms)","trace[290620061] 'compare' (duration: 74.082543ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:19:15.813633Z","caller":"traceutil/trace.go:171","msg":"trace[1096497508] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"101.397206ms","start":"2026-02-27T01:19:15.712211Z","end":"2026-02-27T01:19:15.813608Z","steps":["trace[1096497508] 'process raft request' (duration: 101.354344ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:15.813809Z","caller":"traceutil/trace.go:171","msg":"trace[1595381715] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"101.591454ms","start":"2026-02-27T01:19:15.71218Z","end":"2026-02-27T01:19:15.813772Z","steps":["trace[1595381715] 'process raft request' (duration: 101.332344ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:15.813921Z","caller":"traceutil/trace.go:171","msg":"trace[146216123] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"105.568621ms","start":"2026-02-27T01:19:15.708338Z","end":"2026-02-27T01:19:15.813907Z","steps":["trace[146216123] 'read index received' (duration: 30.985858ms)","trace[146216123] 'applied index is now lower than readState.Index' (duration: 74.581523ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:19:15.81406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.392414ms","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-02-27T01:19:15.814379Z","caller":"traceutil/trace.go:171","msg":"trace[134438822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:307; }","duration":"156.760379ms","start":"2026-02-27T01:19:15.657597Z","end":"2026-02-27T01:19:15.814357Z","steps":["trace[134438822] 'agreement among raft nodes before linearized reading' (duration: 156.352472ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:16.266116Z","caller":"traceutil/trace.go:171","msg":"trace[1603927049] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"102.282985ms","start":"2026-02-27T01:19:16.16381Z","end":"2026-02-27T01:19:16.266093Z","steps":["trace[1603927049] 'process raft request' (duration: 94.014511ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:16.50991Z","caller":"traceutil/trace.go:171","msg":"trace[139339870] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"133.824469ms","start":"2026-02-27T01:19:16.37606Z","end":"2026-02-27T01:19:16.509884Z","steps":["trace[139339870] 'process raft request' (duration: 133.773767ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:16.510414Z","caller":"traceutil/trace.go:171","msg":"trace[2020583470] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"162.645967ms","start":"2026-02-27T01:19:16.347755Z","end":"2026-02-27T01:19:16.510401Z","steps":["trace[2020583470] 'process raft request' (duration: 101.230733ms)","trace[2020583470] 'compare' (duration: 60.694026ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:19:16.682518Z","caller":"traceutil/trace.go:171","msg":"trace[1176327663] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"157.410731ms","start":"2026-02-27T01:19:16.525081Z","end":"2026-02-27T01:19:16.682492Z","steps":["trace[1176327663] 'process raft request' (duration: 157.356009ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:16.682632Z","caller":"traceutil/trace.go:171","msg":"trace[825889458] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"162.783333ms","start":"2026-02-27T01:19:16.519802Z","end":"2026-02-27T01:19:16.682585Z","steps":["trace[825889458] 'process raft request' (duration: 116.882355ms)","trace[825889458] 'compare' (duration: 45.561724ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:19:16.882424Z","caller":"traceutil/trace.go:171","msg":"trace[945588621] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:332; }","duration":"154.171605ms","start":"2026-02-27T01:19:16.728225Z","end":"2026-02-27T01:19:16.882396Z","steps":["trace[945588621] 'read index received' (duration: 107.954116ms)","trace[945588621] 'applied index is now lower than readState.Index' (duration: 46.216169ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:19:16.88246Z","caller":"traceutil/trace.go:171","msg":"trace[522328513] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"187.272361ms","start":"2026-02-27T01:19:16.69515Z","end":"2026-02-27T01:19:16.882423Z","steps":["trace[522328513] 'process raft request' (duration: 141.029261ms)","trace[522328513] 'compare' (duration: 46.071654ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:19:16.882611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.351812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:19:16.882681Z","caller":"traceutil/trace.go:171","msg":"trace[571185109] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:322; }","duration":"154.500588ms","start":"2026-02-27T01:19:16.728163Z","end":"2026-02-27T01:19:16.882664Z","steps":["trace[571185109] 'agreement among raft nodes before linearized reading' (duration: 154.341221ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:17.684654Z","caller":"traceutil/trace.go:171","msg":"trace[1259097102] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"109.024689ms","start":"2026-02-27T01:19:17.575603Z","end":"2026-02-27T01:19:17.684628Z","steps":["trace[1259097102] 'process raft request' (duration: 26.32756ms)","trace[1259097102] 'compare' (duration: 82.586514ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:19:19.394638Z","caller":"traceutil/trace.go:171","msg":"trace[762272236] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:354; }","duration":"269.172355ms","start":"2026-02-27T01:19:19.125442Z","end":"2026-02-27T01:19:19.394614Z","steps":["trace[762272236] 'read index received' (duration: 170.771414ms)","trace[762272236] 'applied index is now lower than readState.Index' (duration: 98.399731ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:19:19.394647Z","caller":"traceutil/trace.go:171","msg":"trace[2054853986] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"274.801218ms","start":"2026-02-27T01:19:19.119803Z","end":"2026-02-27T01:19:19.394604Z","steps":["trace[2054853986] 'process raft request' (duration: 176.286883ms)","trace[2054853986] 'compare' (duration: 98.266165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:19:19.394845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.803371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-27T01:19:19.394894Z","caller":"traceutil/trace.go:171","msg":"trace[192577150] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:346; }","duration":"218.912425ms","start":"2026-02-27T01:19:19.175964Z","end":"2026-02-27T01:19:19.394877Z","steps":["trace[192577150] 'agreement among raft nodes before linearized reading' (duration: 218.810861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:19.394883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.399423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-27T01:19:19.394889Z","caller":"traceutil/trace.go:171","msg":"trace[610286275] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"274.87903ms","start":"2026-02-27T01:19:19.119866Z","end":"2026-02-27T01:19:19.394745Z","steps":["trace[610286275] 'process raft request' (duration: 274.638141ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:19.39495Z","caller":"traceutil/trace.go:171","msg":"trace[316693293] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:346; }","duration":"269.525368ms","start":"2026-02-27T01:19:19.125409Z","end":"2026-02-27T01:19:19.394934Z","steps":["trace[316693293] 'agreement among raft nodes before linearized reading' (duration: 269.344541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:19.394988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.779611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-27T01:19:19.395078Z","caller":"traceutil/trace.go:171","msg":"trace[319148532] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:346; }","duration":"118.897644ms","start":"2026-02-27T01:19:19.276159Z","end":"2026-02-27T01:19:19.395056Z","steps":["trace[319148532] 'agreement among raft nodes before linearized reading' (duration: 118.759429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:19.395159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.599571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-27T01:19:19.395215Z","caller":"traceutil/trace.go:171","msg":"trace[542372565] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:346; }","duration":"169.722416ms","start":"2026-02-27T01:19:19.225478Z","end":"2026-02-27T01:19:19.395201Z","steps":["trace[542372565] 'agreement among raft nodes before linearized reading' (duration: 169.588241ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:19.395654Z","caller":"traceutil/trace.go:171","msg":"trace[1598671738] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"274.823098ms","start":"2026-02-27T01:19:19.120801Z","end":"2026-02-27T01:19:19.395624Z","steps":["trace[1598671738] 'process raft request' (duration: 273.771748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:19.697357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.758381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:19:19.697697Z","caller":"traceutil/trace.go:171","msg":"trace[533038243] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"296.228368ms","start":"2026-02-27T01:19:19.40143Z","end":"2026-02-27T01:19:19.697658Z","steps":["trace[533038243] 'process raft request' (duration: 187.081032ms)","trace[533038243] 'compare' (duration: 108.545714ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:19:19.698152Z","caller":"traceutil/trace.go:171","msg":"trace[256792225] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"295.070074ms","start":"2026-02-27T01:19:19.403069Z","end":"2026-02-27T01:19:19.698139Z","steps":["trace[256792225] 'process raft request' (duration: 294.833545ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:19.698169Z","caller":"traceutil/trace.go:171","msg":"trace[793757390] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"295.828283ms","start":"2026-02-27T01:19:19.402312Z","end":"2026-02-27T01:19:19.698141Z","steps":["trace[793757390] 'process raft request' (duration: 295.446809ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:19.698249Z","caller":"traceutil/trace.go:171","msg":"trace[503809183] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"296.589982ms","start":"2026-02-27T01:19:19.401623Z","end":"2026-02-27T01:19:19.698213Z","steps":["trace[503809183] 'process raft request' (duration: 295.860445ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:19.698407Z","caller":"traceutil/trace.go:171","msg":"trace[2039992239] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"294.908158ms","start":"2026-02-27T01:19:19.403479Z","end":"2026-02-27T01:19:19.698388Z","steps":["trace[2039992239] 'process raft request' (duration: 294.491752ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:19.698547Z","caller":"traceutil/trace.go:171","msg":"trace[190984879] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"296.688585ms","start":"2026-02-27T01:19:19.401801Z","end":"2026-02-27T01:19:19.69849Z","steps":["trace[190984879] 'process raft request' (duration: 295.904996ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:19.699088Z","caller":"traceutil/trace.go:171","msg":"trace[283044206] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"295.472109ms","start":"2026-02-27T01:19:19.403601Z","end":"2026-02-27T01:19:19.699073Z","steps":["trace[283044206] 'process raft request' (duration: 294.400309ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:19.699289Z","caller":"traceutil/trace.go:171","msg":"trace[1107268794] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"294.557455ms","start":"2026-02-27T01:19:19.404717Z","end":"2026-02-27T01:19:19.699274Z","steps":["trace[1107268794] 'process raft request' (duration: 293.334399ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:19.699462Z","caller":"traceutil/trace.go:171","msg":"trace[334447483] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"290.810752ms","start":"2026-02-27T01:19:19.408634Z","end":"2026-02-27T01:19:19.699445Z","steps":["trace[334447483] 'process raft request' (duration: 290.622155ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:19.699497Z","caller":"traceutil/trace.go:171","msg":"trace[2127446746] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"294.334207ms","start":"2026-02-27T01:19:19.405151Z","end":"2026-02-27T01:19:19.699485Z","steps":["trace[2127446746] 'process raft request' (duration: 293.980103ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:19.699475Z","caller":"traceutil/trace.go:171","msg":"trace[2107097121] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:357; }","duration":"273.582261ms","start":"2026-02-27T01:19:19.425761Z","end":"2026-02-27T01:19:19.699343Z","steps":["trace[2107097121] 'read index received' (duration: 162.803434ms)","trace[2107097121] 'applied index is now lower than readState.Index' (duration: 110.777557ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:19:19.699855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.56994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-27T01:19:19.699935Z","caller":"traceutil/trace.go:171","msg":"trace[45078792] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:356; }","duration":"224.694225ms","start":"2026-02-27T01:19:19.475224Z","end":"2026-02-27T01:19:19.699918Z","steps":["trace[45078792] 'agreement among raft nodes before linearized reading' (duration: 224.530349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:19.700046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.268198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-27T01:19:19.700099Z","caller":"traceutil/trace.go:171","msg":"trace[1018072764] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:356; }","duration":"274.358681ms","start":"2026-02-27T01:19:19.425727Z","end":"2026-02-27T01:19:19.700085Z","steps":["trace[1018072764] 'agreement among raft nodes before linearized reading' (duration: 273.788999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:19.700152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.834947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-27T01:19:19.700192Z","caller":"traceutil/trace.go:171","msg":"trace[540483688] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:356; }","duration":"107.92346ms","start":"2026-02-27T01:19:19.592257Z","end":"2026-02-27T01:19:19.70018Z","steps":["trace[540483688] 'agreement among raft nodes before linearized reading' (duration: 107.859497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:19.700297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.866293ms","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-02-27T01:19:19.700333Z","caller":"traceutil/trace.go:171","msg":"trace[1864975378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:356; }","duration":"196.923695ms","start":"2026-02-27T01:19:19.503401Z","end":"2026-02-27T01:19:19.700325Z","steps":["trace[1864975378] 'agreement among raft nodes before linearized reading' (duration: 196.859853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:19.700492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.437722ms","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-02-27T01:19:19.700526Z","caller":"traceutil/trace.go:171","msg":"trace[886310357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:356; }","duration":"189.521864ms","start":"2026-02-27T01:19:19.510996Z","end":"2026-02-27T01:19:19.700518Z","steps":["trace[886310357] 'agreement among raft nodes before linearized reading' (duration: 189.463852ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:20.020572Z","caller":"traceutil/trace.go:171","msg":"trace[1832085392] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:377; }","duration":"242.47865ms","start":"2026-02-27T01:19:19.778066Z","end":"2026-02-27T01:19:20.020545Z","steps":["trace[1832085392] 'read index received' (duration: 235.665415ms)","trace[1832085392] 'applied index is now lower than readState.Index' (duration: 6.811924ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:19:20.020738Z","caller":"traceutil/trace.go:171","msg":"trace[1795159985] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"284.678525ms","start":"2026-02-27T01:19:19.736034Z","end":"2026-02-27T01:19:20.020713Z","steps":["trace[1795159985] 'process raft request' (duration: 277.505077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:20.020949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.942554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-27T01:19:20.021023Z","caller":"traceutil/trace.go:171","msg":"trace[542515643] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:367; }","duration":"285.152433ms","start":"2026-02-27T01:19:19.73585Z","end":"2026-02-27T01:19:20.021003Z","steps":["trace[542515643] 'agreement among raft nodes before linearized reading' (duration: 284.849221ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:20.039473Z","caller":"traceutil/trace.go:171","msg":"trace[159890350] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"256.72853ms","start":"2026-02-27T01:19:19.78272Z","end":"2026-02-27T01:19:20.039449Z","steps":["trace[159890350] 'process raft request' (duration: 256.503722ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:20.039861Z","caller":"traceutil/trace.go:171","msg":"trace[811882950] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"254.005578ms","start":"2026-02-27T01:19:19.78583Z","end":"2026-02-27T01:19:20.039835Z","steps":["trace[811882950] 'process raft request' (duration: 253.548441ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:20.040066Z","caller":"traceutil/trace.go:171","msg":"trace[1169141996] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"253.286709ms","start":"2026-02-27T01:19:19.786731Z","end":"2026-02-27T01:19:20.040017Z","steps":["trace[1169141996] 'process raft request' (duration: 253.03891ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:20.040124Z","caller":"traceutil/trace.go:171","msg":"trace[1130738068] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"253.353242ms","start":"2026-02-27T01:19:19.786731Z","end":"2026-02-27T01:19:20.040085Z","steps":["trace[1130738068] 'process raft request' (duration: 253.085342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:20.040228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.534706ms","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-02-27T01:19:20.040315Z","caller":"traceutil/trace.go:171","msg":"trace[531465414] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"195.754315ms","start":"2026-02-27T01:19:19.844547Z","end":"2026-02-27T01:19:20.040301Z","steps":["trace[531465414] 'agreement among raft nodes before linearized reading' (duration: 195.533587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:20.040719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.592566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-02-27T01:19:20.040807Z","caller":"traceutil/trace.go:171","msg":"trace[1732306579] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:371; }","duration":"255.735822ms","start":"2026-02-27T01:19:19.785057Z","end":"2026-02-27T01:19:20.040793Z","steps":["trace[1732306579] 'agreement among raft nodes before linearized reading' (duration: 254.812938ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:36.948817Z","caller":"traceutil/trace.go:171","msg":"trace[1842282741] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"605.652599ms","start":"2026-02-27T01:19:36.343145Z","end":"2026-02-27T01:19:36.948797Z","steps":["trace[1842282741] 'process raft request' (duration: 605.494654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:36.949096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:19:36.343125Z","time spent":"605.775773ms","remote":"127.0.0.1:53664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:19:42.050505Z","caller":"traceutil/trace.go:171","msg":"trace[1064564280] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"118.547952ms","start":"2026-02-27T01:19:41.931902Z","end":"2026-02-27T01:19:42.05045Z","steps":["trace[1064564280] 'process raft request' (duration: 118.221052ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:44.48357Z","caller":"traceutil/trace.go:171","msg":"trace[1211885770] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"162.544313ms","start":"2026-02-27T01:19:44.321002Z","end":"2026-02-27T01:19:44.483547Z","steps":["trace[1211885770] 'read index received' (duration: 162.401899ms)","trace[1211885770] 'applied index is now lower than readState.Index' (duration: 140.914µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:19:44.483769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.757301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:19:44.483809Z","caller":"traceutil/trace.go:171","msg":"trace[2011627375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"162.847423ms","start":"2026-02-27T01:19:44.320952Z","end":"2026-02-27T01:19:44.483799Z","steps":["trace[2011627375] 'agreement among raft nodes before linearized reading' (duration: 162.7481ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:44.483767Z","caller":"traceutil/trace.go:171","msg":"trace[2106398151] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"242.172734ms","start":"2026-02-27T01:19:44.241543Z","end":"2026-02-27T01:19:44.483716Z","steps":["trace[2106398151] 'process raft request' (duration: 241.864944ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:44.994359Z","caller":"traceutil/trace.go:171","msg":"trace[1416117468] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"501.070667ms","start":"2026-02-27T01:19:44.493259Z","end":"2026-02-27T01:19:44.99433Z","steps":["trace[1416117468] 'process raft request' (duration: 500.851711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:44.994522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:19:44.493243Z","time spent":"501.196191ms","remote":"127.0.0.1:53552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:19:50.190508Z","caller":"traceutil/trace.go:171","msg":"trace[1251089521] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"230.683265ms","start":"2026-02-27T01:19:49.959779Z","end":"2026-02-27T01:19:50.190463Z","steps":["trace[1251089521] 'read index received' (duration: 230.50637ms)","trace[1251089521] 'applied index is now lower than readState.Index' (duration: 175.925µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:19:50.190871Z","caller":"traceutil/trace.go:171","msg":"trace[670887266] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"277.975996ms","start":"2026-02-27T01:19:49.912883Z","end":"2026-02-27T01:19:50.190859Z","steps":["trace[670887266] 'process raft request' (duration: 277.467743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:50.191125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.331622ms","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-02-27T01:19:50.191157Z","caller":"traceutil/trace.go:171","msg":"trace[920857296] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"231.415544ms","start":"2026-02-27T01:19:49.959733Z","end":"2026-02-27T01:19:50.191148Z","steps":["trace[920857296] 'agreement among raft nodes before linearized reading' (duration: 231.303401ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:19:50.31758Z","caller":"traceutil/trace.go:171","msg":"trace[2087979774] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"123.850212ms","start":"2026-02-27T01:19:50.193706Z","end":"2026-02-27T01:19:50.317556Z","steps":["trace[2087979774] 'process raft request' (duration: 123.717408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:50.966379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088727258726328,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T01:19:51.466767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088727258726328,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T01:19:51.966896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088727258726328,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T01:19:52.037274Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.71996825s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T01:19:52.049905Z","caller":"traceutil/trace.go:171","msg":"trace[1724423226] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"1.584561569s","start":"2026-02-27T01:19:50.465324Z","end":"2026-02-27T01:19:52.049885Z","steps":["trace[1724423226] 'read index received' (duration: 1.572087618s)","trace[1724423226] 'applied index is now lower than readState.Index' (duration: 12.473291ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:19:52.051152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546875518s","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-02-27T01:19:52.051366Z","caller":"traceutil/trace.go:171","msg":"trace[2133811799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"1.547100364s","start":"2026-02-27T01:19:50.504227Z","end":"2026-02-27T01:19:52.051327Z","steps":["trace[2133811799] 'agreement among raft nodes before linearized reading' (duration: 1.546689764s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:52.051418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:19:50.50419Z","time spent":"1.547217477s","remote":"127.0.0.1:53664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T01:19:52.051556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.041919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:19:52.051652Z","caller":"traceutil/trace.go:171","msg":"trace[1257208584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"730.170903ms","start":"2026-02-27T01:19:51.321443Z","end":"2026-02-27T01:19:52.051614Z","steps":["trace[1257208584] 'agreement among raft nodes before linearized reading' (duration: 729.980988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:52.05182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:19:51.321413Z","time spent":"730.275846ms","remote":"127.0.0.1:53370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T01:19:52.05189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028028223s","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-02-27T01:19:52.052049Z","caller":"traceutil/trace.go:171","msg":"trace[1955913650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"1.028117896s","start":"2026-02-27T01:19:51.023804Z","end":"2026-02-27T01:19:52.051922Z","steps":["trace[1955913650] 'agreement among raft nodes before linearized reading' (duration: 1.027863929s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:52.052079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:19:51.023783Z","time spent":"1.02828752s","remote":"127.0.0.1:53664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T01:19:52.052704Z","caller":"traceutil/trace.go:171","msg":"trace[1435364098] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.84807711s","start":"2026-02-27T01:19:50.201901Z","end":"2026-02-27T01:19:52.049978Z","steps":["trace[1435364098] 'process raft request' (duration: 1.835568638s)","trace[1435364098] 'compare' (duration: 12.003649ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:19:52.052898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587585818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:19:52.052959Z","caller":"traceutil/trace.go:171","msg":"trace[501636750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"1.58763723s","start":"2026-02-27T01:19:50.4653Z","end":"2026-02-27T01:19:52.052937Z","steps":["trace[501636750] 'agreement among raft nodes before linearized reading' (duration: 1.587559428s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:52.052984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:19:50.465207Z","time spent":"1.587771113s","remote":"127.0.0.1:53384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T01:19:52.052993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:19:50.201867Z","time spent":"1.851050439s","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:19:52.4022Z","caller":"traceutil/trace.go:171","msg":"trace[1367202528] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"345.182749ms","start":"2026-02-27T01:19:52.056987Z","end":"2026-02-27T01:19:52.40217Z","steps":["trace[1367202528] 'process raft request' (duration: 328.489756ms)","trace[1367202528] 'compare' (duration: 16.324623ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:19:52.402314Z","caller":"traceutil/trace.go:171","msg":"trace[1401645521] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"343.72033ms","start":"2026-02-27T01:19:52.058533Z","end":"2026-02-27T01:19:52.402253Z","steps":["trace[1401645521] 'process raft request' (duration: 343.378621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:19:52.402498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:19:52.056972Z","time spent":"345.438075ms","remote":"127.0.0.1:53664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:19:52.402712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:19:52.058512Z","time spent":"344.025298ms","remote":"127.0.0.1:53664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:19:52.403052Z","caller":"traceutil/trace.go:171","msg":"trace[1884685064] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:576; }","duration":"208.257676ms","start":"2026-02-27T01:19:52.194782Z","end":"2026-02-27T01:19:52.40304Z","steps":["trace[1884685064] 'read index received' (duration: 190.763232ms)","trace[1884685064] 'applied index is now lower than readState.Index' (duration: 17.493403ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:19:52.403313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.524733ms","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-02-27T01:19:52.403382Z","caller":"traceutil/trace.go:171","msg":"trace[786375788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"208.630366ms","start":"2026-02-27T01:19:52.19474Z","end":"2026-02-27T01:19:52.40337Z","steps":["trace[786375788] 'agreement among raft nodes before linearized reading' (duration: 208.483652ms)"],"step_count":1}