{"level":"warn","ts":"2026-06-08T17:16:46.571326Z","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-08T17:16:46.571403Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-08T17:16:46.571474Z","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-08T17:16:46.571485Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-06-08T17:16:46.571502Z","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-08T17:16:46.572018Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-06-08T17:16:46.572107Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-08T17:16:46.576182Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.857708ms"} {"level":"info","ts":"2026-06-08T17:16:46.595693Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-06-08T17:16:46.595922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-06-08T17:16:46.595955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-06-08T17:16:46.596412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-08T17:16:46.596452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-06-08T17:16:46.596549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-06-08T17:16:46.604105Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-08T17:16:46.605621Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-08T17:16:46.606944Z","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-08T17:16:46.610029Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-08T17:16:46.612295Z","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-08T17:16:46.612752Z","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-08T17:16:46.613293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-06-08T17:16:46.613379Z","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-08T17:16:46.612242Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-08T17:16:46.613818Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-06-08T17:16:46.616118Z","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-08T17:16:46.616217Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-06-08T17:16:46.616296Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-06-08T17:16:46.616363Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-08T17:16:46.616402Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-08T17:16:46.897739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-06-08T17:16:46.897793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-06-08T17:16:46.89782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-06-08T17:16:46.897903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-06-08T17:16:46.897917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-06-08T17:16:46.897928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-06-08T17:16:46.897938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-06-08T17:16:46.898574Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T17:16:46.899341Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-06-08T17:16:46.899367Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T17:16:46.899465Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-08T17:16:46.899841Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-08T17:16:46.899863Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-08T17:16:46.900617Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T17:16:46.900729Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T17:16:46.900817Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-08T17:16:46.902296Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-06-08T17:16:46.90239Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-08T17:16:49.883178Z","caller":"traceutil/trace.go:171","msg":"trace[892727832] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"281.401874ms","start":"2026-06-08T17:16:49.601741Z","end":"2026-06-08T17:16:49.883143Z","steps":["trace[892727832] 'process raft request' (duration: 201.87845ms)","trace[892727832] 'compare' (duration: 79.367504ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T17:16:50.304432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.75548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-08T17:16:50.304608Z","caller":"traceutil/trace.go:171","msg":"trace[766376450] transaction","detail":"{read_only:false; response_revision:143; number_of_response:1; }","duration":"325.844421ms","start":"2026-06-08T17:16:49.978717Z","end":"2026-06-08T17:16:50.304562Z","steps":["trace[766376450] 'process raft request' (duration: 58.45533ms)","trace[766376450] 'compare' (duration: 266.630011ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T17:16:50.304745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:16:49.97868Z","time spent":"326.002902ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":659,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T17:16:50.49829Z","caller":"traceutil/trace.go:171","msg":"trace[2129250319] transaction","detail":"{read_only:false; response_revision:147; number_of_response:1; }","duration":"103.711026ms","start":"2026-06-08T17:16:50.394549Z","end":"2026-06-08T17:16:50.49826Z","steps":["trace[2129250319] 'process raft request' (duration: 69.633457ms)","trace[2129250319] 'compare' (duration: 33.669172ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T17:16:50.685536Z","caller":"traceutil/trace.go:171","msg":"trace[1463339336] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"136.226792ms","start":"2026-06-08T17:16:50.549282Z","end":"2026-06-08T17:16:50.685509Z","steps":["trace[1463339336] 'process raft request' (duration: 63.156509ms)","trace[1463339336] 'compare' (duration: 72.898451ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T17:16:51.076863Z","caller":"traceutil/trace.go:171","msg":"trace[294816090] linearizableReadLoop","detail":"{readStateIndex:161; appliedIndex:160; }","duration":"183.491885ms","start":"2026-06-08T17:16:50.893345Z","end":"2026-06-08T17:16:51.076836Z","steps":["trace[294816090] 'read index received' (duration: 129.668885ms)","trace[294816090] 'applied index is now lower than readState.Index' (duration: 53.82209ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T17:16:51.076983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.616924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T17:16:51.077013Z","caller":"traceutil/trace.go:171","msg":"trace[809532808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:157; }","duration":"183.7199ms","start":"2026-06-08T17:16:50.893285Z","end":"2026-06-08T17:16:51.077005Z","steps":["trace[809532808] 'agreement among raft nodes before linearized reading' (duration: 183.635155ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:16:51.077186Z","caller":"traceutil/trace.go:171","msg":"trace[856427240] transaction","detail":"{read_only:false; response_revision:157; number_of_response:1; }","duration":"198.284932ms","start":"2026-06-08T17:16:50.878667Z","end":"2026-06-08T17:16:51.076952Z","steps":["trace[856427240] 'process raft request' (duration: 144.28568ms)","trace[856427240] 'compare' (duration: 53.764156ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T17:16:51.290359Z","caller":"traceutil/trace.go:171","msg":"trace[366435335] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"207.969762ms","start":"2026-06-08T17:16:51.082369Z","end":"2026-06-08T17:16:51.290339Z","steps":["trace[366435335] 'process raft request' (duration: 207.93546ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:16:51.290944Z","caller":"traceutil/trace.go:171","msg":"trace[1107405095] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"210.09073ms","start":"2026-06-08T17:16:51.080264Z","end":"2026-06-08T17:16:51.290355Z","steps":["trace[1107405095] 'process raft request' (duration: 183.282952ms)","trace[1107405095] 'compare' (duration: 26.645468ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T17:16:51.474839Z","caller":"traceutil/trace.go:171","msg":"trace[769841500] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"103.261274ms","start":"2026-06-08T17:16:51.371543Z","end":"2026-06-08T17:16:51.474804Z","steps":["trace[769841500] 'process raft request' (duration: 56.326413ms)","trace[769841500] 'compare' (duration: 46.686245ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T17:17:06.744967Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.043592892s","expected-duration":"1s"} {"level":"info","ts":"2026-06-08T17:17:06.745327Z","caller":"traceutil/trace.go:171","msg":"trace[1335138090] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"371.033053ms","start":"2026-06-08T17:17:06.374275Z","end":"2026-06-08T17:17:06.745308Z","steps":["trace[1335138090] 'read index received' (duration: 370.846823ms)","trace[1335138090] 'applied index is now lower than readState.Index' (duration: 185.55µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T17:17:06.745423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.134888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T17:17:06.745422Z","caller":"traceutil/trace.go:171","msg":"trace[2144069851] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"1.04409441s","start":"2026-06-08T17:17:05.70129Z","end":"2026-06-08T17:17:06.745384Z","steps":["trace[2144069851] 'process raft request' (duration: 1.04389699s)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:06.745451Z","caller":"traceutil/trace.go:171","msg":"trace[1607716530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"371.205023ms","start":"2026-06-08T17:17:06.374237Z","end":"2026-06-08T17:17:06.745442Z","steps":["trace[1607716530] 'agreement among raft nodes before linearized reading' (duration: 371.141599ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:06.745519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.381412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-08T17:17:06.745532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:06.374222Z","time spent":"371.301788ms","remote":"127.0.0.1:39780","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-06-08T17:17:06.745548Z","caller":"traceutil/trace.go:171","msg":"trace[222996087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"137.454237ms","start":"2026-06-08T17:17:06.608086Z","end":"2026-06-08T17:17:06.74554Z","steps":["trace[222996087] 'agreement among raft nodes before linearized reading' (duration: 137.348911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:06.745547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.340106ms","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-08T17:17:06.745584Z","caller":"traceutil/trace.go:171","msg":"trace[941834645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:418; }","duration":"125.408709ms","start":"2026-06-08T17:17:06.620165Z","end":"2026-06-08T17:17:06.745573Z","steps":["trace[941834645] 'agreement among raft nodes before linearized reading' (duration: 125.329324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:06.745749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:05.701272Z","time spent":"1.044242709s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T17:17:07.120479Z","caller":"traceutil/trace.go:171","msg":"trace[1718453976] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"371.206488ms","start":"2026-06-08T17:17:06.749245Z","end":"2026-06-08T17:17:07.120452Z","steps":["trace[1718453976] 'process raft request' (duration: 371.161826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:07.120695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:06.74923Z","time spent":"371.386058ms","remote":"127.0.0.1:40042","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-06-08T17:17:07.120894Z","caller":"traceutil/trace.go:171","msg":"trace[348420169] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"371.692454ms","start":"2026-06-08T17:17:06.749117Z","end":"2026-06-08T17:17:07.120809Z","steps":["trace[348420169] 'process raft request' (duration: 366.653012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:07.121077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:06.74909Z","time spent":"371.903285ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T17:17:07.392532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.527113ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-08T17:17:07.514153Z","caller":"traceutil/trace.go:171","msg":"trace[1687767490] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"109.074809ms","start":"2026-06-08T17:17:07.40504Z","end":"2026-06-08T17:17:07.514115Z","steps":["trace[1687767490] 'process raft request' (duration: 108.867298ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:13.372322Z","caller":"traceutil/trace.go:171","msg":"trace[1436502722] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"186.653328ms","start":"2026-06-08T17:17:13.185643Z","end":"2026-06-08T17:17:13.372296Z","steps":["trace[1436502722] 'read index received' (duration: 186.321501ms)","trace[1436502722] 'applied index is now lower than readState.Index' (duration: 330.717µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T17:17:13.37252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.803246ms","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-08T17:17:13.372597Z","caller":"traceutil/trace.go:171","msg":"trace[530609183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:433; }","duration":"186.966885ms","start":"2026-06-08T17:17:13.185617Z","end":"2026-06-08T17:17:13.372584Z","steps":["trace[530609183] 'agreement among raft nodes before linearized reading' (duration: 186.781445ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:13.372852Z","caller":"traceutil/trace.go:171","msg":"trace[2018325735] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"187.434688ms","start":"2026-06-08T17:17:13.185275Z","end":"2026-06-08T17:17:13.37271Z","steps":["trace[2018325735] 'process raft request' (duration: 186.858729ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:21.808473Z","caller":"traceutil/trace.go:171","msg":"trace[196910876] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"130.882102ms","start":"2026-06-08T17:17:21.677571Z","end":"2026-06-08T17:17:21.808453Z","steps":["trace[196910876] 'process raft request' (duration: 130.738626ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:21.808709Z","caller":"traceutil/trace.go:171","msg":"trace[1923928414] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:471; }","duration":"121.927239ms","start":"2026-06-08T17:17:21.68677Z","end":"2026-06-08T17:17:21.808697Z","steps":["trace[1923928414] 'read index received' (duration: 121.455767ms)","trace[1923928414] 'applied index is now lower than readState.Index' (duration: 470.542µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T17:17:21.808896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.159389ms","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-06-08T17:17:21.808962Z","caller":"traceutil/trace.go:171","msg":"trace[1139682278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:453; }","duration":"122.269504ms","start":"2026-06-08T17:17:21.686676Z","end":"2026-06-08T17:17:21.808946Z","steps":["trace[1139682278] 'agreement among raft nodes before linearized reading' (duration: 122.157959ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:23.463234Z","caller":"traceutil/trace.go:171","msg":"trace[1498836835] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"525.145355ms","start":"2026-06-08T17:17:22.938067Z","end":"2026-06-08T17:17:23.463212Z","steps":["trace[1498836835] 'process raft request' (duration: 524.776288ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:23.463368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:22.938051Z","time spent":"525.24975ms","remote":"127.0.0.1:40042","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-06-08T17:17:23.79326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.820403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-08T17:17:23.793447Z","caller":"traceutil/trace.go:171","msg":"trace[1447816784] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"168.592952ms","start":"2026-06-08T17:17:23.624807Z","end":"2026-06-08T17:17:23.7934Z","steps":["trace[1447816784] 'process raft request' (duration: 54.515338ms)","trace[1447816784] 'compare' (duration: 113.349841ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T17:17:23.798331Z","caller":"traceutil/trace.go:171","msg":"trace[1593231762] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"169.570956ms","start":"2026-06-08T17:17:23.62874Z","end":"2026-06-08T17:17:23.798311Z","steps":["trace[1593231762] 'process raft request' (duration: 169.42508ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:30.087587Z","caller":"traceutil/trace.go:171","msg":"trace[1743977603] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"108.215315ms","start":"2026-06-08T17:17:29.979347Z","end":"2026-06-08T17:17:30.087563Z","steps":["trace[1743977603] 'process raft request' (duration: 108.08377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:37.875647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765934318160096,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-08T17:17:38.256511Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.478910574s","expected-duration":"1s"} {"level":"info","ts":"2026-06-08T17:17:38.256932Z","caller":"traceutil/trace.go:171","msg":"trace[1582776504] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.479399192s","start":"2026-06-08T17:17:36.777499Z","end":"2026-06-08T17:17:38.256899Z","steps":["trace[1582776504] 'process raft request' (duration: 1.479250786s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:38.257094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:36.777464Z","time spent":"1.479561679s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T17:17:38.401186Z","caller":"traceutil/trace.go:171","msg":"trace[763148074] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.621951671s","start":"2026-06-08T17:17:36.779194Z","end":"2026-06-08T17:17:38.401146Z","steps":["trace[763148074] 'process raft request' (duration: 1.621746243s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:38.401364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:36.779176Z","time spent":"1.622120698s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T17:17:38.401384Z","caller":"traceutil/trace.go:171","msg":"trace[1549761733] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.617760535s","start":"2026-06-08T17:17:36.783581Z","end":"2026-06-08T17:17:38.401341Z","steps":["trace[1549761733] 'process raft request' (duration: 1.617472663s)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:38.401455Z","caller":"traceutil/trace.go:171","msg":"trace[875223039] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.606117863s","start":"2026-06-08T17:17:36.795272Z","end":"2026-06-08T17:17:38.40139Z","steps":["trace[875223039] 'process raft request' (duration: 1.605853163s)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:38.401541Z","caller":"traceutil/trace.go:171","msg":"trace[2121628467] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.598312414s","start":"2026-06-08T17:17:36.803217Z","end":"2026-06-08T17:17:38.401529Z","steps":["trace[2121628467] 'process raft request' (duration: 1.598084285s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:38.401596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:36.803206Z","time spent":"1.598365615s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T17:17:38.401673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:36.783563Z","time spent":"1.617967043s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T17:17:38.401702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:36.795255Z","time spent":"1.60631635s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T17:17:38.401769Z","caller":"traceutil/trace.go:171","msg":"trace[293974825] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.598322594s","start":"2026-06-08T17:17:36.803435Z","end":"2026-06-08T17:17:38.401758Z","steps":["trace[293974825] 'process raft request' (duration: 1.597954849s)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:38.401799Z","caller":"traceutil/trace.go:171","msg":"trace[1077517547] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.604512689s","start":"2026-06-08T17:17:36.79721Z","end":"2026-06-08T17:17:38.401723Z","steps":["trace[1077517547] 'process raft request' (duration: 1.604044781s)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:38.401876Z","caller":"traceutil/trace.go:171","msg":"trace[1980617907] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.598608365s","start":"2026-06-08T17:17:36.803256Z","end":"2026-06-08T17:17:38.401864Z","steps":["trace[1980617907] 'process raft request' (duration: 1.598084104s)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:38.401898Z","caller":"traceutil/trace.go:171","msg":"trace[471386307] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.575915776s","start":"2026-06-08T17:17:36.825958Z","end":"2026-06-08T17:17:38.401873Z","steps":["trace[471386307] 'process raft request' (duration: 1.575588083s)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:38.401856Z","caller":"traceutil/trace.go:171","msg":"trace[1432152104] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.595829785s","start":"2026-06-08T17:17:36.806012Z","end":"2026-06-08T17:17:38.401842Z","steps":["trace[1432152104] 'process raft request' (duration: 1.595428289s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:38.401958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:36.825938Z","time spent":"1.575992738s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T17:17:38.401955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:36.803251Z","time spent":"1.598673998s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T17:17:38.401958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:36.797201Z","time spent":"1.604675665s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T17:17:38.401969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:36.805993Z","time spent":"1.59594627s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T17:17:38.40183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:36.803308Z","time spent":"1.59849123s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T17:17:38.402055Z","caller":"traceutil/trace.go:171","msg":"trace[873853024] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.553936275s","start":"2026-06-08T17:17:36.848107Z","end":"2026-06-08T17:17:38.402043Z","steps":["trace[873853024] 'process raft request' (duration: 1.553502158s)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:38.402076Z","caller":"traceutil/trace.go:171","msg":"trace[1982618942] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.466679617s","start":"2026-06-08T17:17:36.935386Z","end":"2026-06-08T17:17:38.402066Z","steps":["trace[1982618942] 'process raft request' (duration: 1.466380915s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:38.402095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:36.848086Z","time spent":"1.553990347s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-08T17:17:38.402117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:36.935356Z","time spent":"1.466741679s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-08T17:17:38.410619Z","caller":"traceutil/trace.go:171","msg":"trace[606495283] transaction","detail":"{read_only:false; number_of_response:1; response_revision:566; }","duration":"143.977545ms","start":"2026-06-08T17:17:38.266623Z","end":"2026-06-08T17:17:38.410601Z","steps":["trace[606495283] 'process raft request' (duration: 143.933144ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:38.410649Z","caller":"traceutil/trace.go:171","msg":"trace[276046820] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"146.68126ms","start":"2026-06-08T17:17:38.263949Z","end":"2026-06-08T17:17:38.41063Z","steps":["trace[276046820] 'process raft request' (duration: 146.450002ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:38.4107Z","caller":"traceutil/trace.go:171","msg":"trace[840366656] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"144.992055ms","start":"2026-06-08T17:17:38.265693Z","end":"2026-06-08T17:17:38.410685Z","steps":["trace[840366656] 'process raft request' (duration: 144.809828ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:38.410848Z","caller":"traceutil/trace.go:171","msg":"trace[36447432] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:574; }","duration":"1.035748325s","start":"2026-06-08T17:17:37.375088Z","end":"2026-06-08T17:17:38.410837Z","steps":["trace[36447432] 'read index received' (duration: 881.75019ms)","trace[36447432] 'applied index is now lower than readState.Index' (duration: 153.996794ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T17:17:38.410945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035840248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T17:17:38.410973Z","caller":"traceutil/trace.go:171","msg":"trace[399888566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"1.035916891s","start":"2026-06-08T17:17:37.375047Z","end":"2026-06-08T17:17:38.410964Z","steps":["trace[399888566] 'agreement among raft nodes before linearized reading' (duration: 1.035856769s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:38.410998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:37.375029Z","time spent":"1.035962363s","remote":"127.0.0.1:39780","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-06-08T17:17:38.411135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.676566ms","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-08T17:17:38.411159Z","caller":"traceutil/trace.go:171","msg":"trace[681946406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"751.734238ms","start":"2026-06-08T17:17:37.659417Z","end":"2026-06-08T17:17:38.411151Z","steps":["trace[681946406] 'agreement among raft nodes before linearized reading' (duration: 751.687876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:38.41118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:37.659399Z","time spent":"751.77655ms","remote":"127.0.0.1:40042","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-06-08T17:17:38.411417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.200832ms","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-08T17:17:38.41147Z","caller":"traceutil/trace.go:171","msg":"trace[1875091724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:566; }","duration":"296.299576ms","start":"2026-06-08T17:17:38.115161Z","end":"2026-06-08T17:17:38.41146Z","steps":["trace[1875091724] 'agreement among raft nodes before linearized reading' (duration: 296.15802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:38.411523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.887665ms","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-08T17:17:38.4116Z","caller":"traceutil/trace.go:171","msg":"trace[756232589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"710.985378ms","start":"2026-06-08T17:17:37.700586Z","end":"2026-06-08T17:17:38.411572Z","steps":["trace[756232589] 'agreement among raft nodes before linearized reading' (duration: 710.906945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:38.411639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:37.700566Z","time spent":"711.063981ms","remote":"127.0.0.1:40042","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":"warn","ts":"2026-06-08T17:17:38.411453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.39723ms","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-06-08T17:17:38.411768Z","caller":"traceutil/trace.go:171","msg":"trace[1185564911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:566; }","duration":"704.737903ms","start":"2026-06-08T17:17:37.707023Z","end":"2026-06-08T17:17:38.411761Z","steps":["trace[1185564911] 'agreement among raft nodes before linearized reading' (duration: 704.316687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:38.411796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:37.707011Z","time spent":"704.776725ms","remote":"127.0.0.1:40042","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-06-08T17:17:44.755973Z","caller":"traceutil/trace.go:171","msg":"trace[1285511040] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"833.012378ms","start":"2026-06-08T17:17:43.922931Z","end":"2026-06-08T17:17:44.755944Z","steps":["trace[1285511040] 'process raft request' (duration: 832.468348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:44.756177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:43.922893Z","time spent":"833.157002ms","remote":"127.0.0.1:39888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T17:17:44.757309Z","caller":"traceutil/trace.go:171","msg":"trace[1616458628] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:680; }","duration":"263.052324ms","start":"2026-06-08T17:17:44.49423Z","end":"2026-06-08T17:17:44.757283Z","steps":["trace[1616458628] 'read index received' (duration: 261.03603ms)","trace[1616458628] 'applied index is now lower than readState.Index' (duration: 2.015374ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T17:17:44.757353Z","caller":"traceutil/trace.go:171","msg":"trace[1772728152] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"393.75178ms","start":"2026-06-08T17:17:44.36358Z","end":"2026-06-08T17:17:44.757332Z","steps":["trace[1772728152] 'process raft request' (duration: 393.582053ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:44.757443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:44.363547Z","time spent":"393.852114ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T17:17:44.757609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.380696ms","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-06-08T17:17:44.757651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.60586ms","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-06-08T17:17:44.757666Z","caller":"traceutil/trace.go:171","msg":"trace[1180414453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"263.454178ms","start":"2026-06-08T17:17:44.494198Z","end":"2026-06-08T17:17:44.757652Z","steps":["trace[1180414453] 'agreement among raft nodes before linearized reading' (duration: 263.23704ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:44.757687Z","caller":"traceutil/trace.go:171","msg":"trace[51956585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"261.660222ms","start":"2026-06-08T17:17:44.496015Z","end":"2026-06-08T17:17:44.757675Z","steps":["trace[51956585] 'agreement among raft nodes before linearized reading' (duration: 261.526687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:44.757849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.780396ms","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-06-08T17:17:44.757879Z","caller":"traceutil/trace.go:171","msg":"trace[1355010163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"258.808767ms","start":"2026-06-08T17:17:44.499061Z","end":"2026-06-08T17:17:44.75787Z","steps":["trace[1355010163] 'agreement among raft nodes before linearized reading' (duration: 258.717784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:44.757969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.209762ms","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-08T17:17:44.758061Z","caller":"traceutil/trace.go:171","msg":"trace[1142425003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"259.326727ms","start":"2026-06-08T17:17:44.498712Z","end":"2026-06-08T17:17:44.758039Z","steps":["trace[1142425003] 'agreement among raft nodes before linearized reading' (duration: 259.081468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:45.874629Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765934318160279,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-08T17:17:45.888399Z","caller":"traceutil/trace.go:171","msg":"trace[1915789566] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"514.587478ms","start":"2026-06-08T17:17:45.373787Z","end":"2026-06-08T17:17:45.888375Z","steps":["trace[1915789566] 'read index received' (duration: 513.836201ms)","trace[1915789566] 'applied index is now lower than readState.Index' (duration: 750.297µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T17:17:45.888464Z","caller":"traceutil/trace.go:171","msg":"trace[1674176646] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"621.631979ms","start":"2026-06-08T17:17:45.266802Z","end":"2026-06-08T17:17:45.888434Z","steps":["trace[1674176646] 'process raft request' (duration: 621.064079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:45.888613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:45.266775Z","time spent":"621.765984ms","remote":"127.0.0.1:39888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T17:17:45.888811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.844489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-knj7c\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-08T17:17:45.888877Z","caller":"traceutil/trace.go:171","msg":"trace[1099676721] range","detail":"{range_begin:/registry/pods/kube-system/cilium-knj7c; range_end:; response_count:1; response_revision:664; }","duration":"136.001015ms","start":"2026-06-08T17:17:45.75286Z","end":"2026-06-08T17:17:45.888861Z","steps":["trace[1099676721] 'agreement among raft nodes before linearized reading' (duration: 135.796437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:45.888813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.979663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T17:17:45.889052Z","caller":"traceutil/trace.go:171","msg":"trace[1636096378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"515.263033ms","start":"2026-06-08T17:17:45.373735Z","end":"2026-06-08T17:17:45.888998Z","steps":["trace[1636096378] 'agreement among raft nodes before linearized reading' (duration: 514.815726ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:45.889145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:45.373715Z","time spent":"515.421069ms","remote":"127.0.0.1:39776","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-06-08T17:17:46.665054Z","caller":"traceutil/trace.go:171","msg":"trace[1312978819] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"762.22562ms","start":"2026-06-08T17:17:45.902796Z","end":"2026-06-08T17:17:46.665022Z","steps":["trace[1312978819] 'process raft request' (duration: 762.019263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:46.665265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:45.902778Z","time spent":"762.365575ms","remote":"127.0.0.1:39960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T17:17:46.674662Z","caller":"traceutil/trace.go:171","msg":"trace[725546887] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:691; }","duration":"298.712829ms","start":"2026-06-08T17:17:46.375925Z","end":"2026-06-08T17:17:46.674638Z","steps":["trace[725546887] 'read index received' (duration: 288.926067ms)","trace[725546887] 'applied index is now lower than readState.Index' (duration: 9.785752ms)"],"step_count":2} {"level":"warn","ts":"2026-06-08T17:17:46.67482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.879416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T17:17:46.674861Z","caller":"traceutil/trace.go:171","msg":"trace[1482639838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"298.992729ms","start":"2026-06-08T17:17:46.375858Z","end":"2026-06-08T17:17:46.674851Z","steps":["trace[1482639838] 'agreement among raft nodes before linearized reading' (duration: 298.876295ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:46.675171Z","caller":"traceutil/trace.go:171","msg":"trace[1653141047] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"734.236028ms","start":"2026-06-08T17:17:45.940924Z","end":"2026-06-08T17:17:46.67516Z","steps":["trace[1653141047] 'process raft request' (duration: 733.588695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:46.675249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:45.940901Z","time spent":"734.303471ms","remote":"127.0.0.1:40294","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-06-08T17:17:47.876539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765934318160291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-08T17:17:48.377546Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765934318160291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-08T17:17:48.878633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765934318160291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-08T17:17:49.080206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.307284333s","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-08T17:17:49.080271Z","caller":"traceutil/trace.go:171","msg":"trace[685815205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:666; }","duration":"2.307397018s","start":"2026-06-08T17:17:46.772857Z","end":"2026-06-08T17:17:49.080254Z","steps":["trace[685815205] 'range keys from in-memory index tree' (duration: 2.307120348s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:49.080233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.562661ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-08T17:17:49.080301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:46.772834Z","time spent":"2.30746043s","remote":"127.0.0.1:40042","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-06-08T17:17:49.080319Z","caller":"traceutil/trace.go:171","msg":"trace[1961875348] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:666; }","duration":"417.653634ms","start":"2026-06-08T17:17:48.662651Z","end":"2026-06-08T17:17:49.080305Z","steps":["trace[1961875348] 'range keys from in-memory index tree' (duration: 417.550271ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:49.080515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.307564153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-08T17:17:49.080555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.304211553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-08T17:17:49.080562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.307225771s","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-08T17:17:49.080576Z","caller":"traceutil/trace.go:171","msg":"trace[1336074007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:666; }","duration":"2.307664236s","start":"2026-06-08T17:17:46.772897Z","end":"2026-06-08T17:17:49.080562Z","steps":["trace[1336074007] 'range keys from in-memory index tree' (duration: 2.307410608s)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:49.080589Z","caller":"traceutil/trace.go:171","msg":"trace[1316917115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:666; }","duration":"2.307271403s","start":"2026-06-08T17:17:46.77331Z","end":"2026-06-08T17:17:49.080582Z","steps":["trace[1316917115] 'range keys from in-memory index tree' (duration: 2.307149908s)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:49.080588Z","caller":"traceutil/trace.go:171","msg":"trace[1895769781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:666; }","duration":"2.304256224s","start":"2026-06-08T17:17:46.776322Z","end":"2026-06-08T17:17:49.080578Z","steps":["trace[1895769781] 'range keys from in-memory index tree' (duration: 2.304119569s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:49.080613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:46.773299Z","time spent":"2.307310584s","remote":"127.0.0.1:40042","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-06-08T17:17:49.080617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:46.772877Z","time spent":"2.307732059s","remote":"127.0.0.1:40042","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-08T17:17:49.080621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:46.776315Z","time spent":"2.304299715s","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-08T17:17:49.135843Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.070457588s","expected-duration":"1s"} {"level":"info","ts":"2026-06-08T17:17:49.13753Z","caller":"traceutil/trace.go:171","msg":"trace[801846774] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"2.073497046s","start":"2026-06-08T17:17:47.06398Z","end":"2026-06-08T17:17:49.137477Z","steps":["trace[801846774] 'process raft request' (duration: 2.072326555s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:49.137669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:47.06395Z","time spent":"2.073648401s","remote":"127.0.0.1:39888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T17:17:49.191226Z","caller":"traceutil/trace.go:171","msg":"trace[115281064] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"1.814981055s","start":"2026-06-08T17:17:47.376205Z","end":"2026-06-08T17:17:49.191187Z","steps":["trace[115281064] 'read index received' (duration: 1.759901245s)","trace[115281064] 'applied index is now lower than readState.Index' (duration: 55.07911ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T17:17:49.191274Z","caller":"traceutil/trace.go:171","msg":"trace[1478299487] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"106.227803ms","start":"2026-06-08T17:17:49.085013Z","end":"2026-06-08T17:17:49.191241Z","steps":["trace[1478299487] 'process raft request' (duration: 106.089298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:49.191316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.81510697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T17:17:49.191341Z","caller":"traceutil/trace.go:171","msg":"trace[1087193585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:671; }","duration":"1.815183872s","start":"2026-06-08T17:17:47.37615Z","end":"2026-06-08T17:17:49.191334Z","steps":["trace[1087193585] 'agreement among raft nodes before linearized reading' (duration: 1.81512283s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:17:49.191358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:17:47.37613Z","time spent":"1.815224944s","remote":"127.0.0.1:39780","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-06-08T17:17:49.191342Z","caller":"traceutil/trace.go:171","msg":"trace[762556513] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"105.716014ms","start":"2026-06-08T17:17:49.085587Z","end":"2026-06-08T17:17:49.191303Z","steps":["trace[762556513] 'process raft request' (duration: 105.548698ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:49.191341Z","caller":"traceutil/trace.go:171","msg":"trace[1237964959] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"105.532628ms","start":"2026-06-08T17:17:49.085776Z","end":"2026-06-08T17:17:49.191309Z","steps":["trace[1237964959] 'process raft request' (duration: 105.387493ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:49.191621Z","caller":"traceutil/trace.go:171","msg":"trace[812618081] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"106.667908ms","start":"2026-06-08T17:17:49.08466Z","end":"2026-06-08T17:17:49.191327Z","steps":["trace[812618081] 'process raft request' (duration: 106.222642ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:49.646868Z","caller":"traceutil/trace.go:171","msg":"trace[1146773434] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"121.603122ms","start":"2026-06-08T17:17:49.52524Z","end":"2026-06-08T17:17:49.646843Z","steps":["trace[1146773434] 'process raft request' (duration: 121.460777ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:51.27935Z","caller":"traceutil/trace.go:171","msg":"trace[1422705011] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:730; }","duration":"137.810913ms","start":"2026-06-08T17:17:51.141523Z","end":"2026-06-08T17:17:51.279334Z","steps":["trace[1422705011] 'read index received' (duration: 137.74146ms)","trace[1422705011] 'applied index is now lower than readState.Index' (duration: 68.913µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T17:17:51.279508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.973039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-08T17:17:51.279532Z","caller":"traceutil/trace.go:171","msg":"trace[2142667112] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:703; }","duration":"138.073872ms","start":"2026-06-08T17:17:51.141451Z","end":"2026-06-08T17:17:51.279525Z","steps":["trace[2142667112] 'agreement among raft nodes before linearized reading' (duration: 137.980919ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:17:51.279671Z","caller":"traceutil/trace.go:171","msg":"trace[1400107550] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"185.844516ms","start":"2026-06-08T17:17:51.093582Z","end":"2026-06-08T17:17:51.279426Z","steps":["trace[1400107550] 'process raft request' (duration: 185.66803ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:18:01.514874Z","caller":"traceutil/trace.go:171","msg":"trace[931236639] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"137.249612ms","start":"2026-06-08T17:18:01.377583Z","end":"2026-06-08T17:18:01.514833Z","steps":["trace[931236639] 'process raft request' (duration: 133.592146ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:18:01.514916Z","caller":"traceutil/trace.go:171","msg":"trace[1756316707] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"137.263332ms","start":"2026-06-08T17:18:01.377629Z","end":"2026-06-08T17:18:01.514892Z","steps":["trace[1756316707] 'process raft request' (duration: 137.087316ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:18:01.514856Z","caller":"traceutil/trace.go:171","msg":"trace[469836424] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"136.620742ms","start":"2026-06-08T17:18:01.37818Z","end":"2026-06-08T17:18:01.514801Z","steps":["trace[469836424] 'process raft request' (duration: 136.580531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:18:05.230438Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.330208257s","expected-duration":"1s"} {"level":"info","ts":"2026-06-08T17:18:05.230842Z","caller":"traceutil/trace.go:171","msg":"trace[2093212737] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:864; }","duration":"446.310155ms","start":"2026-06-08T17:18:04.784476Z","end":"2026-06-08T17:18:05.230786Z","steps":["trace[2093212737] 'read index received' (duration: 446.294655ms)","trace[2093212737] 'applied index is now lower than readState.Index' (duration: 13.23µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T17:18:05.230858Z","caller":"traceutil/trace.go:171","msg":"trace[1869598278] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"1.330684972s","start":"2026-06-08T17:18:03.900149Z","end":"2026-06-08T17:18:05.230834Z","steps":["trace[1869598278] 'process raft request' (duration: 1.330494486s)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:18:05.231089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.605145ms","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-06-08T17:18:05.231144Z","caller":"traceutil/trace.go:171","msg":"trace[1551146347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:831; }","duration":"446.715078ms","start":"2026-06-08T17:18:04.784416Z","end":"2026-06-08T17:18:05.231131Z","steps":["trace[1551146347] 'agreement among raft nodes before linearized reading' (duration: 446.535182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:18:05.231135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:18:03.900127Z","time spent":"1.330871398s","remote":"127.0.0.1:39960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T17:18:05.231172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:18:04.784384Z","time spent":"446.78284ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-08T17:18:05.623933Z","caller":"traceutil/trace.go:171","msg":"trace[1638359896] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"626.526806ms","start":"2026-06-08T17:18:04.997384Z","end":"2026-06-08T17:18:05.623911Z","steps":["trace[1638359896] 'process raft request' (duration: 626.386902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:18:05.624079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:18:04.99736Z","time spent":"626.64503ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T17:18:05.625312Z","caller":"traceutil/trace.go:171","msg":"trace[1556663713] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"250.078697ms","start":"2026-06-08T17:18:05.375205Z","end":"2026-06-08T17:18:05.625283Z","steps":["trace[1556663713] 'read index received' (duration: 248.763797ms)","trace[1556663713] 'applied index is now lower than readState.Index' (duration: 1.31399ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T17:18:05.625775Z","caller":"traceutil/trace.go:171","msg":"trace[568810875] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"390.275158ms","start":"2026-06-08T17:18:05.235475Z","end":"2026-06-08T17:18:05.62575Z","steps":["trace[568810875] 'process raft request' (duration: 389.700251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:18:05.626179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:18:05.23545Z","time spent":"390.386092ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-08T17:18:05.626236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.988847ms","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-06-08T17:18:05.626371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.170091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-08T17:18:05.626442Z","caller":"traceutil/trace.go:171","msg":"trace[1282536971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:833; }","duration":"237.556604ms","start":"2026-06-08T17:18:05.388863Z","end":"2026-06-08T17:18:05.62642Z","steps":["trace[1282536971] 'agreement among raft nodes before linearized reading' (duration: 236.76039ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:18:05.626552Z","caller":"traceutil/trace.go:171","msg":"trace[818833129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:833; }","duration":"251.385607ms","start":"2026-06-08T17:18:05.375154Z","end":"2026-06-08T17:18:05.62654Z","steps":["trace[818833129] 'agreement among raft nodes before linearized reading' (duration: 251.120369ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:18:50.066199Z","caller":"traceutil/trace.go:171","msg":"trace[245782685] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"103.729235ms","start":"2026-06-08T17:18:49.962434Z","end":"2026-06-08T17:18:50.066163Z","steps":["trace[245782685] 'process raft request' (duration: 103.433439ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:18:50.067442Z","caller":"traceutil/trace.go:171","msg":"trace[1972468049] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"101.438933ms","start":"2026-06-08T17:18:49.965985Z","end":"2026-06-08T17:18:50.067424Z","steps":["trace[1972468049] 'process raft request' (duration: 101.142546ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:19:00.260417Z","caller":"traceutil/trace.go:171","msg":"trace[417523604] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"118.252035ms","start":"2026-06-08T17:19:00.142139Z","end":"2026-06-08T17:19:00.260391Z","steps":["trace[417523604] 'process raft request' (duration: 108.413144ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:19:20.456156Z","caller":"traceutil/trace.go:171","msg":"trace[1345473227] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"140.50309ms","start":"2026-06-08T17:19:20.315624Z","end":"2026-06-08T17:19:20.456127Z","steps":["trace[1345473227] 'read index received' (duration: 140.339357ms)","trace[1345473227] 'applied index is now lower than readState.Index' (duration: 162.603µs)"],"step_count":2} {"level":"info","ts":"2026-06-08T17:19:20.456204Z","caller":"traceutil/trace.go:171","msg":"trace[125307953] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"163.197077ms","start":"2026-06-08T17:19:20.292966Z","end":"2026-06-08T17:19:20.456163Z","steps":["trace[125307953] 'process raft request' (duration: 162.988823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:19:20.456392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.740915ms","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-06-08T17:19:20.45643Z","caller":"traceutil/trace.go:171","msg":"trace[95996104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1208; }","duration":"140.820427ms","start":"2026-06-08T17:19:20.3156Z","end":"2026-06-08T17:19:20.45642Z","steps":["trace[95996104] 'agreement among raft nodes before linearized reading' (duration: 140.666124ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:19:22.952918Z","caller":"traceutil/trace.go:171","msg":"trace[43144840] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"406.845485ms","start":"2026-06-08T17:19:22.546043Z","end":"2026-06-08T17:19:22.952889Z","steps":["trace[43144840] 'process raft request' (duration: 406.63019ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:19:22.953127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:19:22.546026Z","time spent":"406.992237ms","remote":"127.0.0.1:40042","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-06-08T17:19:23.037388Z","caller":"traceutil/trace.go:171","msg":"trace[2139159105] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1267; }","duration":"218.391036ms","start":"2026-06-08T17:19:22.818968Z","end":"2026-06-08T17:19:23.037359Z","steps":["trace[2139159105] 'read index received' (duration: 133.669447ms)","trace[2139159105] 'applied index is now lower than readState.Index' (duration: 84.720749ms)"],"step_count":2} {"level":"info","ts":"2026-06-08T17:19:23.037546Z","caller":"traceutil/trace.go:171","msg":"trace[1357394769] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"490.541474ms","start":"2026-06-08T17:19:22.54697Z","end":"2026-06-08T17:19:23.037511Z","steps":["trace[1357394769] 'process raft request' (duration: 490.218958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:19:23.037619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.619831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"info","ts":"2026-06-08T17:19:23.037648Z","caller":"traceutil/trace.go:171","msg":"trace[2066944758] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1219; }","duration":"218.718533ms","start":"2026-06-08T17:19:22.818922Z","end":"2026-06-08T17:19:23.037641Z","steps":["trace[2066944758] 'agreement among raft nodes before linearized reading' (duration: 218.5484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:19:23.03775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-08T17:19:22.54696Z","time spent":"490.679157ms","remote":"127.0.0.1:40042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-08T17:19:25.139493Z","caller":"traceutil/trace.go:171","msg":"trace[146454223] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"172.317926ms","start":"2026-06-08T17:19:24.967131Z","end":"2026-06-08T17:19:25.139449Z","steps":["trace[146454223] 'process raft request' (duration: 172.0227ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:19:28.770842Z","caller":"traceutil/trace.go:171","msg":"trace[1942638727] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"128.94943ms","start":"2026-06-08T17:19:28.641867Z","end":"2026-06-08T17:19:28.770816Z","steps":["trace[1942638727] 'read index received' (duration: 128.792377ms)","trace[1942638727] 'applied index is now lower than readState.Index' (duration: 155.353µs)"],"step_count":2} {"level":"warn","ts":"2026-06-08T17:19:28.771008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.116243ms","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-08T17:19:28.77103Z","caller":"traceutil/trace.go:171","msg":"trace[279352687] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1235; }","duration":"129.188135ms","start":"2026-06-08T17:19:28.641834Z","end":"2026-06-08T17:19:28.771022Z","steps":["trace[279352687] 'agreement among raft nodes before linearized reading' (duration: 129.075063ms)"],"step_count":1} {"level":"info","ts":"2026-06-08T17:19:28.771223Z","caller":"traceutil/trace.go:171","msg":"trace[1983491762] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"136.635097ms","start":"2026-06-08T17:19:28.634476Z","end":"2026-06-08T17:19:28.771111Z","steps":["trace[1983491762] 'process raft request' (duration: 136.156798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-08T17:20:30.863254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.541878ms","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-06-08T17:20:30.863351Z","caller":"traceutil/trace.go:171","msg":"trace[161337023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1475; }","duration":"160.693561ms","start":"2026-06-08T17:20:30.702638Z","end":"2026-06-08T17:20:30.863331Z","steps":["trace[161337023] 'range keys from in-memory index tree' (duration: 160.361565ms)"],"step_count":1}