{"level":"warn","ts":"2026-05-26T05:39:51.565417Z","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-26T05:39:51.565543Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65: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-26T05:39:51.565631Z","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-26T05:39:51.565645Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-05-26T05:39:51.565667Z","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-26T05:39:51.566385Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-05-26T05:39:51.566468Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65: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-26T05:39:51.573504Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.809587ms"} {"level":"info","ts":"2026-05-26T05:39:51.586699Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-05-26T05:39:51.586881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T05:39:51.586997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-05-26T05:39:51.587035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T05:39:51.587107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-05-26T05:39:51.587276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-05-26T05:39:51.597945Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T05:39:51.599421Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T05:39:51.60064Z","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-26T05:39:51.603054Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T05:39:51.603309Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T05:39:51.60365Z","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-26T05:39:51.604275Z","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-26T05:39:51.6043Z","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-26T05:39:51.608956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-05-26T05:39:51.609184Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-05-26T05:39:51.609931Z","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-26T05:39:51.610042Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-05-26T05:39:51.61009Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-05-26T05:39:51.610391Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T05:39:51.610432Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T05:39:51.687684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T05:39:51.687728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T05:39:51.687755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-05-26T05:39:51.687774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-05-26T05:39:51.687832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-05-26T05:39:51.687865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-05-26T05:39:51.687906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-05-26T05:39:51.68883Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T05:39:51.689951Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T05:39:51.690131Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T05:39:51.690245Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T05:39:51.690729Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T05:39:51.690873Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T05:39:51.690954Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T05:39:51.693165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-05-26T05:39:51.693949Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T05:39:51.694047Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T05:39:51.694084Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-05-26T05:39:53.365653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.004998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T05:39:53.365796Z","caller":"traceutil/trace.go:171","msg":"trace[152652217] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"103.169943ms","start":"2026-05-26T05:39:53.262605Z","end":"2026-05-26T05:39:53.365775Z","steps":["trace[152652217] 'agreement among raft nodes before linearized reading' (duration: 102.955196ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:39:53.394714Z","caller":"traceutil/trace.go:171","msg":"trace[1259366383] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"104.403627ms","start":"2026-05-26T05:39:53.290247Z","end":"2026-05-26T05:39:53.394651Z","steps":["trace[1259366383] 'process raft request' (duration: 104.256962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:39:53.394945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.352339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-05-26T05:39:53.395044Z","caller":"traceutil/trace.go:171","msg":"trace[1640836782] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:12; }","duration":"105.483064ms","start":"2026-05-26T05:39:53.28954Z","end":"2026-05-26T05:39:53.395023Z","steps":["trace[1640836782] 'agreement among raft nodes before linearized reading' (duration: 105.338849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:39:54.597314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.91573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-26T05:39:54.597483Z","caller":"traceutil/trace.go:171","msg":"trace[343596096] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"116.20328ms","start":"2026-05-26T05:39:54.481255Z","end":"2026-05-26T05:39:54.597459Z","steps":["trace[343596096] 'compare' (duration: 113.727613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:39:55.056982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.496346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-26T05:39:55.057194Z","caller":"traceutil/trace.go:171","msg":"trace[843671627] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"176.763832ms","start":"2026-05-26T05:39:54.880406Z","end":"2026-05-26T05:39:55.057169Z","steps":["trace[843671627] 'process raft request' (duration: 23.995036ms)","trace[843671627] 'compare' (duration: 152.357712ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:39:55.748849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.800379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-26T05:39:55.748986Z","caller":"traceutil/trace.go:171","msg":"trace[1489004593] linearizableReadLoop","detail":"{readStateIndex:114; appliedIndex:113; }","duration":"372.253504ms","start":"2026-05-26T05:39:55.37671Z","end":"2026-05-26T05:39:55.748963Z","steps":["trace[1489004593] 'read index received' (duration: 168.105633ms)","trace[1489004593] 'applied index is now lower than readState.Index' (duration: 204.146691ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:39:55.749124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.405569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-26T05:39:55.749163Z","caller":"traceutil/trace.go:171","msg":"trace[88198582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:110; }","duration":"372.483862ms","start":"2026-05-26T05:39:55.376668Z","end":"2026-05-26T05:39:55.749152Z","steps":["trace[88198582] 'agreement among raft nodes before linearized reading' (duration: 372.349987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:39:55.749233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:39:55.376646Z","time spent":"372.574345ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-26T05:39:55.7492Z","caller":"traceutil/trace.go:171","msg":"trace[1147511537] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"398.876233ms","start":"2026-05-26T05:39:55.350109Z","end":"2026-05-26T05:39:55.748985Z","steps":["trace[1147511537] 'process raft request' (duration: 194.852636ms)","trace[1147511537] 'compare' (duration: 203.609523ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:39:55.749522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:39:55.350095Z","time spent":"399.33176ms","remote":"127.0.0.1:41270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T05:39:56.331221Z","caller":"traceutil/trace.go:171","msg":"trace[1490187099] transaction","detail":"{read_only:false; response_revision:162; number_of_response:1; }","duration":"105.45343ms","start":"2026-05-26T05:39:56.225728Z","end":"2026-05-26T05:39:56.331181Z","steps":["trace[1490187099] 'process raft request' (duration: 36.137251ms)","trace[1490187099] 'compare' (duration: 69.122112ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:39:57.720278Z","caller":"traceutil/trace.go:171","msg":"trace[1568735228] linearizableReadLoop","detail":"{readStateIndex:230; appliedIndex:228; }","duration":"176.392272ms","start":"2026-05-26T05:39:57.54385Z","end":"2026-05-26T05:39:57.720243Z","steps":["trace[1568735228] 'read index received' (duration: 19.277793ms)","trace[1568735228] 'applied index is now lower than readState.Index' (duration: 157.113239ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:39:57.720328Z","caller":"traceutil/trace.go:171","msg":"trace[1410829407] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"181.634935ms","start":"2026-05-26T05:39:57.53858Z","end":"2026-05-26T05:39:57.720215Z","steps":["trace[1410829407] 'process raft request' (duration: 113.312068ms)","trace[1410829407] 'compare' (duration: 67.975225ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:39:57.720432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.557937ms","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-26T05:39:57.720529Z","caller":"traceutil/trace.go:171","msg":"trace[1581497505] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:225; }","duration":"176.684942ms","start":"2026-05-26T05:39:57.543819Z","end":"2026-05-26T05:39:57.720504Z","steps":["trace[1581497505] 'agreement among raft nodes before linearized reading' (duration: 176.545057ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:39:57.720538Z","caller":"traceutil/trace.go:171","msg":"trace[80066352] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"176.377332ms","start":"2026-05-26T05:39:57.54412Z","end":"2026-05-26T05:39:57.720497Z","steps":["trace[80066352] 'process raft request' (duration: 176.008099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:39:59.232016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.882643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-26T05:39:59.232223Z","caller":"traceutil/trace.go:171","msg":"trace[1700709512] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"280.325254ms","start":"2026-05-26T05:39:58.951837Z","end":"2026-05-26T05:39:59.232162Z","steps":["trace[1700709512] 'process raft request' (duration: 150.186162ms)","trace[1700709512] 'compare' (duration: 129.333494ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:39:59.232382Z","caller":"traceutil/trace.go:171","msg":"trace[837881786] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:321; }","duration":"238.828045ms","start":"2026-05-26T05:39:58.993529Z","end":"2026-05-26T05:39:59.232357Z","steps":["trace[837881786] 'read index received' (duration: 108.523137ms)","trace[837881786] 'applied index is now lower than readState.Index' (duration: 130.302848ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:39:59.232666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.093344ms","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-26T05:39:59.232705Z","caller":"traceutil/trace.go:171","msg":"trace[468588727] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:311; }","duration":"239.179857ms","start":"2026-05-26T05:39:58.993513Z","end":"2026-05-26T05:39:59.232692Z","steps":["trace[468588727] 'agreement among raft nodes before linearized reading' (duration: 238.911217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:00.498973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.098439ms","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-05-26T05:40:00.499074Z","caller":"traceutil/trace.go:171","msg":"trace[147065894] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:330; }","duration":"105.277866ms","start":"2026-05-26T05:40:00.393769Z","end":"2026-05-26T05:40:00.499047Z","steps":["trace[147065894] 'range keys from in-memory index tree' (duration: 104.995286ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:06.046732Z","caller":"traceutil/trace.go:171","msg":"trace[1848651660] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"203.656153ms","start":"2026-05-26T05:40:05.843039Z","end":"2026-05-26T05:40:06.046695Z","steps":["trace[1848651660] 'read index received' (duration: 203.346142ms)","trace[1848651660] 'applied index is now lower than readState.Index' (duration: 308.111µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:40:06.046901Z","caller":"traceutil/trace.go:171","msg":"trace[1365327484] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"441.222974ms","start":"2026-05-26T05:40:05.605661Z","end":"2026-05-26T05:40:06.046884Z","steps":["trace[1365327484] 'process raft request' (duration: 440.80843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:06.04709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.026555ms","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-26T05:40:06.04719Z","caller":"traceutil/trace.go:171","msg":"trace[1620202833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:401; }","duration":"204.186841ms","start":"2026-05-26T05:40:05.842978Z","end":"2026-05-26T05:40:06.047165Z","steps":["trace[1620202833] 'agreement among raft nodes before linearized reading' (duration: 204.033846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:06.047866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:05.605641Z","time spent":"441.294636ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:40:07.081169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.932083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T05:40:07.081364Z","caller":"traceutil/trace.go:171","msg":"trace[1513746292] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"1.02913649s","start":"2026-05-26T05:40:06.05216Z","end":"2026-05-26T05:40:07.081296Z","steps":["trace[1513746292] 'process raft request' (duration: 301.970989ms)","trace[1513746292] 'compare' (duration: 726.772507ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:40:07.081509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:06.05213Z","time spent":"1.029340567s","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:40:07.10785Z","caller":"traceutil/trace.go:171","msg":"trace[2078521202] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"444.5027ms","start":"2026-05-26T05:40:06.663318Z","end":"2026-05-26T05:40:07.10782Z","steps":["trace[2078521202] 'read index received' (duration: 444.301803ms)","trace[2078521202] 'applied index is now lower than readState.Index' (duration: 199.937µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:40:07.108037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.687197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-26T05:40:07.108013Z","caller":"traceutil/trace.go:171","msg":"trace[1363840859] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"1.05331166s","start":"2026-05-26T05:40:06.054637Z","end":"2026-05-26T05:40:07.107949Z","steps":["trace[1363840859] 'process raft request' (duration: 1.053052551s)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:07.108083Z","caller":"traceutil/trace.go:171","msg":"trace[44852025] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:403; }","duration":"444.80859ms","start":"2026-05-26T05:40:06.663262Z","end":"2026-05-26T05:40:07.108071Z","steps":["trace[44852025] 'agreement among raft nodes before linearized reading' (duration: 444.676636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:07.108121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:06.663244Z","time spent":"444.868683ms","remote":"127.0.0.1:41006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-05-26T05:40:07.108223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:06.05462Z","time spent":"1.053506907s","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:40:12.250865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.631356ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-26T05:40:31.222669Z","caller":"traceutil/trace.go:171","msg":"trace[1985901427] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"186.002708ms","start":"2026-05-26T05:40:31.036634Z","end":"2026-05-26T05:40:31.222637Z","steps":["trace[1985901427] 'process raft request' (duration: 185.682047ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:33.101235Z","caller":"traceutil/trace.go:171","msg":"trace[4675635] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"228.95987ms","start":"2026-05-26T05:40:32.872251Z","end":"2026-05-26T05:40:33.101211Z","steps":["trace[4675635] 'process raft request' (duration: 228.928489ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:33.101431Z","caller":"traceutil/trace.go:171","msg":"trace[1189357759] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"230.937593ms","start":"2026-05-26T05:40:32.870458Z","end":"2026-05-26T05:40:33.101396Z","steps":["trace[1189357759] 'process raft request' (duration: 230.57394ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:33.101421Z","caller":"traceutil/trace.go:171","msg":"trace[1118973919] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"244.92416ms","start":"2026-05-26T05:40:32.856402Z","end":"2026-05-26T05:40:33.101326Z","steps":["trace[1118973919] 'process raft request' (duration: 244.588738ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:33.101543Z","caller":"traceutil/trace.go:171","msg":"trace[1507055917] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"247.757511ms","start":"2026-05-26T05:40:32.853768Z","end":"2026-05-26T05:40:33.101526Z","steps":["trace[1507055917] 'process raft request' (duration: 247.118087ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:33.101592Z","caller":"traceutil/trace.go:171","msg":"trace[482449365] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"230.348859ms","start":"2026-05-26T05:40:32.871213Z","end":"2026-05-26T05:40:33.101562Z","steps":["trace[482449365] 'process raft request' (duration: 229.925606ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:33.108328Z","caller":"traceutil/trace.go:171","msg":"trace[1864253847] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"220.789305ms","start":"2026-05-26T05:40:32.887523Z","end":"2026-05-26T05:40:33.108312Z","steps":["trace[1864253847] 'process raft request' (duration: 220.721835ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:33.108391Z","caller":"traceutil/trace.go:171","msg":"trace[1810596113] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"221.929482ms","start":"2026-05-26T05:40:32.886444Z","end":"2026-05-26T05:40:33.108373Z","steps":["trace[1810596113] 'process raft request' (duration: 221.774451ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:33.108507Z","caller":"traceutil/trace.go:171","msg":"trace[1594088691] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"234.799443ms","start":"2026-05-26T05:40:32.873692Z","end":"2026-05-26T05:40:33.108492Z","steps":["trace[1594088691] 'process raft request' (duration: 234.397721ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:33.108736Z","caller":"traceutil/trace.go:171","msg":"trace[80728878] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"221.078387ms","start":"2026-05-26T05:40:32.887651Z","end":"2026-05-26T05:40:33.108729Z","steps":["trace[80728878] 'process raft request' (duration: 220.636394ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:33.108918Z","caller":"traceutil/trace.go:171","msg":"trace[1768147032] transaction","detail":"{read_only:false; number_of_response:1; response_revision:540; }","duration":"217.029408ms","start":"2026-05-26T05:40:32.891875Z","end":"2026-05-26T05:40:33.108904Z","steps":["trace[1768147032] 'process raft request' (duration: 216.653006ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:33.109006Z","caller":"traceutil/trace.go:171","msg":"trace[1395695215] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"204.176658ms","start":"2026-05-26T05:40:32.904793Z","end":"2026-05-26T05:40:33.10897Z","steps":["trace[1395695215] 'process raft request' (duration: 203.915057ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:33.109151Z","caller":"traceutil/trace.go:171","msg":"trace[947725295] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"196.923718ms","start":"2026-05-26T05:40:32.91222Z","end":"2026-05-26T05:40:33.109144Z","steps":["trace[947725295] 'process raft request' (duration: 196.659666ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:33.109669Z","caller":"traceutil/trace.go:171","msg":"trace[1501747111] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"189.037413ms","start":"2026-05-26T05:40:32.92062Z","end":"2026-05-26T05:40:33.109657Z","steps":["trace[1501747111] 'process raft request' (duration: 188.443128ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:39.191755Z","caller":"traceutil/trace.go:171","msg":"trace[1279372248] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"215.197084ms","start":"2026-05-26T05:40:38.976521Z","end":"2026-05-26T05:40:39.191718Z","steps":["trace[1279372248] 'read index received' (duration: 214.061713ms)","trace[1279372248] 'applied index is now lower than readState.Index' (duration: 1.133941ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:40:39.191795Z","caller":"traceutil/trace.go:171","msg":"trace[350234506] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"279.936171ms","start":"2026-05-26T05:40:38.911801Z","end":"2026-05-26T05:40:39.191737Z","steps":["trace[350234506] 'process raft request' (duration: 279.639858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:39.191995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.428686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:40:39.19209Z","caller":"traceutil/trace.go:171","msg":"trace[924393585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"215.599457ms","start":"2026-05-26T05:40:38.976472Z","end":"2026-05-26T05:40:39.192071Z","steps":["trace[924393585] 'agreement among raft nodes before linearized reading' (duration: 215.392095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:39.694065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620626783905687647,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T05:40:39.732256Z","caller":"traceutil/trace.go:171","msg":"trace[1102748418] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"538.588814ms","start":"2026-05-26T05:40:39.193643Z","end":"2026-05-26T05:40:39.732232Z","steps":["trace[1102748418] 'read index received' (duration: 537.470223ms)","trace[1102748418] 'applied index is now lower than readState.Index' (duration: 1.098271ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:40:39.732431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.770666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T05:40:39.732507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.190327ms","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-26T05:40:39.732534Z","caller":"traceutil/trace.go:171","msg":"trace[1229872259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"538.978907ms","start":"2026-05-26T05:40:39.193533Z","end":"2026-05-26T05:40:39.732512Z","steps":["trace[1229872259] 'agreement among raft nodes before linearized reading' (duration: 538.809236ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:39.732553Z","caller":"traceutil/trace.go:171","msg":"trace[1948404207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"448.298388ms","start":"2026-05-26T05:40:39.284243Z","end":"2026-05-26T05:40:39.732542Z","steps":["trace[1948404207] 'agreement among raft nodes before linearized reading' (duration: 448.120456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:39.732587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:39.193505Z","time spent":"539.073719ms","remote":"127.0.0.1:40986","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-26T05:40:39.732594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:39.284226Z","time spent":"448.359368ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-26T05:40:39.732456Z","caller":"traceutil/trace.go:171","msg":"trace[1295474109] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"611.900077ms","start":"2026-05-26T05:40:39.120509Z","end":"2026-05-26T05:40:39.732409Z","steps":["trace[1295474109] 'process raft request' (duration: 610.993258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:39.732731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.814974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-26T05:40:39.732735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.080886ms","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-26T05:40:39.732785Z","caller":"traceutil/trace.go:171","msg":"trace[905010742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"446.919205ms","start":"2026-05-26T05:40:39.285852Z","end":"2026-05-26T05:40:39.732772Z","steps":["trace[905010742] 'agreement among raft nodes before linearized reading' (duration: 446.736673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:39.732822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:39.285832Z","time spent":"446.982145ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-26T05:40:39.73284Z","caller":"traceutil/trace.go:171","msg":"trace[140617513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"180.205617ms","start":"2026-05-26T05:40:39.552614Z","end":"2026-05-26T05:40:39.73282Z","steps":["trace[140617513] 'agreement among raft nodes before linearized reading' (duration: 179.897294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:39.732937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:39.120483Z","time spent":"612.22858ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:40:40.259745Z","caller":"traceutil/trace.go:171","msg":"trace[994713467] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"520.346568ms","start":"2026-05-26T05:40:39.739375Z","end":"2026-05-26T05:40:40.259722Z","steps":["trace[994713467] 'process raft request' (duration: 520.261696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:40.259904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:39.739361Z","time spent":"520.459289ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:40:40.260236Z","caller":"traceutil/trace.go:171","msg":"trace[438441845] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"522.192995ms","start":"2026-05-26T05:40:39.738029Z","end":"2026-05-26T05:40:40.260222Z","steps":["trace[438441845] 'process raft request' (duration: 499.484669ms)","trace[438441845] 'compare' (duration: 21.770836ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:40:40.260351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:39.738004Z","time spent":"522.311257ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:40:40.304544Z","caller":"traceutil/trace.go:171","msg":"trace[906975230] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:669; }","duration":"327.156931ms","start":"2026-05-26T05:40:39.977363Z","end":"2026-05-26T05:40:40.30452Z","steps":["trace[906975230] 'read index received' (duration: 260.165055ms)","trace[906975230] 'applied index is now lower than readState.Index' (duration: 66.990446ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:40:40.304678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.299632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:40:40.304655Z","caller":"traceutil/trace.go:171","msg":"trace[68123931] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"510.64235ms","start":"2026-05-26T05:40:39.793967Z","end":"2026-05-26T05:40:40.304609Z","steps":["trace[68123931] 'process raft request' (duration: 510.378086ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:40.304711Z","caller":"traceutil/trace.go:171","msg":"trace[1182484125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"327.402463ms","start":"2026-05-26T05:40:39.977299Z","end":"2026-05-26T05:40:40.304702Z","steps":["trace[1182484125] 'agreement among raft nodes before linearized reading' (duration: 327.325592ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:40.304724Z","caller":"traceutil/trace.go:171","msg":"trace[1063380031] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"312.125617ms","start":"2026-05-26T05:40:39.992574Z","end":"2026-05-26T05:40:40.3047Z","steps":["trace[1063380031] 'process raft request' (duration: 311.884254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:40.304745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:39.97728Z","time spent":"327.457404ms","remote":"127.0.0.1:40976","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-26T05:40:40.3048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:39.793942Z","time spent":"510.784321ms","remote":"127.0.0.1:41438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T05:40:40.304838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:39.992543Z","time spent":"312.226087ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:40:42.147211Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.438747883s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T05:40:42.216174Z","caller":"traceutil/trace.go:171","msg":"trace[1209930961] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.51014727s","start":"2026-05-26T05:40:40.705996Z","end":"2026-05-26T05:40:42.216143Z","steps":["trace[1209930961] 'process raft request' (duration: 1.442491572s)","trace[1209930961] 'compare' (duration: 66.618576ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:40:42.21609Z","caller":"traceutil/trace.go:171","msg":"trace[908654793] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"428.812539ms","start":"2026-05-26T05:40:41.787206Z","end":"2026-05-26T05:40:42.216018Z","steps":["trace[908654793] 'read index received' (duration: 360.267012ms)","trace[908654793] 'applied index is now lower than readState.Index' (duration: 68.543927ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:40:42.216804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.585197ms","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-26T05:40:42.216898Z","caller":"traceutil/trace.go:171","msg":"trace[1553691472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"429.690738ms","start":"2026-05-26T05:40:41.787191Z","end":"2026-05-26T05:40:42.216882Z","steps":["trace[1553691472] 'agreement among raft nodes before linearized reading' (duration: 429.456616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:42.216932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:41.787128Z","time spent":"429.795939ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T05:40:42.216829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:40.705966Z","time spent":"1.510763775s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:40:42.419195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.107209ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-26T05:40:42.419433Z","caller":"traceutil/trace.go:171","msg":"trace[2028522550] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"197.811039ms","start":"2026-05-26T05:40:42.221599Z","end":"2026-05-26T05:40:42.41941Z","steps":["trace[2028522550] 'process raft request' (duration: 197.703908ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:42.419951Z","caller":"traceutil/trace.go:171","msg":"trace[1702050948] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"203.171396ms","start":"2026-05-26T05:40:42.216749Z","end":"2026-05-26T05:40:42.41992Z","steps":["trace[1702050948] 'read index received' (duration: 43.325229ms)","trace[1702050948] 'applied index is now lower than readState.Index' (duration: 159.842657ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:40:42.619142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.349339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:40:42.619264Z","caller":"traceutil/trace.go:171","msg":"trace[1317990762] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"341.991699ms","start":"2026-05-26T05:40:42.277227Z","end":"2026-05-26T05:40:42.619219Z","steps":["trace[1317990762] 'process raft request' (duration: 303.914875ms)","trace[1317990762] 'compare' (duration: 37.801121ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:40:42.619431Z","caller":"traceutil/trace.go:171","msg":"trace[450385082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"641.750813ms","start":"2026-05-26T05:40:41.977658Z","end":"2026-05-26T05:40:42.619409Z","steps":["trace[450385082] 'agreement among raft nodes before linearized reading' (duration: 442.397707ms)","trace[450385082] 'range keys from in-memory index tree' (duration: 198.983442ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:40:42.619506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:41.977623Z","time spent":"641.843724ms","remote":"127.0.0.1:40976","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-26T05:40:42.619749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.352262ms","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-26T05:40:42.619259Z","caller":"traceutil/trace.go:171","msg":"trace[12725569] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"199.187094ms","start":"2026-05-26T05:40:42.420029Z","end":"2026-05-26T05:40:42.619217Z","steps":["trace[12725569] 'read index received' (duration: 161.10553ms)","trace[12725569] 'applied index is now lower than readState.Index' (duration: 38.079544ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:40:42.619826Z","caller":"traceutil/trace.go:171","msg":"trace[1839958633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"356.496244ms","start":"2026-05-26T05:40:42.26331Z","end":"2026-05-26T05:40:42.619806Z","steps":["trace[1839958633] 'agreement among raft nodes before linearized reading' (duration: 356.039739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:42.619883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:42.263286Z","time spent":"356.584524ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T05:40:42.619971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:42.277208Z","time spent":"342.239023ms","remote":"127.0.0.1:41156","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-26T05:40:42.619915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.391212ms","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-26T05:40:42.620068Z","caller":"traceutil/trace.go:171","msg":"trace[137475142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"356.593384ms","start":"2026-05-26T05:40:42.263461Z","end":"2026-05-26T05:40:42.620054Z","steps":["trace[137475142] 'agreement among raft nodes before linearized reading' (duration: 355.889857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:42.620584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:42.263439Z","time spent":"357.13162ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T05:40:42.620805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.747307ms","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-26T05:40:42.620872Z","caller":"traceutil/trace.go:171","msg":"trace[1211380281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"277.85841ms","start":"2026-05-26T05:40:42.342997Z","end":"2026-05-26T05:40:42.620855Z","steps":["trace[1211380281] 'agreement among raft nodes before linearized reading' (duration: 276.240283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:44.452034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.122201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:40:44.452128Z","caller":"traceutil/trace.go:171","msg":"trace[1804752199] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:681; }","duration":"102.284813ms","start":"2026-05-26T05:40:44.349823Z","end":"2026-05-26T05:40:44.452107Z","steps":["trace[1804752199] 'range keys from in-memory index tree' (duration: 101.99906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:44.452361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.529759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:40:44.452464Z","caller":"traceutil/trace.go:171","msg":"trace[1893437145] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:681; }","duration":"302.643091ms","start":"2026-05-26T05:40:44.149794Z","end":"2026-05-26T05:40:44.452437Z","steps":["trace[1893437145] 'range keys from in-memory index tree' (duration: 302.441788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:44.45252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:44.149774Z","time spent":"302.726291ms","remote":"127.0.0.1:41010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-05-26T05:40:54.951025Z","caller":"traceutil/trace.go:171","msg":"trace[894012081] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"259.622314ms","start":"2026-05-26T05:40:54.691379Z","end":"2026-05-26T05:40:54.951001Z","steps":["trace[894012081] 'process raft request' (duration: 259.478502ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:54.951112Z","caller":"traceutil/trace.go:171","msg":"trace[647266039] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:825; }","duration":"259.152327ms","start":"2026-05-26T05:40:54.691927Z","end":"2026-05-26T05:40:54.951079Z","steps":["trace[647266039] 'read index received' (duration: 259.138967ms)","trace[647266039] 'applied index is now lower than readState.Index' (duration: 10.85µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:40:54.951517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.571203ms","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-26T05:40:54.951564Z","caller":"traceutil/trace.go:171","msg":"trace[1726028016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:791; }","duration":"259.639414ms","start":"2026-05-26T05:40:54.691914Z","end":"2026-05-26T05:40:54.951553Z","steps":["trace[1726028016] 'agreement among raft nodes before linearized reading' (duration: 259.271799ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:54.95832Z","caller":"traceutil/trace.go:171","msg":"trace[2092384634] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"198.594133ms","start":"2026-05-26T05:40:54.759707Z","end":"2026-05-26T05:40:54.958301Z","steps":["trace[2092384634] 'process raft request' (duration: 198.544403ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:54.958397Z","caller":"traceutil/trace.go:171","msg":"trace[1688436314] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"265.503067ms","start":"2026-05-26T05:40:54.692866Z","end":"2026-05-26T05:40:54.958369Z","steps":["trace[1688436314] 'process raft request' (duration: 265.281314ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:40:58.479498Z","caller":"traceutil/trace.go:171","msg":"trace[884493291] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"438.17191ms","start":"2026-05-26T05:40:58.0413Z","end":"2026-05-26T05:40:58.479472Z","steps":["trace[884493291] 'read index received' (duration: 437.966816ms)","trace[884493291] 'applied index is now lower than readState.Index' (duration: 204.164µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:40:58.479743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.415474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-26T05:40:58.479786Z","caller":"traceutil/trace.go:171","msg":"trace[725397353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"438.501315ms","start":"2026-05-26T05:40:58.041272Z","end":"2026-05-26T05:40:58.479774Z","steps":["trace[725397353] 'agreement among raft nodes before linearized reading' (duration: 438.325202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:58.479823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:58.041256Z","time spent":"438.557306ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-26T05:40:58.480193Z","caller":"traceutil/trace.go:171","msg":"trace[1030090884] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"456.652054ms","start":"2026-05-26T05:40:58.02351Z","end":"2026-05-26T05:40:58.480162Z","steps":["trace[1030090884] 'process raft request' (duration: 455.82311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:40:58.480529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:40:58.023484Z","time spent":"456.790475ms","remote":"127.0.0.1:41156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:41:09.439769Z","caller":"traceutil/trace.go:171","msg":"trace[983084722] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"119.609619ms","start":"2026-05-26T05:41:09.32014Z","end":"2026-05-26T05:41:09.43975Z","steps":["trace[983084722] 'process raft request' (duration: 51.312352ms)","trace[983084722] 'compare' (duration: 68.205064ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:41:49.03073Z","caller":"traceutil/trace.go:171","msg":"trace[1969238479] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1156; }","duration":"194.36491ms","start":"2026-05-26T05:41:48.836342Z","end":"2026-05-26T05:41:49.030707Z","steps":["trace[1969238479] 'read index received' (duration: 194.211006ms)","trace[1969238479] 'applied index is now lower than readState.Index' (duration: 152.654µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:41:49.030795Z","caller":"traceutil/trace.go:171","msg":"trace[236038660] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"230.848397ms","start":"2026-05-26T05:41:48.799901Z","end":"2026-05-26T05:41:49.03075Z","steps":["trace[236038660] 'process raft request' (duration: 230.663682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:41:49.031025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.656556ms","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-26T05:41:49.031116Z","caller":"traceutil/trace.go:171","msg":"trace[1530494007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1109; }","duration":"194.79138ms","start":"2026-05-26T05:41:48.836303Z","end":"2026-05-26T05:41:49.031094Z","steps":["trace[1530494007] 'agreement among raft nodes before linearized reading' (duration: 194.520734ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:41:55.424769Z","caller":"traceutil/trace.go:171","msg":"trace[1504408199] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1180; }","duration":"103.595351ms","start":"2026-05-26T05:41:55.321152Z","end":"2026-05-26T05:41:55.424747Z","steps":["trace[1504408199] 'read index received' (duration: 103.391986ms)","trace[1504408199] 'applied index is now lower than readState.Index' (duration: 202.465µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:41:55.424964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.788105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T05:41:55.425016Z","caller":"traceutil/trace.go:171","msg":"trace[1647776762] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1132; }","duration":"103.893248ms","start":"2026-05-26T05:41:55.321113Z","end":"2026-05-26T05:41:55.425006Z","steps":["trace[1647776762] 'agreement among raft nodes before linearized reading' (duration: 103.758305ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:41:55.425011Z","caller":"traceutil/trace.go:171","msg":"trace[1434247334] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"105.611992ms","start":"2026-05-26T05:41:55.319375Z","end":"2026-05-26T05:41:55.424987Z","steps":["trace[1434247334] 'process raft request' (duration: 105.230482ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:42:01.282629Z","caller":"traceutil/trace.go:171","msg":"trace[149274586] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"131.49493ms","start":"2026-05-26T05:42:01.151098Z","end":"2026-05-26T05:42:01.282592Z","steps":["trace[149274586] 'process raft request' (duration: 124.308195ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:42:13.434836Z","caller":"traceutil/trace.go:171","msg":"trace[1426712922] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"101.741583ms","start":"2026-05-26T05:42:13.333063Z","end":"2026-05-26T05:42:13.434804Z","steps":["trace[1426712922] 'process raft request' (duration: 101.504597ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:42:15.36902Z","caller":"traceutil/trace.go:171","msg":"trace[233211279] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"201.201325ms","start":"2026-05-26T05:42:15.167791Z","end":"2026-05-26T05:42:15.368993Z","steps":["trace[233211279] 'process raft request' (duration: 201.033431ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:42:15.55543Z","caller":"traceutil/trace.go:171","msg":"trace[872406345] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"115.147879ms","start":"2026-05-26T05:42:15.440261Z","end":"2026-05-26T05:42:15.555409Z","steps":["trace[872406345] 'process raft request' (duration: 114.865311ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:42:15.763074Z","caller":"traceutil/trace.go:171","msg":"trace[1046540073] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"156.952274ms","start":"2026-05-26T05:42:15.606101Z","end":"2026-05-26T05:42:15.763053Z","steps":["trace[1046540073] 'process raft request' (duration: 130.023399ms)","trace[1046540073] 'compare' (duration: 26.845063ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:42:45.602924Z","caller":"traceutil/trace.go:171","msg":"trace[1127223171] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"209.424983ms","start":"2026-05-26T05:42:45.393468Z","end":"2026-05-26T05:42:45.602893Z","steps":["trace[1127223171] 'process raft request' (duration: 209.267798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:42:46.203374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.323743ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:42:46.203555Z","caller":"traceutil/trace.go:171","msg":"trace[448952038] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1329; }","duration":"246.528199ms","start":"2026-05-26T05:42:45.957Z","end":"2026-05-26T05:42:46.203529Z","steps":["trace[448952038] 'range keys from in-memory index tree' (duration: 246.292432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:42:46.203875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.160665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T05:42:46.204134Z","caller":"traceutil/trace.go:171","msg":"trace[451961066] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"324.685338ms","start":"2026-05-26T05:42:45.879427Z","end":"2026-05-26T05:42:46.204112Z","steps":["trace[451961066] 'process raft request' (duration: 324.624996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:42:46.204195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:42:45.87941Z","time spent":"324.760539ms","remote":"127.0.0.1:41180","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-26T05:42:46.204214Z","caller":"traceutil/trace.go:171","msg":"trace[103804824] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1390; }","duration":"500.349091ms","start":"2026-05-26T05:42:45.703844Z","end":"2026-05-26T05:42:46.204194Z","steps":["trace[103804824] 'read index received' (duration: 185.856226ms)","trace[103804824] 'applied index is now lower than readState.Index' (duration: 314.490255ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:42:46.204383Z","caller":"traceutil/trace.go:171","msg":"trace[123360832] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"595.95237ms","start":"2026-05-26T05:42:45.608393Z","end":"2026-05-26T05:42:46.204345Z","steps":["trace[123360832] 'process raft request' (duration: 281.181968ms)","trace[123360832] 'compare' (duration: 313.99171ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:42:46.204555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.698031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-26T05:42:46.20459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:42:45.608377Z","time spent":"596.096966ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:42:46.204626Z","caller":"traceutil/trace.go:171","msg":"trace[2053642856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1331; }","duration":"500.797514ms","start":"2026-05-26T05:42:45.703811Z","end":"2026-05-26T05:42:46.204608Z","steps":["trace[2053642856] 'agreement among raft nodes before linearized reading' (duration: 500.524836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:42:46.204564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.413362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-26T05:42:46.204556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.048163ms","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-26T05:42:46.204745Z","caller":"traceutil/trace.go:171","msg":"trace[1603503932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1331; }","duration":"451.288659ms","start":"2026-05-26T05:42:45.753443Z","end":"2026-05-26T05:42:46.204732Z","steps":["trace[1603503932] 'agreement among raft nodes before linearized reading' (duration: 450.916259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:42:46.204789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:42:45.75342Z","time spent":"451.35877ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T05:42:46.20469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:42:45.7038Z","time spent":"500.875445ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T05:42:46.204849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.642534ms","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-26T05:42:46.204886Z","caller":"traceutil/trace.go:171","msg":"trace[1859184565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1331; }","duration":"422.713587ms","start":"2026-05-26T05:42:45.782161Z","end":"2026-05-26T05:42:46.204874Z","steps":["trace[1859184565] 'agreement among raft nodes before linearized reading' (duration: 422.250923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:42:46.204924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:42:45.782141Z","time spent":"422.775939ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-26T05:42:46.205281Z","caller":"traceutil/trace.go:171","msg":"trace[13214702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1331; }","duration":"428.150494ms","start":"2026-05-26T05:42:45.777109Z","end":"2026-05-26T05:42:46.205259Z","steps":["trace[13214702] 'agreement among raft nodes before linearized reading' (duration: 427.313859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:42:46.205363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:42:45.777097Z","time spent":"428.251376ms","remote":"127.0.0.1:41180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-26T05:42:46.408641Z","caller":"traceutil/trace.go:171","msg":"trace[1182240592] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"197.950072ms","start":"2026-05-26T05:42:46.210662Z","end":"2026-05-26T05:42:46.408612Z","steps":["trace[1182240592] 'process raft request' (duration: 197.757047ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:42:46.408888Z","caller":"traceutil/trace.go:171","msg":"trace[1594190722] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"198.137187ms","start":"2026-05-26T05:42:46.210731Z","end":"2026-05-26T05:42:46.408869Z","steps":["trace[1594190722] 'process raft request' (duration: 197.840729ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:42:46.409099Z","caller":"traceutil/trace.go:171","msg":"trace[1447458780] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1394; }","duration":"105.423317ms","start":"2026-05-26T05:42:46.303665Z","end":"2026-05-26T05:42:46.409089Z","steps":["trace[1447458780] 'read index received' (duration: 104.899953ms)","trace[1447458780] 'applied index is now lower than readState.Index' (duration: 522.424µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:42:46.409118Z","caller":"traceutil/trace.go:171","msg":"trace[293648004] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"198.138748ms","start":"2026-05-26T05:42:46.210964Z","end":"2026-05-26T05:42:46.409103Z","steps":["trace[293648004] 'process raft request' (duration: 197.825509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:42:46.409201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.53764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T05:42:46.409238Z","caller":"traceutil/trace.go:171","msg":"trace[1199858422] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1335; }","duration":"105.620232ms","start":"2026-05-26T05:42:46.303609Z","end":"2026-05-26T05:42:46.409229Z","steps":["trace[1199858422] 'agreement among raft nodes before linearized reading' (duration: 105.52469ms)"],"step_count":1}