{"level":"warn","ts":"2026-06-13T01:03:08.008426Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T01:03:08.008515Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-06-13T01:03:08.008602Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T01:03:08.008617Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-06-13T01:03:08.008649Z","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-06-13T01:03:08.009286Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-06-13T01:03:08.009376Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-06-13T01:03:08.015963Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.327163ms"} {"level":"info","ts":"2026-06-13T01:03:08.027397Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-06-13T01:03:08.02755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-06-13T01:03:08.027607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-06-13T01:03:08.027637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-13T01:03:08.027669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-06-13T01:03:08.027746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-06-13T01:03:08.032786Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-13T01:03:08.034135Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-13T01:03:08.035439Z","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-06-13T01:03:08.037923Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-13T01:03:08.038097Z","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-06-13T01:03:08.03817Z","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-06-13T01:03:08.03859Z","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-06-13T01:03:08.038359Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-13T01:03:08.03996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-06-13T01:03:08.040135Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-06-13T01:03:08.042324Z","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-06-13T01:03:08.042538Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-06-13T01:03:08.042684Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-06-13T01:03:08.042564Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-13T01:03:08.042614Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-13T01:03:08.22836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-06-13T01:03:08.228404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-06-13T01:03:08.228427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-06-13T01:03:08.228441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-06-13T01:03:08.228449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-06-13T01:03:08.228462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-06-13T01:03:08.228471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-06-13T01:03:08.285109Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T01:03:08.285893Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-06-13T01:03:08.286055Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T01:03:08.286124Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T01:03:08.289154Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-06-13T01:03:08.291444Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-13T01:03:08.292285Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-13T01:03:08.292314Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-13T01:03:08.294514Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T01:03:08.294646Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T01:03:08.294717Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-06-13T01:03:12.512593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.913543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-06-13T01:03:12.512707Z","caller":"traceutil/trace.go:171","msg":"trace[876575024] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:271; }","duration":"106.096705ms","start":"2026-06-13T01:03:12.406589Z","end":"2026-06-13T01:03:12.512686Z","steps":["trace[876575024] 'range keys from in-memory index tree' (duration: 105.735463ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:14.453424Z","caller":"traceutil/trace.go:171","msg":"trace[899378188] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"130.012361ms","start":"2026-06-13T01:03:14.323381Z","end":"2026-06-13T01:03:14.453394Z","steps":["trace[899378188] 'process raft request' (duration: 129.79861ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:14.453508Z","caller":"traceutil/trace.go:171","msg":"trace[576345868] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"129.966761ms","start":"2026-06-13T01:03:14.323513Z","end":"2026-06-13T01:03:14.45348Z","steps":["trace[576345868] 'process raft request' (duration: 129.768139ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:14.453651Z","caller":"traceutil/trace.go:171","msg":"trace[636683754] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"111.676466ms","start":"2026-06-13T01:03:14.34195Z","end":"2026-06-13T01:03:14.453626Z","steps":["trace[636683754] 'process raft request' (duration: 111.360334ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:14.626076Z","caller":"traceutil/trace.go:171","msg":"trace[370513865] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:335; }","duration":"120.377374ms","start":"2026-06-13T01:03:14.505669Z","end":"2026-06-13T01:03:14.626046Z","steps":["trace[370513865] 'read index received' (duration: 52.964715ms)","trace[370513865] 'applied index is now lower than readState.Index' (duration: 67.411699ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:03:14.626214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.516476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-06-13T01:03:14.626258Z","caller":"traceutil/trace.go:171","msg":"trace[170255348] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:324; }","duration":"120.603497ms","start":"2026-06-13T01:03:14.505642Z","end":"2026-06-13T01:03:14.626245Z","steps":["trace[170255348] 'agreement among raft nodes before linearized reading' (duration: 120.505526ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:14.62624Z","caller":"traceutil/trace.go:171","msg":"trace[1523318510] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"129.293514ms","start":"2026-06-13T01:03:14.496899Z","end":"2026-06-13T01:03:14.626192Z","steps":["trace[1523318510] 'process raft request' (duration: 61.898856ms)","trace[1523318510] 'compare' (duration: 67.079336ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:03:17.184186Z","caller":"traceutil/trace.go:171","msg":"trace[622100841] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:366; }","duration":"328.081799ms","start":"2026-06-13T01:03:16.856022Z","end":"2026-06-13T01:03:17.184104Z","steps":["trace[622100841] 'read index received' (duration: 318.112062ms)","trace[622100841] 'applied index is now lower than readState.Index' (duration: 9.968597ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:03:17.184185Z","caller":"traceutil/trace.go:171","msg":"trace[1188155651] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"372.479011ms","start":"2026-06-13T01:03:16.811682Z","end":"2026-06-13T01:03:17.184161Z","steps":["trace[1188155651] 'process raft request' (duration: 362.925218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:17.184519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.6911ms","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":"warn","ts":"2026-06-13T01:03:17.184524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.922395ms","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-06-13T01:03:17.184563Z","caller":"traceutil/trace.go:171","msg":"trace[1909532567] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:355; }","duration":"277.781431ms","start":"2026-06-13T01:03:16.906768Z","end":"2026-06-13T01:03:17.18455Z","steps":["trace[1909532567] 'agreement among raft nodes before linearized reading' (duration: 277.67453ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:17.184639Z","caller":"traceutil/trace.go:171","msg":"trace[54067975] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:355; }","duration":"178.976245ms","start":"2026-06-13T01:03:17.005585Z","end":"2026-06-13T01:03:17.184562Z","steps":["trace[54067975] 'agreement among raft nodes before linearized reading' (duration: 178.883704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:17.184639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.321346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-06-13T01:03:17.184721Z","caller":"traceutil/trace.go:171","msg":"trace[1672011456] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:355; }","duration":"224.442057ms","start":"2026-06-13T01:03:16.960255Z","end":"2026-06-13T01:03:17.184697Z","steps":["trace[1672011456] 'agreement among raft nodes before linearized reading' (duration: 224.258616ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:17.184814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:16.811666Z","time spent":"372.748714ms","remote":"127.0.0.1:45960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T01:03:17.18511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.088787ms","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-06-13T01:03:17.185193Z","caller":"traceutil/trace.go:171","msg":"trace[482561051] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:355; }","duration":"329.203497ms","start":"2026-06-13T01:03:16.855976Z","end":"2026-06-13T01:03:17.18518Z","steps":["trace[482561051] 'agreement among raft nodes before linearized reading' (duration: 328.22881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:17.185232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:16.855954Z","time spent":"329.267938ms","remote":"127.0.0.1:45660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-06-13T01:03:17.621873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.717238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-13T01:03:17.622135Z","caller":"traceutil/trace.go:171","msg":"trace[515264679] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:372; }","duration":"348.062553ms","start":"2026-06-13T01:03:17.274053Z","end":"2026-06-13T01:03:17.622115Z","steps":["trace[515264679] 'read index received' (duration: 97.826001ms)","trace[515264679] 'applied index is now lower than readState.Index' (duration: 250.235462ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:03:17.62229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.227375ms","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-06-13T01:03:17.622321Z","caller":"traceutil/trace.go:171","msg":"trace[1560078531] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:363; }","duration":"348.296936ms","start":"2026-06-13T01:03:17.274013Z","end":"2026-06-13T01:03:17.62231Z","steps":["trace[1560078531] 'agreement among raft nodes before linearized reading' (duration: 348.154975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:17.622357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:17.273997Z","time spent":"348.351446ms","remote":"127.0.0.1:45660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-06-13T01:03:17.622637Z","caller":"traceutil/trace.go:171","msg":"trace[1975866937] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"390.242168ms","start":"2026-06-13T01:03:17.232384Z","end":"2026-06-13T01:03:17.622626Z","steps":["trace[1975866937] 'process raft request' (duration: 389.677164ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:17.62288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:17.232374Z","time spent":"390.288798ms","remote":"127.0.0.1:45946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:03:17.622183Z","caller":"traceutil/trace.go:171","msg":"trace[1377695497] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"391.366507ms","start":"2026-06-13T01:03:17.230801Z","end":"2026-06-13T01:03:17.622168Z","steps":["trace[1377695497] 'process raft request' (duration: 391.183555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:17.623312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:17.230791Z","time spent":"392.354784ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:03:17.624723Z","caller":"traceutil/trace.go:171","msg":"trace[126622020] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"392.274694ms","start":"2026-06-13T01:03:17.229804Z","end":"2026-06-13T01:03:17.622079Z","steps":["trace[126622020] 'process raft request' (duration: 142.055982ms)","trace[126622020] 'compare' (duration: 249.542797ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:03:17.624824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:17.229783Z","time spent":"394.997965ms","remote":"127.0.0.1:45552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T01:03:17.815046Z","caller":"traceutil/trace.go:171","msg":"trace[1022128875] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"540.921035ms","start":"2026-06-13T01:03:17.274098Z","end":"2026-06-13T01:03:17.815019Z","steps":["trace[1022128875] 'process raft request' (duration: 532.40102ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:17.815336Z","caller":"traceutil/trace.go:171","msg":"trace[1518538119] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"540.382921ms","start":"2026-06-13T01:03:17.274945Z","end":"2026-06-13T01:03:17.815328Z","steps":["trace[1518538119] 'process raft request' (duration: 540.034939ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:17.815093Z","caller":"traceutil/trace.go:171","msg":"trace[1843510255] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"185.672377ms","start":"2026-06-13T01:03:17.629405Z","end":"2026-06-13T01:03:17.815077Z","steps":["trace[1843510255] 'process raft request' (duration: 185.645866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:17.815605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:17.274085Z","time spent":"541.292658ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T01:03:17.815137Z","caller":"traceutil/trace.go:171","msg":"trace[1636014501] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"540.603503ms","start":"2026-06-13T01:03:17.274527Z","end":"2026-06-13T01:03:17.815131Z","steps":["trace[1636014501] 'process raft request' (duration: 540.421202ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:17.815853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:17.27451Z","time spent":"541.278468ms","remote":"127.0.0.1:45552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T01:03:17.815252Z","caller":"traceutil/trace.go:171","msg":"trace[2073793328] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:375; }","duration":"188.146885ms","start":"2026-06-13T01:03:17.627085Z","end":"2026-06-13T01:03:17.815232Z","steps":["trace[2073793328] 'read index received' (duration: 179.426368ms)","trace[2073793328] 'applied index is now lower than readState.Index' (duration: 8.718567ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:03:17.815282Z","caller":"traceutil/trace.go:171","msg":"trace[2071045473] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"538.426396ms","start":"2026-06-13T01:03:17.276791Z","end":"2026-06-13T01:03:17.815217Z","steps":["trace[2071045473] 'process raft request' (duration: 538.221064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:17.816187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:17.276782Z","time spent":"539.329253ms","remote":"127.0.0.1:45924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:03:17.8164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.148535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-hzw7c\" ","response":"range_response_count:1 size:3439"} {"level":"warn","ts":"2026-06-13T01:03:17.815341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.242705ms","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-06-13T01:03:17.816502Z","caller":"traceutil/trace.go:171","msg":"trace[1969596450] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:368; }","duration":"189.441145ms","start":"2026-06-13T01:03:17.627037Z","end":"2026-06-13T01:03:17.816478Z","steps":["trace[1969596450] 'agreement among raft nodes before linearized reading' (duration: 188.239306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:17.815495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:17.274925Z","time spent":"540.472272ms","remote":"127.0.0.1:45660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T01:03:17.816447Z","caller":"traceutil/trace.go:171","msg":"trace[1458258843] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-hzw7c; range_end:; response_count:1; response_revision:368; }","duration":"188.227156ms","start":"2026-06-13T01:03:17.628206Z","end":"2026-06-13T01:03:17.816433Z","steps":["trace[1458258843] 'agreement among raft nodes before linearized reading' (duration: 188.147815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:17.816857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.902303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-13T01:03:17.817472Z","caller":"traceutil/trace.go:171","msg":"trace[1139160607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:368; }","duration":"106.574348ms","start":"2026-06-13T01:03:17.710884Z","end":"2026-06-13T01:03:17.817458Z","steps":["trace[1139160607] 'agreement among raft nodes before linearized reading' (duration: 105.780021ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:18.026201Z","caller":"traceutil/trace.go:171","msg":"trace[159440853] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"112.395992ms","start":"2026-06-13T01:03:17.913777Z","end":"2026-06-13T01:03:18.026173Z","steps":["trace[159440853] 'process raft request' (duration: 83.817474ms)","trace[159440853] 'compare' (duration: 28.412707ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:03:19.42207Z","caller":"traceutil/trace.go:171","msg":"trace[1196985873] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"322.902058ms","start":"2026-06-13T01:03:19.099144Z","end":"2026-06-13T01:03:19.422046Z","steps":["trace[1196985873] 'read index received' (duration: 322.713077ms)","trace[1196985873] 'applied index is now lower than readState.Index' (duration: 187.811µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:03:19.422119Z","caller":"traceutil/trace.go:171","msg":"trace[97852663] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"533.673362ms","start":"2026-06-13T01:03:18.888415Z","end":"2026-06-13T01:03:19.422088Z","steps":["trace[97852663] 'process raft request' (duration: 533.4552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:19.422302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.16308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T01:03:19.422309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:18.888391Z","time spent":"533.824422ms","remote":"127.0.0.1:45552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:03:19.422342Z","caller":"traceutil/trace.go:171","msg":"trace[957170243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:393; }","duration":"323.259211ms","start":"2026-06-13T01:03:19.099072Z","end":"2026-06-13T01:03:19.422331Z","steps":["trace[957170243] 'agreement among raft nodes before linearized reading' (duration: 323.160421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:19.422376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:19.099053Z","time spent":"323.315932ms","remote":"127.0.0.1:45496","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-06-13T01:03:19.422377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.318166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-06-13T01:03:19.423416Z","caller":"traceutil/trace.go:171","msg":"trace[858742101] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:393; }","duration":"144.383125ms","start":"2026-06-13T01:03:19.279006Z","end":"2026-06-13T01:03:19.423389Z","steps":["trace[858742101] 'agreement among raft nodes before linearized reading' (duration: 143.272206ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:19.712111Z","caller":"traceutil/trace.go:171","msg":"trace[1034567577] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"279.56365ms","start":"2026-06-13T01:03:19.432522Z","end":"2026-06-13T01:03:19.712086Z","steps":["trace[1034567577] 'process raft request' (duration: 277.492114ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:19.878725Z","caller":"traceutil/trace.go:171","msg":"trace[1078533686] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"154.004628ms","start":"2026-06-13T01:03:19.72469Z","end":"2026-06-13T01:03:19.878695Z","steps":["trace[1078533686] 'process raft request' (duration: 90.792689ms)","trace[1078533686] 'compare' (duration: 63.043848ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:03:23.485163Z","caller":"traceutil/trace.go:171","msg":"trace[2039778513] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"137.202886ms","start":"2026-06-13T01:03:23.347929Z","end":"2026-06-13T01:03:23.485132Z","steps":["trace[2039778513] 'process raft request' (duration: 137.001044ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:24.144096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.273671ms","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-06-13T01:03:24.14423Z","caller":"traceutil/trace.go:171","msg":"trace[638899269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"137.478072ms","start":"2026-06-13T01:03:24.006722Z","end":"2026-06-13T01:03:24.1442Z","steps":["trace[638899269] 'range keys from in-memory index tree' (duration: 137.132369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:24.14443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.462655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-13T01:03:24.144547Z","caller":"traceutil/trace.go:171","msg":"trace[685678630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"178.622516ms","start":"2026-06-13T01:03:23.965891Z","end":"2026-06-13T01:03:24.144514Z","steps":["trace[685678630] 'range keys from in-memory index tree' (duration: 178.348644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:24.144774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.783859ms","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-06-13T01:03:24.144849Z","caller":"traceutil/trace.go:171","msg":"trace[1453987577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"101.919969ms","start":"2026-06-13T01:03:24.042908Z","end":"2026-06-13T01:03:24.144828Z","steps":["trace[1453987577] 'range keys from in-memory index tree' (duration: 101.620297ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:24.31523Z","caller":"traceutil/trace.go:171","msg":"trace[891631191] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"165.79273ms","start":"2026-06-13T01:03:24.149404Z","end":"2026-06-13T01:03:24.315197Z","steps":["trace[891631191] 'process raft request' (duration: 165.575049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:24.710845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.448472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T01:03:24.711429Z","caller":"traceutil/trace.go:171","msg":"trace[507510811] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"561.303111ms","start":"2026-06-13T01:03:24.150083Z","end":"2026-06-13T01:03:24.711386Z","steps":["trace[507510811] 'process raft request' (duration: 561.088169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:24.711637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:24.15007Z","time spent":"561.458932ms","remote":"127.0.0.1:45722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:03:24.71187Z","caller":"traceutil/trace.go:171","msg":"trace[1380838908] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"561.322821ms","start":"2026-06-13T01:03:24.150507Z","end":"2026-06-13T01:03:24.711829Z","steps":["trace[1380838908] 'process raft request' (duration: 560.726216ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:24.711868Z","caller":"traceutil/trace.go:171","msg":"trace[1875571582] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"561.28028ms","start":"2026-06-13T01:03:24.150038Z","end":"2026-06-13T01:03:24.711319Z","steps":["trace[1875571582] 'process raft request' (duration: 196.204423ms)","trace[1875571582] 'compare' (duration: 363.182733ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:03:24.711951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.226938ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:03:24.712053Z","caller":"traceutil/trace.go:171","msg":"trace[1817851739] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:417; }","duration":"187.332839ms","start":"2026-06-13T01:03:24.52471Z","end":"2026-06-13T01:03:24.712043Z","steps":["trace[1817851739] 'agreement among raft nodes before linearized reading' (duration: 187.207958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:24.712106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:24.150027Z","time spent":"562.015106ms","remote":"127.0.0.1:45722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:03:24.712224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:24.150482Z","time spent":"561.540183ms","remote":"127.0.0.1:45722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:03:24.711854Z","caller":"traceutil/trace.go:171","msg":"trace[124655830] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:428; }","duration":"187.108997ms","start":"2026-06-13T01:03:24.52472Z","end":"2026-06-13T01:03:24.711829Z","steps":["trace[124655830] 'read index received' (duration: 49.25µs)","trace[124655830] 'applied index is now lower than readState.Index' (duration: 187.057487ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:03:26.316264Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.594247616s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-13T01:03:26.536423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.98017ms","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-06-13T01:03:26.536562Z","caller":"traceutil/trace.go:171","msg":"trace[1183541719] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:417; }","duration":"928.142591ms","start":"2026-06-13T01:03:25.6084Z","end":"2026-06-13T01:03:26.536543Z","steps":["trace[1183541719] 'range keys from in-memory index tree' (duration: 927.958719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:26.536848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.237664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T01:03:26.536998Z","caller":"traceutil/trace.go:171","msg":"trace[1816969501] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"216.949419ms","start":"2026-06-13T01:03:26.320023Z","end":"2026-06-13T01:03:26.536972Z","steps":["trace[1816969501] 'read index received' (duration: 15.55µs)","trace[1816969501] 'applied index is now lower than readState.Index' (duration: 216.932229ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:03:26.537045Z","caller":"traceutil/trace.go:171","msg":"trace[559291885] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"1.815084413s","start":"2026-06-13T01:03:24.721919Z","end":"2026-06-13T01:03:26.537004Z","steps":["trace[559291885] 'process raft request' (duration: 1.594554327s)","trace[559291885] 'compare' (duration: 219.64882ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:03:26.537139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.1093ms","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-06-13T01:03:26.537187Z","caller":"traceutil/trace.go:171","msg":"trace[642091890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"217.17643ms","start":"2026-06-13T01:03:26.319996Z","end":"2026-06-13T01:03:26.537173Z","steps":["trace[642091890] 'agreement among raft nodes before linearized reading' (duration: 217.070729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:26.537227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:24.721894Z","time spent":"1.815230014s","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:03:52.753478Z","caller":"traceutil/trace.go:171","msg":"trace[911335408] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"133.705057ms","start":"2026-06-13T01:03:52.619751Z","end":"2026-06-13T01:03:52.753456Z","steps":["trace[911335408] 'process raft request' (duration: 133.664925ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:52.753811Z","caller":"traceutil/trace.go:171","msg":"trace[1019669132] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"142.262982ms","start":"2026-06-13T01:03:52.611535Z","end":"2026-06-13T01:03:52.753798Z","steps":["trace[1019669132] 'process raft request' (duration: 141.725162ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:52.754019Z","caller":"traceutil/trace.go:171","msg":"trace[310061931] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:574; }","duration":"124.949653ms","start":"2026-06-13T01:03:52.629019Z","end":"2026-06-13T01:03:52.753969Z","steps":["trace[310061931] 'read index received' (duration: 124.930202ms)","trace[310061931] 'applied index is now lower than readState.Index' (duration: 16.221µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:03:52.754142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.11071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:03:52.754176Z","caller":"traceutil/trace.go:171","msg":"trace[1444943008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"125.196033ms","start":"2026-06-13T01:03:52.628973Z","end":"2026-06-13T01:03:52.754169Z","steps":["trace[1444943008] 'agreement among raft nodes before linearized reading' (duration: 125.091489ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:52.842688Z","caller":"traceutil/trace.go:171","msg":"trace[1970769386] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"209.085974ms","start":"2026-06-13T01:03:52.633573Z","end":"2026-06-13T01:03:52.842659Z","steps":["trace[1970769386] 'process raft request' (duration: 208.891306ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:52.842757Z","caller":"traceutil/trace.go:171","msg":"trace[629799563] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"209.068014ms","start":"2026-06-13T01:03:52.633652Z","end":"2026-06-13T01:03:52.84272Z","steps":["trace[629799563] 'process raft request' (duration: 208.908247ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:52.842895Z","caller":"traceutil/trace.go:171","msg":"trace[850434604] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"204.169267ms","start":"2026-06-13T01:03:52.638715Z","end":"2026-06-13T01:03:52.842884Z","steps":["trace[850434604] 'process raft request' (duration: 203.934787ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:52.842873Z","caller":"traceutil/trace.go:171","msg":"trace[1458517259] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"204.115086ms","start":"2026-06-13T01:03:52.638701Z","end":"2026-06-13T01:03:52.842817Z","steps":["trace[1458517259] 'process raft request' (duration: 203.909457ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:52.842976Z","caller":"traceutil/trace.go:171","msg":"trace[395979625] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"203.9813ms","start":"2026-06-13T01:03:52.638985Z","end":"2026-06-13T01:03:52.842966Z","steps":["trace[395979625] 'process raft request' (duration: 203.815324ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:52.843051Z","caller":"traceutil/trace.go:171","msg":"trace[686437576] transaction","detail":"{read_only:false; number_of_response:1; response_revision:560; }","duration":"203.882166ms","start":"2026-06-13T01:03:52.639158Z","end":"2026-06-13T01:03:52.843041Z","steps":["trace[686437576] 'process raft request' (duration: 203.686329ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:52.843085Z","caller":"traceutil/trace.go:171","msg":"trace[707295035] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"202.693992ms","start":"2026-06-13T01:03:52.640382Z","end":"2026-06-13T01:03:52.843076Z","steps":["trace[707295035] 'process raft request' (duration: 202.516595ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:52.843051Z","caller":"traceutil/trace.go:171","msg":"trace[2093079357] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"204.170478ms","start":"2026-06-13T01:03:52.638808Z","end":"2026-06-13T01:03:52.842978Z","steps":["trace[2093079357] 'process raft request' (duration: 203.95414ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:52.84322Z","caller":"traceutil/trace.go:171","msg":"trace[2112697576] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"196.491156ms","start":"2026-06-13T01:03:52.646694Z","end":"2026-06-13T01:03:52.843185Z","steps":["trace[2112697576] 'process raft request' (duration: 196.283588ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:52.8433Z","caller":"traceutil/trace.go:171","msg":"trace[630532881] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"209.488809ms","start":"2026-06-13T01:03:52.633126Z","end":"2026-06-13T01:03:52.842615Z","steps":["trace[630532881] 'process raft request' (duration: 207.998463ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:52.843192Z","caller":"traceutil/trace.go:171","msg":"trace[1627589891] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"184.731088ms","start":"2026-06-13T01:03:52.658451Z","end":"2026-06-13T01:03:52.843182Z","steps":["trace[1627589891] 'process raft request' (duration: 184.590462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:52.843421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.101325ms","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-06-13T01:03:52.843458Z","caller":"traceutil/trace.go:171","msg":"trace[1507215459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:563; }","duration":"102.162498ms","start":"2026-06-13T01:03:52.741284Z","end":"2026-06-13T01:03:52.843446Z","steps":["trace[1507215459] 'agreement among raft nodes before linearized reading' (duration: 101.942539ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:03:58.946047Z","caller":"traceutil/trace.go:171","msg":"trace[1231830747] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"331.829131ms","start":"2026-06-13T01:03:58.614198Z","end":"2026-06-13T01:03:58.946027Z","steps":["trace[1231830747] 'read index received' (duration: 331.292323ms)","trace[1231830747] 'applied index is now lower than readState.Index' (duration: 535.978µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:03:58.946125Z","caller":"traceutil/trace.go:171","msg":"trace[129668934] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"683.335344ms","start":"2026-06-13T01:03:58.262751Z","end":"2026-06-13T01:03:58.946086Z","steps":["trace[129668934] 'process raft request' (duration: 682.991411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:58.946282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:58.262711Z","time spent":"683.50887ms","remote":"127.0.0.1:45568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T01:03:58.946299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.05154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:03:58.946553Z","caller":"traceutil/trace.go:171","msg":"trace[320400411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"332.393932ms","start":"2026-06-13T01:03:58.614146Z","end":"2026-06-13T01:03:58.94654Z","steps":["trace[320400411] 'agreement among raft nodes before linearized reading' (duration: 331.997918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:03:58.946593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:58.614124Z","time spent":"332.455134ms","remote":"127.0.0.1:45496","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-06-13T01:03:59.933807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899886588100730,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T01:04:00.023008Z","caller":"traceutil/trace.go:171","msg":"trace[204640124] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"589.880554ms","start":"2026-06-13T01:03:59.43311Z","end":"2026-06-13T01:04:00.022991Z","steps":["trace[204640124] 'read index received' (duration: 589.149237ms)","trace[204640124] 'applied index is now lower than readState.Index' (duration: 730.606µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:04:00.023033Z","caller":"traceutil/trace.go:171","msg":"trace[393748530] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"627.005927ms","start":"2026-06-13T01:03:59.396013Z","end":"2026-06-13T01:04:00.023019Z","steps":["trace[393748530] 'process raft request' (duration: 626.571972ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:00.023126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:59.395983Z","time spent":"627.08518ms","remote":"127.0.0.1:45568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:04:00.023139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.025348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-694jj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-13T01:04:00.023156Z","caller":"traceutil/trace.go:171","msg":"trace[1886645450] range","detail":"{range_begin:/registry/pods/kube-system/cilium-694jj; range_end:; response_count:1; response_revision:643; }","duration":"590.07275ms","start":"2026-06-13T01:03:59.433078Z","end":"2026-06-13T01:04:00.02315Z","steps":["trace[1886645450] 'agreement among raft nodes before linearized reading' (duration: 589.980027ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:00.02317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:03:59.433061Z","time spent":"590.105941ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-694jj\" "} {"level":"info","ts":"2026-06-13T01:04:00.143815Z","caller":"traceutil/trace.go:171","msg":"trace[1765734946] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"105.978904ms","start":"2026-06-13T01:04:00.037809Z","end":"2026-06-13T01:04:00.143788Z","steps":["trace[1765734946] 'process raft request' (duration: 67.839668ms)","trace[1765734946] 'compare' (duration: 37.98634ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:04:01.842838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119473034s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-13T01:04:01.843044Z","caller":"traceutil/trace.go:171","msg":"trace[1480120711] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.049941408s","start":"2026-06-13T01:04:00.79308Z","end":"2026-06-13T01:04:01.843021Z","steps":["trace[1480120711] 'process raft request' (duration: 1.049870076s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:01.843123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:04:00.793056Z","time spent":"1.050022481s","remote":"127.0.0.1:45722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:04:01.843489Z","caller":"traceutil/trace.go:171","msg":"trace[259676094] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"1.230085659s","start":"2026-06-13T01:04:00.613366Z","end":"2026-06-13T01:04:01.843452Z","steps":["trace[259676094] 'read index received' (duration: 109.915421ms)","trace[259676094] 'applied index is now lower than readState.Index' (duration: 1.120168368s)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:04:01.843661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230274965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:04:01.843697Z","caller":"traceutil/trace.go:171","msg":"trace[546060668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"1.230368819s","start":"2026-06-13T01:04:00.613317Z","end":"2026-06-13T01:04:01.843686Z","steps":["trace[546060668] 'agreement among raft nodes before linearized reading' (duration: 1.230276785s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:01.843728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:04:00.613297Z","time spent":"1.230423921s","remote":"127.0.0.1:45496","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-06-13T01:04:01.843872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.41233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-13T01:04:01.843951Z","caller":"traceutil/trace.go:171","msg":"trace[1553117787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"887.529334ms","start":"2026-06-13T01:04:00.956406Z","end":"2026-06-13T01:04:01.843936Z","steps":["trace[1553117787] 'agreement among raft nodes before linearized reading' (duration: 887.326038ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:01.844061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:04:00.956386Z","time spent":"887.665748ms","remote":"127.0.0.1:45722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-13T01:04:01.844235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.090901ms","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-06-13T01:04:01.844261Z","caller":"traceutil/trace.go:171","msg":"trace[738355447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"877.146622ms","start":"2026-06-13T01:04:00.967107Z","end":"2026-06-13T01:04:01.844254Z","steps":["trace[738355447] 'agreement among raft nodes before linearized reading' (duration: 877.049719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:01.84427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.633819ms","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-06-13T01:04:01.84428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:04:00.967097Z","time spent":"877.178764ms","remote":"127.0.0.1:45722","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-06-13T01:04:01.844293Z","caller":"traceutil/trace.go:171","msg":"trace[872428736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"881.693191ms","start":"2026-06-13T01:04:00.962591Z","end":"2026-06-13T01:04:01.844284Z","steps":["trace[872428736] 'agreement among raft nodes before linearized reading' (duration: 881.622929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:01.84431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:04:00.96257Z","time spent":"881.736223ms","remote":"127.0.0.1:45722","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-06-13T01:04:01.844398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.377199ms","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-06-13T01:04:01.844414Z","caller":"traceutil/trace.go:171","msg":"trace[485855632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"887.4134ms","start":"2026-06-13T01:04:00.956995Z","end":"2026-06-13T01:04:01.844408Z","steps":["trace[485855632] 'agreement among raft nodes before linearized reading' (duration: 887.361058ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:01.844429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:04:00.956983Z","time spent":"887.442381ms","remote":"127.0.0.1:45722","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-06-13T01:04:01.84448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.755891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-13T01:04:01.844546Z","caller":"traceutil/trace.go:171","msg":"trace[931755380] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:646; }","duration":"525.866665ms","start":"2026-06-13T01:04:01.318663Z","end":"2026-06-13T01:04:01.844529Z","steps":["trace[931755380] 'agreement among raft nodes before linearized reading' (duration: 525.675608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:01.844575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:04:01.318648Z","time spent":"525.920847ms","remote":"127.0.0.1:45518","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.53\" "} {"level":"info","ts":"2026-06-13T01:04:01.843529Z","caller":"traceutil/trace.go:171","msg":"trace[1147978211] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.76359026s","start":"2026-06-13T01:04:00.079672Z","end":"2026-06-13T01:04:01.843262Z","steps":["trace[1147978211] 'process raft request' (duration: 643.615378ms)","trace[1147978211] 'compare' (duration: 1.119289468s)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:04:01.84497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:04:00.079644Z","time spent":"1.765280318s","remote":"127.0.0.1:45660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T01:04:15.551071Z","caller":"traceutil/trace.go:171","msg":"trace[484702356] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"169.708584ms","start":"2026-06-13T01:04:15.381337Z","end":"2026-06-13T01:04:15.551046Z","steps":["trace[484702356] 'process raft request' (duration: 169.56029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:18.498359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899886588101150,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T01:04:18.509527Z","caller":"traceutil/trace.go:171","msg":"trace[1495111022] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:850; }","duration":"511.819862ms","start":"2026-06-13T01:04:17.997682Z","end":"2026-06-13T01:04:18.509502Z","steps":["trace[1495111022] 'read index received' (duration: 511.656647ms)","trace[1495111022] 'applied index is now lower than readState.Index' (duration: 162.405µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:04:18.509605Z","caller":"traceutil/trace.go:171","msg":"trace[1241872440] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"544.87634ms","start":"2026-06-13T01:04:17.964703Z","end":"2026-06-13T01:04:18.509579Z","steps":["trace[1241872440] 'process raft request' (duration: 544.664474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:18.509745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:04:17.964672Z","time spent":"544.982763ms","remote":"127.0.0.1:45722","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-06-13T01:04:18.509801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.091659ms","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-06-13T01:04:18.509877Z","caller":"traceutil/trace.go:171","msg":"trace[2126701565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:819; }","duration":"512.217823ms","start":"2026-06-13T01:04:17.997641Z","end":"2026-06-13T01:04:18.509859Z","steps":["trace[2126701565] 'agreement among raft nodes before linearized reading' (duration: 511.971716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:18.50995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:04:17.997628Z","time spent":"512.313515ms","remote":"127.0.0.1:45722","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-06-13T01:04:18.5098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.181416ms","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-06-13T01:04:18.510519Z","caller":"traceutil/trace.go:171","msg":"trace[1629797499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:819; }","duration":"507.932288ms","start":"2026-06-13T01:04:18.002567Z","end":"2026-06-13T01:04:18.510499Z","steps":["trace[1629797499] 'agreement among raft nodes before linearized reading' (duration: 507.049623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:18.510599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:04:18.002516Z","time spent":"508.065601ms","remote":"127.0.0.1:45722","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-06-13T01:04:18.5112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.647165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-13T01:04:18.511288Z","caller":"traceutil/trace.go:171","msg":"trace[523457907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:819; }","duration":"496.896171ms","start":"2026-06-13T01:04:18.014372Z","end":"2026-06-13T01:04:18.511268Z","steps":["trace[523457907] 'agreement among raft nodes before linearized reading' (duration: 495.409589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:18.511362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:04:18.01436Z","time spent":"496.984433ms","remote":"127.0.0.1:45722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-13T01:04:18.670457Z","caller":"traceutil/trace.go:171","msg":"trace[1309954391] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"156.869244ms","start":"2026-06-13T01:04:18.513563Z","end":"2026-06-13T01:04:18.670433Z","steps":["trace[1309954391] 'process raft request' (duration: 156.695169ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:04:18.677621Z","caller":"traceutil/trace.go:171","msg":"trace[335412265] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"162.699491ms","start":"2026-06-13T01:04:18.514876Z","end":"2026-06-13T01:04:18.677575Z","steps":["trace[335412265] 'process raft request' (duration: 162.526126ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:04:18.677707Z","caller":"traceutil/trace.go:171","msg":"trace[744787853] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"162.938958ms","start":"2026-06-13T01:04:18.514749Z","end":"2026-06-13T01:04:18.677688Z","steps":["trace[744787853] 'process raft request' (duration: 162.500486ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:04:18.677982Z","caller":"traceutil/trace.go:171","msg":"trace[1979248039] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"120.465768ms","start":"2026-06-13T01:04:18.55749Z","end":"2026-06-13T01:04:18.677955Z","steps":["trace[1979248039] 'process raft request' (duration: 119.967664ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:04:23.26878Z","caller":"traceutil/trace.go:171","msg":"trace[960262569] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"226.437204ms","start":"2026-06-13T01:04:23.042313Z","end":"2026-06-13T01:04:23.26875Z","steps":["trace[960262569] 'process raft request' (duration: 215.887285ms)","trace[960262569] 'compare' (duration: 10.063994ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:04:23.282268Z","caller":"traceutil/trace.go:171","msg":"trace[1729183841] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"167.481093ms","start":"2026-06-13T01:04:23.114759Z","end":"2026-06-13T01:04:23.28224Z","steps":["trace[1729183841] 'process raft request' (duration: 167.201185ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:04:23.282426Z","caller":"traceutil/trace.go:171","msg":"trace[905603137] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"167.503644ms","start":"2026-06-13T01:04:23.114903Z","end":"2026-06-13T01:04:23.282407Z","steps":["trace[905603137] 'process raft request' (duration: 167.190415ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:04:23.282633Z","caller":"traceutil/trace.go:171","msg":"trace[2078912178] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"140.586319ms","start":"2026-06-13T01:04:23.142038Z","end":"2026-06-13T01:04:23.282624Z","steps":["trace[2078912178] 'process raft request' (duration: 140.542778ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:04:23.282697Z","caller":"traceutil/trace.go:171","msg":"trace[296779769] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"167.676198ms","start":"2026-06-13T01:04:23.114997Z","end":"2026-06-13T01:04:23.282673Z","steps":["trace[296779769] 'process raft request' (duration: 167.349399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:32.930025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.27317ms","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-06-13T01:04:32.930411Z","caller":"traceutil/trace.go:171","msg":"trace[382366200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:984; }","duration":"167.67182ms","start":"2026-06-13T01:04:32.762716Z","end":"2026-06-13T01:04:32.930388Z","steps":["trace[382366200] 'range keys from in-memory index tree' (duration: 167.093516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:04:32.930073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.864807ms","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-06-13T01:04:32.930518Z","caller":"traceutil/trace.go:171","msg":"trace[1327185029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:984; }","duration":"202.35881ms","start":"2026-06-13T01:04:32.72815Z","end":"2026-06-13T01:04:32.930508Z","steps":["trace[1327185029] 'range keys from in-memory index tree' (duration: 201.714414ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:04:50.120835Z","caller":"traceutil/trace.go:171","msg":"trace[1708824802] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"176.087259ms","start":"2026-06-13T01:04:49.944721Z","end":"2026-06-13T01:04:50.120808Z","steps":["trace[1708824802] 'process raft request' (duration: 175.914046ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:04:50.794708Z","caller":"traceutil/trace.go:171","msg":"trace[480742914] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"122.238466ms","start":"2026-06-13T01:04:50.672447Z","end":"2026-06-13T01:04:50.794686Z","steps":["trace[480742914] 'process raft request' (duration: 122.107633ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:05:13.118702Z","caller":"traceutil/trace.go:171","msg":"trace[83076088] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"162.078826ms","start":"2026-06-13T01:05:12.9566Z","end":"2026-06-13T01:05:13.118679Z","steps":["trace[83076088] 'process raft request' (duration: 101.644847ms)","trace[83076088] 'compare' (duration: 60.332207ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:06:13.599699Z","caller":"traceutil/trace.go:171","msg":"trace[1737625183] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"100.196679ms","start":"2026-06-13T01:06:13.499483Z","end":"2026-06-13T01:06:13.59968Z","steps":["trace[1737625183] 'process raft request' (duration: 98.800501ms)"],"step_count":1}