{"level":"warn","ts":"2026-05-30T05:12:25.110286Z","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-30T05:12:25.110371Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-30T05:12:25.110433Z","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-30T05:12:25.11044Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-05-30T05:12:25.110455Z","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-30T05:12:25.111051Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-05-30T05:12:25.111132Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-30T05:12:25.11511Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.777393ms"} {"level":"info","ts":"2026-05-30T05:12:25.125241Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-05-30T05:12:25.125299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T05:12:25.125318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-05-30T05:12:25.125328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T05:12:25.125337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-05-30T05:12:25.125369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-05-30T05:12:25.130657Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T05:12:25.131753Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T05:12:25.132848Z","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-30T05:12:25.135684Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T05:12:25.136107Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T05:12:25.136207Z","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-30T05:12:25.136341Z","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-30T05:12:25.136356Z","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-30T05:12:25.13744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-05-30T05:12:25.138848Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-05-30T05:12:25.141038Z","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-30T05:12:25.141217Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T05:12:25.141245Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T05:12:25.141228Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-05-30T05:12:25.14131Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-05-30T05:12:26.126068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T05:12:26.126119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T05:12:26.126145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-05-30T05:12:26.126157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-05-30T05:12:26.126162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-05-30T05:12:26.126178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-05-30T05:12:26.126186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-05-30T05:12:26.126996Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T05:12:26.127048Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T05:12:26.127238Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T05:12:26.127246Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T05:12:26.127435Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T05:12:26.12759Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T05:12:26.129885Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T05:12:26.13015Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T05:12:26.130245Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T05:12:26.131345Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-30T05:12:26.132563Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-05-30T05:12:29.274744Z","caller":"traceutil/trace.go:171","msg":"trace[622512796] transaction","detail":"{read_only:false; response_revision:184; number_of_response:1; }","duration":"221.360612ms","start":"2026-05-30T05:12:29.053333Z","end":"2026-05-30T05:12:29.274694Z","steps":["trace[622512796] 'process raft request' (duration: 177.18706ms)","trace[622512796] 'compare' (duration: 43.870826ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T05:12:29.986173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311694567876291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T05:12:31.48573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99979317s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-30T05:12:31.485848Z","caller":"traceutil/trace.go:171","msg":"trace[228753798] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999963414s","start":"2026-05-30T05:12:29.48586Z","end":"2026-05-30T05:12:31.485823Z","steps":["trace[228753798] 'agreement among raft nodes before linearized reading' (duration: 1.999826121s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:31.485898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:12:29.485838Z","time spent":"2.000050125s","remote":"127.0.0.1:36570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/30 05:12:31 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-30T05:12:31.510987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000794923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-30T05:12:31.511149Z","caller":"traceutil/trace.go:171","msg":"trace[1639725662] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000987317s","start":"2026-05-30T05:12:29.510139Z","end":"2026-05-30T05:12:31.511126Z","steps":["trace[1639725662] 'agreement among raft nodes before linearized reading' (duration: 2.000808624s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:31.511226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:12:29.510128Z","time spent":"2.001085139s","remote":"127.0.0.1:36578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/30 05:12:31 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-30T05:12:32.024806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.800520117s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T05:12:32.024922Z","caller":"traceutil/trace.go:171","msg":"trace[1357195072] linearizableReadLoop","detail":"{readStateIndex:189; appliedIndex:188; }","duration":"2.539001904s","start":"2026-05-30T05:12:29.485903Z","end":"2026-05-30T05:12:32.024905Z","steps":["trace[1357195072] 'read index received' (duration: 737.803871ms)","trace[1357195072] 'applied index is now lower than readState.Index' (duration: 1.801196563s)"],"step_count":2} {"level":"info","ts":"2026-05-30T05:12:32.025035Z","caller":"traceutil/trace.go:171","msg":"trace[896063306] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"2.741176622s","start":"2026-05-30T05:12:29.283832Z","end":"2026-05-30T05:12:32.025009Z","steps":["trace[896063306] 'process raft request' (duration: 939.93596ms)","trace[896063306] 'compare' (duration: 1.800251641s)"],"step_count":2} {"level":"warn","ts":"2026-05-30T05:12:32.025155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:12:29.283798Z","time spent":"2.741306095s","remote":"127.0.0.1:36898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":694,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T05:12:32.379914Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.503498706s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-30T05:12:32.390534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.431741028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T05:12:32.390565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.427811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T05:12:32.390584Z","caller":"traceutil/trace.go:171","msg":"trace[1448266366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:186; }","duration":"2.431848301s","start":"2026-05-30T05:12:29.958725Z","end":"2026-05-30T05:12:32.390573Z","steps":["trace[1448266366] 'agreement among raft nodes before linearized reading' (duration: 2.431757029s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:32.390569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.320252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T05:12:32.390567Z","caller":"traceutil/trace.go:171","msg":"trace[949943205] transaction","detail":"{read_only:false; response_revision:186; number_of_response:1; }","duration":"1.514209362s","start":"2026-05-30T05:12:30.876318Z","end":"2026-05-30T05:12:32.390527Z","steps":["trace[949943205] 'process raft request' (duration: 1.503817023s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:32.390616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:12:29.958694Z","time spent":"2.431915903s","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-30T05:12:32.390615Z","caller":"traceutil/trace.go:171","msg":"trace[218250525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:186; }","duration":"843.481332ms","start":"2026-05-30T05:12:31.54712Z","end":"2026-05-30T05:12:32.390602Z","steps":["trace[218250525] 'agreement among raft nodes before linearized reading' (duration: 843.39058ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:32.39063Z","caller":"traceutil/trace.go:171","msg":"trace[1766623296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:186; }","duration":"781.386895ms","start":"2026-05-30T05:12:31.609226Z","end":"2026-05-30T05:12:32.390613Z","steps":["trace[1766623296] 'agreement among raft nodes before linearized reading' (duration: 781.140309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:32.390665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:12:31.609152Z","time spent":"781.505747ms","remote":"127.0.0.1:36570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T05:12:32.390662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:12:31.54706Z","time spent":"843.592536ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T05:12:32.390691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:12:30.876291Z","time spent":"1.514339945s","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3953,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T05:12:32.390548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.289746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T05:12:32.390807Z","caller":"traceutil/trace.go:171","msg":"trace[933694705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:186; }","duration":"960.610954ms","start":"2026-05-30T05:12:31.430177Z","end":"2026-05-30T05:12:32.390788Z","steps":["trace[933694705] 'agreement among raft nodes before linearized reading' (duration: 960.263816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:32.390795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.638507159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T05:12:32.390911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:12:31.430156Z","time spent":"960.714866ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-30T05:12:32.390302Z","caller":"traceutil/trace.go:171","msg":"trace[1247676750] linearizableReadLoop","detail":"{readStateIndex:190; appliedIndex:189; }","duration":"365.307995ms","start":"2026-05-30T05:12:32.024948Z","end":"2026-05-30T05:12:32.390256Z","steps":["trace[1247676750] 'read index received' (duration: 355.085729ms)","trace[1247676750] 'applied index is now lower than readState.Index' (duration: 10.220806ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T05:12:32.3911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.391523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T05:12:32.391143Z","caller":"traceutil/trace.go:171","msg":"trace[796870086] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader; range_end:; response_count:0; response_revision:186; }","duration":"363.462474ms","start":"2026-05-30T05:12:32.027665Z","end":"2026-05-30T05:12:32.391128Z","steps":["trace[796870086] 'agreement among raft nodes before linearized reading' (duration: 363.383572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:32.391188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:12:32.02765Z","time spent":"363.530326ms","remote":"127.0.0.1:36910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader\" "} {"level":"info","ts":"2026-05-30T05:12:32.390913Z","caller":"traceutil/trace.go:171","msg":"trace[1232861542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:186; }","duration":"2.638675463s","start":"2026-05-30T05:12:29.75221Z","end":"2026-05-30T05:12:32.390885Z","steps":["trace[1232861542] 'agreement among raft nodes before linearized reading' (duration: 2.638452108s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:32.392138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:12:29.75214Z","time spent":"2.639952901s","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-30T05:12:32.650022Z","caller":"traceutil/trace.go:171","msg":"trace[962441512] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"114.525816ms","start":"2026-05-30T05:12:32.535478Z","end":"2026-05-30T05:12:32.650003Z","steps":["trace[962441512] 'process raft request' (duration: 114.420483ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:34.303745Z","caller":"traceutil/trace.go:171","msg":"trace[410067029] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"100.309124ms","start":"2026-05-30T05:12:34.203417Z","end":"2026-05-30T05:12:34.303726Z","steps":["trace[410067029] 'process raft request' (duration: 97.737867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:34.693562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.224454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T05:12:34.693914Z","caller":"traceutil/trace.go:171","msg":"trace[361583374] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"203.911722ms","start":"2026-05-30T05:12:34.489955Z","end":"2026-05-30T05:12:34.693867Z","steps":["trace[361583374] 'process raft request' (duration: 35.321229ms)","trace[361583374] 'compare' (duration: 168.098593ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T05:12:34.694061Z","caller":"traceutil/trace.go:171","msg":"trace[236580878] transaction","detail":"{read_only:false; number_of_response:0; response_revision:260; }","duration":"106.606763ms","start":"2026-05-30T05:12:34.587442Z","end":"2026-05-30T05:12:34.694048Z","steps":["trace[236580878] 'process raft request' (duration: 106.567953ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:34.694137Z","caller":"traceutil/trace.go:171","msg":"trace[1126674321] linearizableReadLoop","detail":"{readStateIndex:266; appliedIndex:265; }","duration":"201.931618ms","start":"2026-05-30T05:12:34.492173Z","end":"2026-05-30T05:12:34.694105Z","steps":["trace[1126674321] 'read index received' (duration: 33.147181ms)","trace[1126674321] 'applied index is now lower than readState.Index' (duration: 168.780307ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T05:12:34.694385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.178714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-30T05:12:34.694452Z","caller":"traceutil/trace.go:171","msg":"trace[725873272] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:260; }","duration":"202.278136ms","start":"2026-05-30T05:12:34.492155Z","end":"2026-05-30T05:12:34.694433Z","steps":["trace[725873272] 'agreement among raft nodes before linearized reading' (duration: 202.05255ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:34.779285Z","caller":"traceutil/trace.go:171","msg":"trace[536466946] transaction","detail":"{read_only:false; number_of_response:0; response_revision:260; }","duration":"170.173857ms","start":"2026-05-30T05:12:34.609082Z","end":"2026-05-30T05:12:34.779255Z","steps":["trace[536466946] 'process raft request' (duration: 170.078696ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:34.779299Z","caller":"traceutil/trace.go:171","msg":"trace[1348019462] transaction","detail":"{read_only:false; number_of_response:0; response_revision:260; }","duration":"169.87883ms","start":"2026-05-30T05:12:34.609389Z","end":"2026-05-30T05:12:34.779268Z","steps":["trace[1348019462] 'process raft request' (duration: 169.795229ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:34.779381Z","caller":"traceutil/trace.go:171","msg":"trace[782243854] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"168.812687ms","start":"2026-05-30T05:12:34.610532Z","end":"2026-05-30T05:12:34.779344Z","steps":["trace[782243854] 'process raft request' (duration: 168.703924ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:34.77942Z","caller":"traceutil/trace.go:171","msg":"trace[1923983804] transaction","detail":"{read_only:false; number_of_response:0; response_revision:260; }","duration":"169.774068ms","start":"2026-05-30T05:12:34.609627Z","end":"2026-05-30T05:12:34.779401Z","steps":["trace[1923983804] 'process raft request' (duration: 169.578535ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:34.779505Z","caller":"traceutil/trace.go:171","msg":"trace[884862033] transaction","detail":"{read_only:false; number_of_response:0; response_revision:260; }","duration":"170.823192ms","start":"2026-05-30T05:12:34.608673Z","end":"2026-05-30T05:12:34.779496Z","steps":["trace[884862033] 'process raft request' (duration: 170.412153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:34.779606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.891905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4015"} {"level":"warn","ts":"2026-05-30T05:12:34.779649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.372004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-05-30T05:12:34.77966Z","caller":"traceutil/trace.go:171","msg":"trace[346185708] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:261; }","duration":"286.966816ms","start":"2026-05-30T05:12:34.492679Z","end":"2026-05-30T05:12:34.779646Z","steps":["trace[346185708] 'agreement among raft nodes before linearized reading' (duration: 286.848653ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:34.779699Z","caller":"traceutil/trace.go:171","msg":"trace[1278274831] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:261; }","duration":"267.467095ms","start":"2026-05-30T05:12:34.512218Z","end":"2026-05-30T05:12:34.779685Z","steps":["trace[1278274831] 'agreement among raft nodes before linearized reading' (duration: 267.278161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:34.779856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.258502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-30T05:12:34.779948Z","caller":"traceutil/trace.go:171","msg":"trace[722515640] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:261; }","duration":"210.458066ms","start":"2026-05-30T05:12:34.569468Z","end":"2026-05-30T05:12:34.779926Z","steps":["trace[722515640] 'agreement among raft nodes before linearized reading' (duration: 209.970965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:34.78045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.453443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T05:12:34.780498Z","caller":"traceutil/trace.go:171","msg":"trace[1528102048] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:261; }","duration":"261.517264ms","start":"2026-05-30T05:12:34.518969Z","end":"2026-05-30T05:12:34.780486Z","steps":["trace[1528102048] 'agreement among raft nodes before linearized reading' (duration: 261.441152ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:34.897673Z","caller":"traceutil/trace.go:171","msg":"trace[1885826790] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:272; }","duration":"118.335754ms","start":"2026-05-30T05:12:34.779312Z","end":"2026-05-30T05:12:34.897648Z","steps":["trace[1885826790] 'read index received' (duration: 118.250572ms)","trace[1885826790] 'applied index is now lower than readState.Index' (duration: 83.792µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T05:12:34.897744Z","caller":"traceutil/trace.go:171","msg":"trace[937335071] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"198.78657ms","start":"2026-05-30T05:12:34.698881Z","end":"2026-05-30T05:12:34.897668Z","steps":["trace[937335071] 'process raft request' (duration: 198.631676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:34.897876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.557911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4015"} {"level":"info","ts":"2026-05-30T05:12:34.897922Z","caller":"traceutil/trace.go:171","msg":"trace[1958811860] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:262; }","duration":"202.659524ms","start":"2026-05-30T05:12:34.695249Z","end":"2026-05-30T05:12:34.897909Z","steps":["trace[1958811860] 'agreement among raft nodes before linearized reading' (duration: 202.524802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:34.927601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.748737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4318"} {"level":"info","ts":"2026-05-30T05:12:34.927652Z","caller":"traceutil/trace.go:171","msg":"trace[1436787771] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:264; }","duration":"145.862491ms","start":"2026-05-30T05:12:34.781777Z","end":"2026-05-30T05:12:34.92764Z","steps":["trace[1436787771] 'agreement among raft nodes before linearized reading' (duration: 145.755118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:34.927677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.945238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-30T05:12:34.927746Z","caller":"traceutil/trace.go:171","msg":"trace[274727587] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:264; }","duration":"144.054471ms","start":"2026-05-30T05:12:34.783674Z","end":"2026-05-30T05:12:34.927728Z","steps":["trace[274727587] 'agreement among raft nodes before linearized reading' (duration: 143.818945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:34.927602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.469079ms","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-30T05:12:34.9279Z","caller":"traceutil/trace.go:171","msg":"trace[1976517457] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"147.006065ms","start":"2026-05-30T05:12:34.780871Z","end":"2026-05-30T05:12:34.927877Z","steps":["trace[1976517457] 'process raft request' (duration: 146.412612ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:34.927963Z","caller":"traceutil/trace.go:171","msg":"trace[528966704] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"143.752844ms","start":"2026-05-30T05:12:34.784197Z","end":"2026-05-30T05:12:34.92795Z","steps":["trace[528966704] 'process raft request' (duration: 143.219282ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:34.928013Z","caller":"traceutil/trace.go:171","msg":"trace[596635946] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:264; }","duration":"144.831387ms","start":"2026-05-30T05:12:34.783084Z","end":"2026-05-30T05:12:34.927915Z","steps":["trace[596635946] 'agreement among raft nodes before linearized reading' (duration: 144.410348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:34.928032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.900012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7285"} {"level":"info","ts":"2026-05-30T05:12:34.92832Z","caller":"traceutil/trace.go:171","msg":"trace[151167845] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:264; }","duration":"147.19865ms","start":"2026-05-30T05:12:34.78109Z","end":"2026-05-30T05:12:34.928288Z","steps":["trace[151167845] 'agreement among raft nodes before linearized reading' (duration: 146.840441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:34.92812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.339123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5316"} {"level":"info","ts":"2026-05-30T05:12:34.928578Z","caller":"traceutil/trace.go:171","msg":"trace[993314550] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:264; }","duration":"147.850634ms","start":"2026-05-30T05:12:34.780718Z","end":"2026-05-30T05:12:34.928568Z","steps":["trace[993314550] 'agreement among raft nodes before linearized reading' (duration: 147.288442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:34.928924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.945475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6957"} {"level":"info","ts":"2026-05-30T05:12:34.929181Z","caller":"traceutil/trace.go:171","msg":"trace[1817498191] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:264; }","duration":"147.221831ms","start":"2026-05-30T05:12:34.781947Z","end":"2026-05-30T05:12:34.929169Z","steps":["trace[1817498191] 'agreement among raft nodes before linearized reading' (duration: 146.26186ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:35.12918Z","caller":"traceutil/trace.go:171","msg":"trace[1255025554] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"196.568711ms","start":"2026-05-30T05:12:34.932583Z","end":"2026-05-30T05:12:35.129151Z","steps":["trace[1255025554] 'process raft request' (duration: 196.52095ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:35.129213Z","caller":"traceutil/trace.go:171","msg":"trace[1573593845] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"199.373284ms","start":"2026-05-30T05:12:34.9298Z","end":"2026-05-30T05:12:35.129173Z","steps":["trace[1573593845] 'process raft request' (duration: 137.341204ms)","trace[1573593845] 'compare' (duration: 61.788754ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T05:12:35.132263Z","caller":"traceutil/trace.go:171","msg":"trace[1460106762] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"177.289356ms","start":"2026-05-30T05:12:34.954956Z","end":"2026-05-30T05:12:35.132246Z","steps":["trace[1460106762] 'process raft request' (duration: 177.210444ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:35.132309Z","caller":"traceutil/trace.go:171","msg":"trace[330620361] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"190.410325ms","start":"2026-05-30T05:12:34.941873Z","end":"2026-05-30T05:12:35.132284Z","steps":["trace[330620361] 'process raft request' (duration: 189.552366ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:43.413287Z","caller":"traceutil/trace.go:171","msg":"trace[1099934481] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"110.0201ms","start":"2026-05-30T05:12:43.303248Z","end":"2026-05-30T05:12:43.413268Z","steps":["trace[1099934481] 'process raft request' (duration: 109.905907ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:44.720013Z","caller":"traceutil/trace.go:171","msg":"trace[2046492026] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"139.629358ms","start":"2026-05-30T05:12:44.580355Z","end":"2026-05-30T05:12:44.719985Z","steps":["trace[2046492026] 'process raft request' (duration: 139.578497ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:44.720075Z","caller":"traceutil/trace.go:171","msg":"trace[423565304] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"140.288792ms","start":"2026-05-30T05:12:44.579759Z","end":"2026-05-30T05:12:44.720048Z","steps":["trace[423565304] 'process raft request' (duration: 139.978045ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:44.720156Z","caller":"traceutil/trace.go:171","msg":"trace[824131130] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"139.819552ms","start":"2026-05-30T05:12:44.580296Z","end":"2026-05-30T05:12:44.720116Z","steps":["trace[824131130] 'process raft request' (duration: 139.586597ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:12:47.382122Z","caller":"traceutil/trace.go:171","msg":"trace[13776464] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"623.420038ms","start":"2026-05-30T05:12:46.75867Z","end":"2026-05-30T05:12:47.38209Z","steps":["trace[13776464] 'process raft request' (duration: 602.023353ms)","trace[13776464] 'compare' (duration: 21.054998ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T05:12:47.382147Z","caller":"traceutil/trace.go:171","msg":"trace[823206737] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"623.358457ms","start":"2026-05-30T05:12:46.758766Z","end":"2026-05-30T05:12:47.382124Z","steps":["trace[823206737] 'process raft request' (duration: 623.114251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:12:47.382448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:12:46.75865Z","time spent":"623.54466ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T05:12:47.382569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:12:46.758759Z","time spent":"623.448999ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T05:13:22.742816Z","caller":"traceutil/trace.go:171","msg":"trace[615555049] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"238.13993ms","start":"2026-05-30T05:13:22.504624Z","end":"2026-05-30T05:13:22.742764Z","steps":["trace[615555049] 'process raft request' (duration: 237.515521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:13:23.366358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311694567877175,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-30T05:13:23.549367Z","caller":"traceutil/trace.go:171","msg":"trace[2008385586] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"683.786977ms","start":"2026-05-30T05:13:22.865557Z","end":"2026-05-30T05:13:23.549344Z","steps":["trace[2008385586] 'read index received' (duration: 651.885045ms)","trace[2008385586] 'applied index is now lower than readState.Index' (duration: 31.900932ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T05:13:23.549488Z","caller":"traceutil/trace.go:171","msg":"trace[2124036317] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"767.876389ms","start":"2026-05-30T05:13:22.78159Z","end":"2026-05-30T05:13:23.549467Z","steps":["trace[2124036317] 'process raft request' (duration: 735.866503ms)","trace[2124036317] 'compare' (duration: 31.713577ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T05:13:23.549592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:13:22.781553Z","time spent":"767.970432ms","remote":"127.0.0.1:36748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T05:13:23.549681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.778806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"warn","ts":"2026-05-30T05:13:23.549806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.581822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T05:13:23.549844Z","caller":"traceutil/trace.go:171","msg":"trace[286626265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"263.653163ms","start":"2026-05-30T05:13:23.286177Z","end":"2026-05-30T05:13:23.54983Z","steps":["trace[286626265] 'agreement among raft nodes before linearized reading' (duration: 263.586392ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:13:23.549856Z","caller":"traceutil/trace.go:171","msg":"trace[599737023] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"738.312165ms","start":"2026-05-30T05:13:22.811458Z","end":"2026-05-30T05:13:23.549771Z","steps":["trace[599737023] 'process raft request' (duration: 737.843681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:13:23.549999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.260666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-30T05:13:23.549723Z","caller":"traceutil/trace.go:171","msg":"trace[1527806399] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:624; }","duration":"636.963081ms","start":"2026-05-30T05:13:22.912748Z","end":"2026-05-30T05:13:23.549711Z","steps":["trace[1527806399] 'agreement among raft nodes before linearized reading' (duration: 636.856618ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:13:23.550081Z","caller":"traceutil/trace.go:171","msg":"trace[1295729774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"440.39272ms","start":"2026-05-30T05:13:23.109668Z","end":"2026-05-30T05:13:23.55006Z","steps":["trace[1295729774] 'agreement among raft nodes before linearized reading' (duration: 440.169373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:13:23.550111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:13:22.912705Z","time spent":"637.394424ms","remote":"127.0.0.1:36604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.4\" "} {"level":"warn","ts":"2026-05-30T05:13:23.550126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:13:23.109642Z","time spent":"440.475551ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T05:13:23.550123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:13:22.811422Z","time spent":"738.577243ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T05:13:23.552266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.691414ms","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-30T05:13:23.552366Z","caller":"traceutil/trace.go:171","msg":"trace[1530342231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"686.847828ms","start":"2026-05-30T05:13:22.865487Z","end":"2026-05-30T05:13:23.552335Z","steps":["trace[1530342231] 'agreement among raft nodes before linearized reading' (duration: 684.079385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:13:23.552409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:13:22.865458Z","time spent":"686.939842ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T05:13:37.829469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.848145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-30T05:13:37.829561Z","caller":"traceutil/trace.go:171","msg":"trace[2000256932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"153.98217ms","start":"2026-05-30T05:13:37.675556Z","end":"2026-05-30T05:13:37.829539Z","steps":["trace[2000256932] 'range keys from in-memory index tree' (duration: 153.66861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:13:37.82987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.988445ms","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-30T05:13:37.829911Z","caller":"traceutil/trace.go:171","msg":"trace[1932834607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:786; }","duration":"209.073118ms","start":"2026-05-30T05:13:37.620828Z","end":"2026-05-30T05:13:37.829901Z","steps":["trace[1932834607] 'range keys from in-memory index tree' (duration: 208.876961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:13:37.830235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.154054ms","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-30T05:13:37.830353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.659702ms","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-30T05:13:37.830434Z","caller":"traceutil/trace.go:171","msg":"trace[1072588930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:786; }","duration":"152.788055ms","start":"2026-05-30T05:13:37.677626Z","end":"2026-05-30T05:13:37.830414Z","steps":["trace[1072588930] 'range keys from in-memory index tree' (duration: 152.507056ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:13:37.830563Z","caller":"traceutil/trace.go:171","msg":"trace[1601851938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:786; }","duration":"205.297768ms","start":"2026-05-30T05:13:37.625005Z","end":"2026-05-30T05:13:37.830302Z","steps":["trace[1601851938] 'range keys from in-memory index tree' (duration: 204.916537ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:13:52.303871Z","caller":"traceutil/trace.go:171","msg":"trace[683398978] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"382.1992ms","start":"2026-05-30T05:13:51.921647Z","end":"2026-05-30T05:13:52.303846Z","steps":["trace[683398978] 'read index received' (duration: 382.112578ms)","trace[683398978] 'applied index is now lower than readState.Index' (duration: 85.582µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T05:13:52.303993Z","caller":"traceutil/trace.go:171","msg":"trace[1551672353] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"384.299069ms","start":"2026-05-30T05:13:51.919656Z","end":"2026-05-30T05:13:52.303955Z","steps":["trace[1551672353] 'process raft request' (duration: 384.044031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:13:52.304162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:13:51.91962Z","time spent":"384.445033ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T05:13:52.304176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.496347ms","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-30T05:13:52.304476Z","caller":"traceutil/trace.go:171","msg":"trace[250878664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"382.865999ms","start":"2026-05-30T05:13:51.921586Z","end":"2026-05-30T05:13:52.304452Z","steps":["trace[250878664] 'agreement among raft nodes before linearized reading' (duration: 382.391776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:13:52.304543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:13:51.921558Z","time spent":"382.972702ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-30T05:13:52.349947Z","caller":"traceutil/trace.go:171","msg":"trace[2082966284] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"428.258737ms","start":"2026-05-30T05:13:51.921656Z","end":"2026-05-30T05:13:52.349914Z","steps":["trace[2082966284] 'process raft request' (duration: 428.045921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:13:52.35008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.714925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-wr22d\" ","response":"range_response_count:1 size:5675"} {"level":"warn","ts":"2026-05-30T05:13:52.350169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:13:51.921642Z","time spent":"428.447432ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T05:13:52.350244Z","caller":"traceutil/trace.go:171","msg":"trace[910750989] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-wr22d; range_end:; response_count:1; response_revision:954; }","duration":"138.89949ms","start":"2026-05-30T05:13:52.211285Z","end":"2026-05-30T05:13:52.350184Z","steps":["trace[910750989] 'agreement among raft nodes before linearized reading' (duration: 138.615522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:13:52.351001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.418361ms","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-30T05:13:52.351075Z","caller":"traceutil/trace.go:171","msg":"trace[1123283638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"429.333817ms","start":"2026-05-30T05:13:51.921718Z","end":"2026-05-30T05:13:52.351052Z","steps":["trace[1123283638] 'agreement among raft nodes before linearized reading' (duration: 428.209816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:13:52.351133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:13:51.92166Z","time spent":"429.46106ms","remote":"127.0.0.1:36820","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":"info","ts":"2026-05-30T05:14:19.168782Z","caller":"traceutil/trace.go:171","msg":"trace[1862565034] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"164.786501ms","start":"2026-05-30T05:14:19.003976Z","end":"2026-05-30T05:14:19.168763Z","steps":["trace[1862565034] 'process raft request' (duration: 164.666897ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:14:37.14543Z","caller":"traceutil/trace.go:171","msg":"trace[232352804] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"198.776232ms","start":"2026-05-30T05:14:36.946618Z","end":"2026-05-30T05:14:37.145394Z","steps":["trace[232352804] 'process raft request' (duration: 198.566516ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:14:37.238316Z","caller":"traceutil/trace.go:171","msg":"trace[1614259902] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"204.163918ms","start":"2026-05-30T05:14:37.034128Z","end":"2026-05-30T05:14:37.238292Z","steps":["trace[1614259902] 'process raft request' (duration: 166.248007ms)","trace[1614259902] 'compare' (duration: 37.817749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T05:15:01.157659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.983294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T05:15:01.15776Z","caller":"traceutil/trace.go:171","msg":"trace[592756712] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1248; }","duration":"281.221573ms","start":"2026-05-30T05:15:00.876525Z","end":"2026-05-30T05:15:01.157746Z","steps":["trace[592756712] 'read index received' (duration: 45.009334ms)","trace[592756712] 'applied index is now lower than readState.Index' (duration: 236.211189ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T05:15:01.157939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.421188ms","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-30T05:15:01.157967Z","caller":"traceutil/trace.go:171","msg":"trace[848535145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1195; }","duration":"281.447969ms","start":"2026-05-30T05:15:00.876511Z","end":"2026-05-30T05:15:01.157959Z","steps":["trace[848535145] 'agreement among raft nodes before linearized reading' (duration: 281.269503ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:15:01.157944Z","caller":"traceutil/trace.go:171","msg":"trace[413838146] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"369.457139ms","start":"2026-05-30T05:15:00.788458Z","end":"2026-05-30T05:15:01.157915Z","steps":["trace[413838146] 'process raft request' (duration: 133.140886ms)","trace[413838146] 'compare' (duration: 235.855001ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T05:15:01.158068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:15:00.788414Z","time spent":"369.591322ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T05:15:03.656201Z","caller":"traceutil/trace.go:171","msg":"trace[376825177] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"369.815296ms","start":"2026-05-30T05:15:03.286363Z","end":"2026-05-30T05:15:03.656178Z","steps":["trace[376825177] 'read index received' (duration: 369.685923ms)","trace[376825177] 'applied index is now lower than readState.Index' (duration: 128.333µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T05:15:03.656294Z","caller":"traceutil/trace.go:171","msg":"trace[1472868907] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"467.4615ms","start":"2026-05-30T05:15:03.188787Z","end":"2026-05-30T05:15:03.656248Z","steps":["trace[1472868907] 'process raft request' (duration: 467.219134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:15:03.656377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.012752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T05:15:03.656457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:15:03.188767Z","time spent":"467.602964ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T05:15:03.656601Z","caller":"traceutil/trace.go:171","msg":"trace[1835786013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"370.104914ms","start":"2026-05-30T05:15:03.286298Z","end":"2026-05-30T05:15:03.656403Z","steps":["trace[1835786013] 'agreement among raft nodes before linearized reading' (duration: 370.025112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:15:03.656659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T05:15:03.286274Z","time spent":"370.37283ms","remote":"127.0.0.1:36570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T05:15:03.662365Z","caller":"traceutil/trace.go:171","msg":"trace[335120683] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"236.451064ms","start":"2026-05-30T05:15:03.425888Z","end":"2026-05-30T05:15:03.662339Z","steps":["trace[335120683] 'process raft request' (duration: 236.198798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:15:03.662649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.728165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-30T05:15:03.662698Z","caller":"traceutil/trace.go:171","msg":"trace[1280346224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1204; }","duration":"231.839048ms","start":"2026-05-30T05:15:03.430848Z","end":"2026-05-30T05:15:03.662687Z","steps":["trace[1280346224] 'agreement among raft nodes before linearized reading' (duration: 231.696905ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:15:05.84028Z","caller":"traceutil/trace.go:171","msg":"trace[1347275362] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"175.324912ms","start":"2026-05-30T05:15:05.664931Z","end":"2026-05-30T05:15:05.840255Z","steps":["trace[1347275362] 'process raft request' (duration: 175.153548ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:15:05.861572Z","caller":"traceutil/trace.go:171","msg":"trace[1444959258] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1263; }","duration":"189.444054ms","start":"2026-05-30T05:15:05.672108Z","end":"2026-05-30T05:15:05.861552Z","steps":["trace[1444959258] 'read index received' (duration: 168.043911ms)","trace[1444959258] 'applied index is now lower than readState.Index' (duration: 21.399213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T05:15:05.86176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.628838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-30T05:15:05.861798Z","caller":"traceutil/trace.go:171","msg":"trace[678396892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1210; }","duration":"189.703169ms","start":"2026-05-30T05:15:05.672082Z","end":"2026-05-30T05:15:05.861785Z","steps":["trace[678396892] 'agreement among raft nodes before linearized reading' (duration: 189.550956ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:15:05.861876Z","caller":"traceutil/trace.go:171","msg":"trace[1585949572] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"194.91369ms","start":"2026-05-30T05:15:05.666886Z","end":"2026-05-30T05:15:05.8618Z","steps":["trace[1585949572] 'process raft request' (duration: 194.538851ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:15:14.945357Z","caller":"traceutil/trace.go:171","msg":"trace[1836360523] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"175.132656ms","start":"2026-05-30T05:15:14.770195Z","end":"2026-05-30T05:15:14.945328Z","steps":["trace[1836360523] 'process raft request' (duration: 174.951061ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:15:28.294915Z","caller":"traceutil/trace.go:171","msg":"trace[1359537729] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"116.731328ms","start":"2026-05-30T05:15:28.178147Z","end":"2026-05-30T05:15:28.294878Z","steps":["trace[1359537729] 'process raft request' (duration: 42.357757ms)","trace[1359537729] 'compare' (duration: 74.001502ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T05:15:29.200608Z","caller":"traceutil/trace.go:171","msg":"trace[1863340099] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"170.543922ms","start":"2026-05-30T05:15:29.030038Z","end":"2026-05-30T05:15:29.200582Z","steps":["trace[1863340099] 'read index received' (duration: 170.278565ms)","trace[1863340099] 'applied index is now lower than readState.Index' (duration: 264.187µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T05:15:29.200748Z","caller":"traceutil/trace.go:171","msg":"trace[718071727] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"181.907771ms","start":"2026-05-30T05:15:29.01881Z","end":"2026-05-30T05:15:29.200718Z","steps":["trace[718071727] 'process raft request' (duration: 181.529861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:15:29.200892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.810269ms","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-30T05:15:29.200954Z","caller":"traceutil/trace.go:171","msg":"trace[600400076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1326; }","duration":"170.943172ms","start":"2026-05-30T05:15:29.029995Z","end":"2026-05-30T05:15:29.200938Z","steps":["trace[600400076] 'agreement among raft nodes before linearized reading' (duration: 170.689996ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:15:36.26056Z","caller":"traceutil/trace.go:171","msg":"trace[2035973220] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"204.35969ms","start":"2026-05-30T05:15:36.056173Z","end":"2026-05-30T05:15:36.260533Z","steps":["trace[2035973220] 'read index received' (duration: 204.227076ms)","trace[2035973220] 'applied index is now lower than readState.Index' (duration: 131.034µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T05:15:36.260617Z","caller":"traceutil/trace.go:171","msg":"trace[1226855991] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"224.429789ms","start":"2026-05-30T05:15:36.036148Z","end":"2026-05-30T05:15:36.260578Z","steps":["trace[1226855991] 'process raft request' (duration: 224.232614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:15:36.26079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.589316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-05-30T05:15:36.260876Z","caller":"traceutil/trace.go:171","msg":"trace[1188030300] range","detail":"{range_begin:/registry/clusterroles/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:1350; }","duration":"204.715978ms","start":"2026-05-30T05:15:36.05614Z","end":"2026-05-30T05:15:36.260856Z","steps":["trace[1188030300] 'agreement among raft nodes before linearized reading' (duration: 204.542844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T05:15:36.26093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.54504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-envoy-gateway-rolebinding\" ","response":"range_response_count:1 size:842"} {"level":"info","ts":"2026-05-30T05:15:36.26104Z","caller":"traceutil/trace.go:171","msg":"trace[1856998306] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-envoy-gateway-rolebinding; range_end:; response_count:1; response_revision:1350; }","duration":"157.724914ms","start":"2026-05-30T05:15:36.103295Z","end":"2026-05-30T05:15:36.26102Z","steps":["trace[1856998306] 'agreement among raft nodes before linearized reading' (duration: 157.400626ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T05:15:49.547136Z","caller":"traceutil/trace.go:171","msg":"trace[500046781] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"113.494209ms","start":"2026-05-30T05:15:49.433618Z","end":"2026-05-30T05:15:49.547112Z","steps":["trace[500046781] 'process raft request' (duration: 113.312064ms)"],"step_count":1}