{"level":"warn","ts":"2026-08-22T14:30:06.697886Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:30:06.698112Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-08-22T14:30:06.698332Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T14:30:06.698404Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-08-22T14:30:06.698474Z","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-08-22T14:30:06.699402Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-08-22T14:30:06.699534Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-08-22T14:30:06.860316Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"160.473638ms"} {"level":"info","ts":"2026-08-22T14:30:07.391738Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-08-22T14:30:07.391947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T14:30:07.392033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-08-22T14:30:07.392087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T14:30:07.392142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-08-22T14:30:07.392228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-08-22T14:30:07.515791Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T14:30:07.531517Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T14:30:07.565666Z","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-08-22T14:30:07.608006Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T14:30:07.608339Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T14:30:07.608371Z","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-08-22T14:30:07.60853Z","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-08-22T14:30:07.608658Z","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-08-22T14:30:07.612324Z","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-08-22T14:30:07.612478Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-08-22T14:30:07.612554Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-08-22T14:30:07.612655Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T14:30:07.612725Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T14:30:07.641205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-08-22T14:30:07.641342Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-08-22T14:30:08.092908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T14:30:08.092957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T14:30:08.092999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-08-22T14:30:08.093018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-08-22T14:30:08.093026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-08-22T14:30:08.093042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-08-22T14:30:08.093052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-08-22T14:30:08.170245Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T14:30:08.170257Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:30:08.170321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:30:08.17052Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:30:08.170587Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T14:30:08.170652Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T14:30:08.174485Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T14:30:08.175309Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:30:08.175498Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:30:08.175544Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:30:08.176174Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-08-22T14:30:09.729957Z","caller":"traceutil/trace.go:171","msg":"trace[1920839236] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"126.120823ms","start":"2026-08-22T14:30:09.603814Z","end":"2026-08-22T14:30:09.729934Z","steps":["trace[1920839236] 'process raft request' (duration: 125.949827ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:09.904074Z","caller":"traceutil/trace.go:171","msg":"trace[1230996807] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"294.282299ms","start":"2026-08-22T14:30:09.609764Z","end":"2026-08-22T14:30:09.904047Z","steps":["trace[1230996807] 'read index received' (duration: 120.002483ms)","trace[1230996807] 'applied index is now lower than readState.Index' (duration: 174.278696ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:09.904108Z","caller":"traceutil/trace.go:171","msg":"trace[1677080938] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"298.785306ms","start":"2026-08-22T14:30:09.605303Z","end":"2026-08-22T14:30:09.904089Z","steps":["trace[1677080938] 'process raft request' (duration: 298.354205ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:09.904183Z","caller":"traceutil/trace.go:171","msg":"trace[1282716129] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"216.250629ms","start":"2026-08-22T14:30:09.687923Z","end":"2026-08-22T14:30:09.904174Z","steps":["trace[1282716129] 'process raft request' (duration: 215.914726ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:09.904175Z","caller":"traceutil/trace.go:171","msg":"trace[1412814910] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"224.220136ms","start":"2026-08-22T14:30:09.679922Z","end":"2026-08-22T14:30:09.904143Z","steps":["trace[1412814910] 'process raft request' (duration: 223.872533ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:09.904334Z","caller":"traceutil/trace.go:171","msg":"trace[1586579763] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"208.026247ms","start":"2026-08-22T14:30:09.696292Z","end":"2026-08-22T14:30:09.904318Z","steps":["trace[1586579763] 'process raft request' (duration: 207.674424ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:09.904513Z","caller":"traceutil/trace.go:171","msg":"trace[2003866812] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"208.451687ms","start":"2026-08-22T14:30:09.696034Z","end":"2026-08-22T14:30:09.904486Z","steps":["trace[2003866812] 'process raft request' (duration: 207.86211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:09.904585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.790677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:30:09.904671Z","caller":"traceutil/trace.go:171","msg":"trace[3890540] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"294.933984ms","start":"2026-08-22T14:30:09.60972Z","end":"2026-08-22T14:30:09.904654Z","steps":["trace[3890540] 'agreement among raft nodes before linearized reading' (duration: 294.66963ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:09.904833Z","caller":"traceutil/trace.go:171","msg":"trace[1129725522] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"208.610013ms","start":"2026-08-22T14:30:09.696174Z","end":"2026-08-22T14:30:09.904784Z","steps":["trace[1129725522] 'process raft request' (duration: 207.747352ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:09.904885Z","caller":"traceutil/trace.go:171","msg":"trace[785236425] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"210.4587ms","start":"2026-08-22T14:30:09.694407Z","end":"2026-08-22T14:30:09.904866Z","steps":["trace[785236425] 'process raft request' (duration: 209.461612ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:09.904934Z","caller":"traceutil/trace.go:171","msg":"trace[571939748] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"207.573686ms","start":"2026-08-22T14:30:09.697345Z","end":"2026-08-22T14:30:09.904919Z","steps":["trace[571939748] 'process raft request' (duration: 206.654666ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:09.904963Z","caller":"traceutil/trace.go:171","msg":"trace[1757172418] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"208.70559ms","start":"2026-08-22T14:30:09.696231Z","end":"2026-08-22T14:30:09.904937Z","steps":["trace[1757172418] 'process raft request' (duration: 207.709702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:09.90507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.339552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:30:09.906826Z","caller":"traceutil/trace.go:171","msg":"trace[574977953] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"248.109171ms","start":"2026-08-22T14:30:09.658683Z","end":"2026-08-22T14:30:09.906792Z","steps":["trace[574977953] 'agreement among raft nodes before linearized reading' (duration: 246.347351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:09.90509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.532044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:30:09.906931Z","caller":"traceutil/trace.go:171","msg":"trace[715273748] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"210.351963ms","start":"2026-08-22T14:30:09.696545Z","end":"2026-08-22T14:30:09.906897Z","steps":["trace[715273748] 'agreement among raft nodes before linearized reading' (duration: 208.505655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:09.905128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.46588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:30:09.907049Z","caller":"traceutil/trace.go:171","msg":"trace[2063870601] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"212.376727ms","start":"2026-08-22T14:30:09.694654Z","end":"2026-08-22T14:30:09.90703Z","steps":["trace[2063870601] 'agreement among raft nodes before linearized reading' (duration: 210.446371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:09.905118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.905887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:30:09.907234Z","caller":"traceutil/trace.go:171","msg":"trace[1874949156] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"157.018709ms","start":"2026-08-22T14:30:09.750161Z","end":"2026-08-22T14:30:09.907179Z","steps":["trace[1874949156] 'agreement among raft nodes before linearized reading' (duration: 154.911386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:09.90518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.281486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:30:09.907329Z","caller":"traceutil/trace.go:171","msg":"trace[599830340] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:0; response_revision:11; }","duration":"229.456096ms","start":"2026-08-22T14:30:09.677858Z","end":"2026-08-22T14:30:09.907314Z","steps":["trace[599830340] 'agreement among raft nodes before linearized reading' (duration: 227.298655ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:10.056796Z","caller":"traceutil/trace.go:171","msg":"trace[1761379105] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"145.294236ms","start":"2026-08-22T14:30:09.911477Z","end":"2026-08-22T14:30:10.056771Z","steps":["trace[1761379105] 'process raft request' (duration: 78.616517ms)","trace[1761379105] 'compare' (duration: 66.344196ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:10.056863Z","caller":"traceutil/trace.go:171","msg":"trace[88030121] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"144.140081ms","start":"2026-08-22T14:30:09.912694Z","end":"2026-08-22T14:30:10.056834Z","steps":["trace[88030121] 'process raft request' (duration: 143.975705ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:10.057103Z","caller":"traceutil/trace.go:171","msg":"trace[1080324063] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"143.984356ms","start":"2026-08-22T14:30:09.913111Z","end":"2026-08-22T14:30:10.057096Z","steps":["trace[1080324063] 'process raft request' (duration: 143.660763ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:10.057181Z","caller":"traceutil/trace.go:171","msg":"trace[1297336111] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"144.060984ms","start":"2026-08-22T14:30:09.913114Z","end":"2026-08-22T14:30:10.057175Z","steps":["trace[1297336111] 'process raft request' (duration: 143.633883ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:10.057169Z","caller":"traceutil/trace.go:171","msg":"trace[1788346286] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"144.22035ms","start":"2026-08-22T14:30:09.912928Z","end":"2026-08-22T14:30:10.057148Z","steps":["trace[1788346286] 'process raft request' (duration: 143.78962ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:10.057288Z","caller":"traceutil/trace.go:171","msg":"trace[37427568] transaction","detail":"{read_only:false; number_of_response:0; response_revision:14; }","duration":"145.104701ms","start":"2026-08-22T14:30:09.91217Z","end":"2026-08-22T14:30:10.057275Z","steps":["trace[37427568] 'process raft request' (duration: 144.415236ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:10.05741Z","caller":"traceutil/trace.go:171","msg":"trace[850300889] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"141.337856ms","start":"2026-08-22T14:30:09.916065Z","end":"2026-08-22T14:30:10.057403Z","steps":["trace[850300889] 'process raft request' (duration: 140.762308ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:10.057469Z","caller":"traceutil/trace.go:171","msg":"trace[758103595] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"145.115531ms","start":"2026-08-22T14:30:09.912335Z","end":"2026-08-22T14:30:10.057451Z","steps":["trace[758103595] 'process raft request' (duration: 144.283939ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:10.056795Z","caller":"traceutil/trace.go:171","msg":"trace[1013959276] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"145.193889ms","start":"2026-08-22T14:30:09.91154Z","end":"2026-08-22T14:30:10.056734Z","steps":["trace[1013959276] 'process raft request' (duration: 144.997493ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:10.060128Z","caller":"traceutil/trace.go:171","msg":"trace[2095114994] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:15; }","duration":"118.659156ms","start":"2026-08-22T14:30:09.941402Z","end":"2026-08-22T14:30:10.060061Z","steps":["trace[2095114994] 'read index received' (duration: 48.689999ms)","trace[2095114994] 'applied index is now lower than readState.Index' (duration: 68.864922ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:10.060494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.273559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-nk2g6\" ","response":"range_response_count:1 size:861"} {"level":"warn","ts":"2026-08-22T14:30:10.060666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.771653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-22T14:30:10.060717Z","caller":"traceutil/trace.go:171","msg":"trace[1695712904] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:21; }","duration":"115.854941ms","start":"2026-08-22T14:30:09.944843Z","end":"2026-08-22T14:30:10.060698Z","steps":["trace[1695712904] 'agreement among raft nodes before linearized reading' (duration: 115.766633ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:10.060567Z","caller":"traceutil/trace.go:171","msg":"trace[633144616] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-nk2g6; range_end:; response_count:1; response_revision:21; }","duration":"149.377476ms","start":"2026-08-22T14:30:09.911171Z","end":"2026-08-22T14:30:10.060548Z","steps":["trace[633144616] 'agreement among raft nodes before linearized reading' (duration: 149.120552ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:10.456642Z","caller":"traceutil/trace.go:171","msg":"trace[847729666] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"142.496232ms","start":"2026-08-22T14:30:10.314123Z","end":"2026-08-22T14:30:10.456619Z","steps":["trace[847729666] 'process raft request' (duration: 116.75513ms)","trace[847729666] 'compare' (duration: 25.631074ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:10.46312Z","caller":"traceutil/trace.go:171","msg":"trace[1308951173] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"147.440324ms","start":"2026-08-22T14:30:10.31551Z","end":"2026-08-22T14:30:10.46295Z","steps":["trace[1308951173] 'process raft request' (duration: 147.372196ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:10.661603Z","caller":"traceutil/trace.go:171","msg":"trace[1634966708] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"106.287496ms","start":"2026-08-22T14:30:10.555293Z","end":"2026-08-22T14:30:10.66158Z","steps":["trace[1634966708] 'process raft request' (duration: 106.137589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:11.913217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.327874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:30:11.913322Z","caller":"traceutil/trace.go:171","msg":"trace[966876155] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"152.295938ms","start":"2026-08-22T14:30:11.761008Z","end":"2026-08-22T14:30:11.913304Z","steps":["trace[966876155] 'process raft request' (duration: 27.384097ms)","trace[966876155] 'compare' (duration: 124.179237ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:12.36517Z","caller":"traceutil/trace.go:171","msg":"trace[1422928812] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"100.127641ms","start":"2026-08-22T14:30:12.265016Z","end":"2026-08-22T14:30:12.365144Z","steps":["trace[1422928812] 'process raft request' (duration: 52.363851ms)","trace[1422928812] 'compare' (duration: 47.676072ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:13.131919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.27676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:30:13.132009Z","caller":"traceutil/trace.go:171","msg":"trace[1805386708] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-controller; range_end:; response_count:0; response_revision:177; }","duration":"127.401698ms","start":"2026-08-22T14:30:13.004586Z","end":"2026-08-22T14:30:13.131987Z","steps":["trace[1805386708] 'range keys from in-memory index tree' (duration: 127.215211ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:13.243882Z","caller":"traceutil/trace.go:171","msg":"trace[626779743] linearizableReadLoop","detail":"{readStateIndex:182; appliedIndex:181; }","duration":"105.469251ms","start":"2026-08-22T14:30:13.138386Z","end":"2026-08-22T14:30:13.243855Z","steps":["trace[626779743] 'read index received' (duration: 105.226035ms)","trace[626779743] 'applied index is now lower than readState.Index' (duration: 242.216µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:13.244042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.641538ms","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-08-22T14:30:13.244091Z","caller":"traceutil/trace.go:171","msg":"trace[1285390724] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"109.075015ms","start":"2026-08-22T14:30:13.134992Z","end":"2026-08-22T14:30:13.244067Z","steps":["trace[1285390724] 'process raft request' (duration: 108.711601ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:13.244122Z","caller":"traceutil/trace.go:171","msg":"trace[369059604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:178; }","duration":"105.755265ms","start":"2026-08-22T14:30:13.138352Z","end":"2026-08-22T14:30:13.244108Z","steps":["trace[369059604] 'agreement among raft nodes before linearized reading' (duration: 105.610458ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:13.408953Z","caller":"traceutil/trace.go:171","msg":"trace[839870504] transaction","detail":"{read_only:false; response_revision:179; number_of_response:1; }","duration":"161.418462ms","start":"2026-08-22T14:30:13.247504Z","end":"2026-08-22T14:30:13.408923Z","steps":["trace[839870504] 'process raft request' (duration: 150.422645ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:13.428161Z","caller":"traceutil/trace.go:171","msg":"trace[356379630] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"179.154015ms","start":"2026-08-22T14:30:13.248987Z","end":"2026-08-22T14:30:13.428141Z","steps":["trace[356379630] 'process raft request' (duration: 178.698073ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:13.80332Z","caller":"traceutil/trace.go:171","msg":"trace[1841121792] transaction","detail":"{read_only:false; response_revision:189; number_of_response:1; }","duration":"146.644653ms","start":"2026-08-22T14:30:13.656652Z","end":"2026-08-22T14:30:13.803296Z","steps":["trace[1841121792] 'process raft request' (duration: 72.230372ms)","trace[1841121792] 'compare' (duration: 74.039229ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:14.239342Z","caller":"traceutil/trace.go:171","msg":"trace[630788114] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"104.974521ms","start":"2026-08-22T14:30:14.134144Z","end":"2026-08-22T14:30:14.239119Z","steps":["trace[630788114] 'process raft request' (duration: 38.019561ms)","trace[630788114] 'compare' (duration: 66.557937ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:14.973613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.798156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:30:14.973815Z","caller":"traceutil/trace.go:171","msg":"trace[1484435527] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"382.204587ms","start":"2026-08-22T14:30:14.591576Z","end":"2026-08-22T14:30:14.97378Z","steps":["trace[1484435527] 'process raft request' (duration: 145.164455ms)","trace[1484435527] 'compare' (duration: 236.668369ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:14.973919Z","caller":"traceutil/trace.go:171","msg":"trace[1890034134] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"337.697758ms","start":"2026-08-22T14:30:14.636205Z","end":"2026-08-22T14:30:14.973903Z","steps":["trace[1890034134] 'process raft request' (duration: 337.56119ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:14.973918Z","caller":"traceutil/trace.go:171","msg":"trace[1249789687] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"340.273294ms","start":"2026-08-22T14:30:14.633559Z","end":"2026-08-22T14:30:14.973833Z","steps":["trace[1249789687] 'process raft request' (duration: 340.159826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:14.97396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:14.591561Z","time spent":"382.335625ms","remote":"127.0.0.1:46860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":330,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:30:14.974022Z","caller":"traceutil/trace.go:171","msg":"trace[1764858659] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"211.014054ms","start":"2026-08-22T14:30:14.762999Z","end":"2026-08-22T14:30:14.974013Z","steps":["trace[1764858659] 'process raft request' (duration: 210.812467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:14.973987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:14.636194Z","time spent":"337.766177ms","remote":"127.0.0.1:46728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":203,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:30:14.974667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:14.633524Z","time spent":"340.50335ms","remote":"127.0.0.1:46550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:30:15.31554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.413607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:30:15.315721Z","caller":"traceutil/trace.go:171","msg":"trace[1801692921] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"263.584278ms","start":"2026-08-22T14:30:15.052123Z","end":"2026-08-22T14:30:15.315707Z","steps":["trace[1801692921] 'process raft request' (duration: 263.547128ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:15.315856Z","caller":"traceutil/trace.go:171","msg":"trace[1428113400] linearizableReadLoop","detail":"{readStateIndex:223; appliedIndex:220; }","duration":"337.190303ms","start":"2026-08-22T14:30:14.978653Z","end":"2026-08-22T14:30:15.315843Z","steps":["trace[1428113400] 'read index received' (duration: 70.395037ms)","trace[1428113400] 'applied index is now lower than readState.Index' (duration: 266.793446ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:15.315914Z","caller":"traceutil/trace.go:171","msg":"trace[1191692331] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"337.304831ms","start":"2026-08-22T14:30:14.978583Z","end":"2026-08-22T14:30:15.315888Z","steps":["trace[1191692331] 'process raft request' (duration: 179.433612ms)","trace[1191692331] 'compare' (duration: 157.296108ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:15.316186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.42913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-22T14:30:15.315914Z","caller":"traceutil/trace.go:171","msg":"trace[1047496043] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"336.77898ms","start":"2026-08-22T14:30:14.979053Z","end":"2026-08-22T14:30:15.315832Z","steps":["trace[1047496043] 'process raft request' (duration: 336.573203ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:15.316216Z","caller":"traceutil/trace.go:171","msg":"trace[1914345503] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:219; }","duration":"337.45643ms","start":"2026-08-22T14:30:14.978749Z","end":"2026-08-22T14:30:15.316206Z","steps":["trace[1914345503] 'agreement among raft nodes before linearized reading' (duration: 337.40888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:15.316326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:14.978711Z","time spent":"337.579767ms","remote":"127.0.0.1:46550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-08-22T14:30:15.315941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.283161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:30:15.31638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:14.978573Z","time spent":"337.589607ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:30:15.316449Z","caller":"traceutil/trace.go:171","msg":"trace[43502843] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:219; }","duration":"337.798723ms","start":"2026-08-22T14:30:14.9786Z","end":"2026-08-22T14:30:15.316398Z","steps":["trace[43502843] 'agreement among raft nodes before linearized reading' (duration: 337.302131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:15.316424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:14.979036Z","time spent":"337.263613ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:30:15.316505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:14.978584Z","time spent":"337.902372ms","remote":"127.0.0.1:46728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-08-22T14:30:15.542453Z","caller":"traceutil/trace.go:171","msg":"trace[1878228770] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"219.066813ms","start":"2026-08-22T14:30:15.32337Z","end":"2026-08-22T14:30:15.542437Z","steps":["trace[1878228770] 'process raft request' (duration: 219.038614ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:15.542543Z","caller":"traceutil/trace.go:171","msg":"trace[1202754601] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"220.813664ms","start":"2026-08-22T14:30:15.321704Z","end":"2026-08-22T14:30:15.542518Z","steps":["trace[1202754601] 'process raft request' (duration: 220.300663ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:15.567821Z","caller":"traceutil/trace.go:171","msg":"trace[65409646] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"212.41467ms","start":"2026-08-22T14:30:15.355383Z","end":"2026-08-22T14:30:15.567797Z","steps":["trace[65409646] 'process raft request' (duration: 212.355961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:15.762142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.870046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:30:15.762377Z","caller":"traceutil/trace.go:171","msg":"trace[1433653851] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"143.573212ms","start":"2026-08-22T14:30:15.618785Z","end":"2026-08-22T14:30:15.762358Z","steps":["trace[1433653851] 'process raft request' (duration: 143.468634ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:15.762395Z","caller":"traceutil/trace.go:171","msg":"trace[441603752] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"149.429317ms","start":"2026-08-22T14:30:15.612897Z","end":"2026-08-22T14:30:15.762327Z","steps":["trace[441603752] 'process raft request' (duration: 35.286426ms)","trace[441603752] 'compare' (duration: 113.770308ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:15.762413Z","caller":"traceutil/trace.go:171","msg":"trace[451213880] linearizableReadLoop","detail":"{readStateIndex:235; appliedIndex:234; }","duration":"143.970587ms","start":"2026-08-22T14:30:15.618407Z","end":"2026-08-22T14:30:15.762377Z","steps":["trace[451213880] 'read index received' (duration: 29.784546ms)","trace[451213880] 'applied index is now lower than readState.Index' (duration: 114.182521ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:15.762591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.156844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-22T14:30:15.762644Z","caller":"traceutil/trace.go:171","msg":"trace[39475569] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:230; }","duration":"144.247991ms","start":"2026-08-22T14:30:15.618387Z","end":"2026-08-22T14:30:15.762635Z","steps":["trace[39475569] 'agreement among raft nodes before linearized reading' (duration: 144.079044ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:15.985459Z","caller":"traceutil/trace.go:171","msg":"trace[1061856560] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"153.814474ms","start":"2026-08-22T14:30:15.831618Z","end":"2026-08-22T14:30:15.985433Z","steps":["trace[1061856560] 'process raft request' (duration: 153.763285ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:15.985625Z","caller":"traceutil/trace.go:171","msg":"trace[76580765] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"154.414636ms","start":"2026-08-22T14:30:15.831054Z","end":"2026-08-22T14:30:15.985469Z","steps":["trace[76580765] 'process raft request' (duration: 70.615031ms)","trace[76580765] 'compare' (duration: 83.581488ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:16.613132Z","caller":"traceutil/trace.go:171","msg":"trace[1362541096] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"131.254458ms","start":"2026-08-22T14:30:16.481848Z","end":"2026-08-22T14:30:16.613103Z","steps":["trace[1362541096] 'process raft request' (duration: 47.273269ms)","trace[1362541096] 'compare' (duration: 83.8462ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:16.613574Z","caller":"traceutil/trace.go:171","msg":"trace[2123226191] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"130.276413ms","start":"2026-08-22T14:30:16.48328Z","end":"2026-08-22T14:30:16.613557Z","steps":["trace[2123226191] 'process raft request' (duration: 130.249923ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:16.613673Z","caller":"traceutil/trace.go:171","msg":"trace[882037488] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"130.385632ms","start":"2026-08-22T14:30:16.483256Z","end":"2026-08-22T14:30:16.613641Z","steps":["trace[882037488] 'process raft request' (duration: 130.200815ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:16.867159Z","caller":"traceutil/trace.go:171","msg":"trace[1180921674] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"172.954282ms","start":"2026-08-22T14:30:16.694181Z","end":"2026-08-22T14:30:16.867135Z","steps":["trace[1180921674] 'process raft request' (duration: 107.751387ms)","trace[1180921674] 'compare' (duration: 65.033078ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:16.867224Z","caller":"traceutil/trace.go:171","msg":"trace[1172040808] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"170.739015ms","start":"2026-08-22T14:30:16.696475Z","end":"2026-08-22T14:30:16.867214Z","steps":["trace[1172040808] 'process raft request' (duration: 170.685596ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:18.21794Z","caller":"traceutil/trace.go:171","msg":"trace[84050609] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"121.082616ms","start":"2026-08-22T14:30:18.096828Z","end":"2026-08-22T14:30:18.21791Z","steps":["trace[84050609] 'process raft request' (duration: 120.967948ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:18.238525Z","caller":"traceutil/trace.go:171","msg":"trace[2011525024] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"140.120954ms","start":"2026-08-22T14:30:18.098386Z","end":"2026-08-22T14:30:18.238507Z","steps":["trace[2011525024] 'process raft request' (duration: 139.954807ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:18.238583Z","caller":"traceutil/trace.go:171","msg":"trace[1504934731] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"140.176524ms","start":"2026-08-22T14:30:18.098386Z","end":"2026-08-22T14:30:18.238563Z","steps":["trace[1504934731] 'process raft request' (duration: 140.015766ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:18.23868Z","caller":"traceutil/trace.go:171","msg":"trace[692249647] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"135.793702ms","start":"2026-08-22T14:30:18.102875Z","end":"2026-08-22T14:30:18.238669Z","steps":["trace[692249647] 'process raft request' (duration: 135.568835ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:18.238628Z","caller":"traceutil/trace.go:171","msg":"trace[1826290752] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"121.436893ms","start":"2026-08-22T14:30:18.117172Z","end":"2026-08-22T14:30:18.238609Z","steps":["trace[1826290752] 'process raft request' (duration: 121.330974ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:18.23855Z","caller":"traceutil/trace.go:171","msg":"trace[1966523888] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"139.947997ms","start":"2026-08-22T14:30:18.098578Z","end":"2026-08-22T14:30:18.238526Z","steps":["trace[1966523888] 'process raft request' (duration: 139.848198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:19.922117Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680222037096735488,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:30:20.253007Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.484263058s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T14:30:20.25545Z","caller":"traceutil/trace.go:171","msg":"trace[1765805251] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:346; }","duration":"833.72249ms","start":"2026-08-22T14:30:19.421698Z","end":"2026-08-22T14:30:20.25542Z","steps":["trace[1765805251] 'read index received' (duration: 833.526622ms)","trace[1765805251] 'applied index is now lower than readState.Index' (duration: 194.668µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:20.255621Z","caller":"traceutil/trace.go:171","msg":"trace[171494960] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"1.487418023s","start":"2026-08-22T14:30:18.768111Z","end":"2026-08-22T14:30:20.255529Z","steps":["trace[171494960] 'process raft request' (duration: 1.487167646s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:20.25573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.904195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-08-22T14:30:20.255755Z","caller":"traceutil/trace.go:171","msg":"trace[1217852539] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:333; }","duration":"613.950964ms","start":"2026-08-22T14:30:19.641795Z","end":"2026-08-22T14:30:20.255746Z","steps":["trace[1217852539] 'agreement among raft nodes before linearized reading' (duration: 613.914055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:20.255791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:19.641779Z","time spent":"614.006184ms","remote":"127.0.0.1:46716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4344,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-08-22T14:30:20.255825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.211991ms","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-08-22T14:30:20.255933Z","caller":"traceutil/trace.go:171","msg":"trace[608037897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:333; }","duration":"831.341639ms","start":"2026-08-22T14:30:19.424565Z","end":"2026-08-22T14:30:20.255907Z","steps":["trace[608037897] 'agreement among raft nodes before linearized reading' (duration: 831.118782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:20.255914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:18.768088Z","time spent":"1.487697639s","remote":"127.0.0.1:46728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:30:20.255992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:19.424534Z","time spent":"831.448269ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T14:30:20.25568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.950417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-22T14:30:20.256183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.658547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-22T14:30:20.256205Z","caller":"traceutil/trace.go:171","msg":"trace[1858631758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:333; }","duration":"631.715647ms","start":"2026-08-22T14:30:19.624484Z","end":"2026-08-22T14:30:20.256199Z","steps":["trace[1858631758] 'agreement among raft nodes before linearized reading' (duration: 631.671788ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:20.256197Z","caller":"traceutil/trace.go:171","msg":"trace[559001646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:333; }","duration":"834.51997ms","start":"2026-08-22T14:30:19.421657Z","end":"2026-08-22T14:30:20.256177Z","steps":["trace[559001646] 'agreement among raft nodes before linearized reading' (duration: 833.922237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:20.256224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:19.624468Z","time spent":"631.752747ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T14:30:20.256256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:19.42164Z","time spent":"834.60122ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T14:30:20.472808Z","caller":"traceutil/trace.go:171","msg":"trace[1468664897] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"209.415284ms","start":"2026-08-22T14:30:20.26336Z","end":"2026-08-22T14:30:20.472776Z","steps":["trace[1468664897] 'process raft request' (duration: 209.317505ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:20.472817Z","caller":"traceutil/trace.go:171","msg":"trace[1389623375] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"210.821128ms","start":"2026-08-22T14:30:20.261956Z","end":"2026-08-22T14:30:20.472777Z","steps":["trace[1389623375] 'process raft request' (duration: 210.521572ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:20.473063Z","caller":"traceutil/trace.go:171","msg":"trace[62884461] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:347; }","duration":"210.578941ms","start":"2026-08-22T14:30:20.262468Z","end":"2026-08-22T14:30:20.473047Z","steps":["trace[62884461] 'read index received' (duration: 34.396042ms)","trace[62884461] 'applied index is now lower than readState.Index' (duration: 176.180309ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:20.473137Z","caller":"traceutil/trace.go:171","msg":"trace[1039814013] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"208.322126ms","start":"2026-08-22T14:30:20.264785Z","end":"2026-08-22T14:30:20.473107Z","steps":["trace[1039814013] 'process raft request' (duration: 207.936031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:20.473302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.972529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-22T14:30:20.473366Z","caller":"traceutil/trace.go:171","msg":"trace[829874610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:337; }","duration":"186.070818ms","start":"2026-08-22T14:30:20.287278Z","end":"2026-08-22T14:30:20.473349Z","steps":["trace[829874610] 'agreement among raft nodes before linearized reading' (duration: 185.93942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:20.474209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.823589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-22T14:30:20.475024Z","caller":"traceutil/trace.go:171","msg":"trace[1824210621] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:337; }","duration":"212.540319ms","start":"2026-08-22T14:30:20.26245Z","end":"2026-08-22T14:30:20.474991Z","steps":["trace[1824210621] 'agreement among raft nodes before linearized reading' (duration: 210.72344ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:20.724288Z","caller":"traceutil/trace.go:171","msg":"trace[676820865] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"109.393105ms","start":"2026-08-22T14:30:20.614872Z","end":"2026-08-22T14:30:20.724265Z","steps":["trace[676820865] 'process raft request' (duration: 109.157527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:21.879132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.701229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:30:21.879338Z","caller":"traceutil/trace.go:171","msg":"trace[1427490020] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:399; }","duration":"558.856418ms","start":"2026-08-22T14:30:21.320455Z","end":"2026-08-22T14:30:21.879312Z","steps":["trace[1427490020] 'read index received' (duration: 85.872282ms)","trace[1427490020] 'applied index is now lower than readState.Index' (duration: 472.982576ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:21.879438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.002657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-9t57wz\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-08-22T14:30:21.879473Z","caller":"traceutil/trace.go:171","msg":"trace[1778642558] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-9t57wz; range_end:; response_count:1; response_revision:386; }","duration":"559.123936ms","start":"2026-08-22T14:30:21.320338Z","end":"2026-08-22T14:30:21.879462Z","steps":["trace[1778642558] 'agreement among raft nodes before linearized reading' (duration: 559.046836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:21.879504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:21.320312Z","time spent":"559.183286ms","remote":"127.0.0.1:46614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":562,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-9t57wz\" "} {"level":"info","ts":"2026-08-22T14:30:21.879357Z","caller":"traceutil/trace.go:171","msg":"trace[1466387909] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"584.131026ms","start":"2026-08-22T14:30:21.295206Z","end":"2026-08-22T14:30:21.879337Z","steps":["trace[1466387909] 'process raft request' (duration: 111.097211ms)","trace[1466387909] 'compare' (duration: 472.510431ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:21.879759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:21.295176Z","time spent":"584.557553ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:30:22.688989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.119207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-22T14:30:22.689084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.448411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-22T14:30:22.689049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.795963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:30:22.689002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.483084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-22T14:30:22.689137Z","caller":"traceutil/trace.go:171","msg":"trace[211873708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:386; }","duration":"210.53691ms","start":"2026-08-22T14:30:22.478578Z","end":"2026-08-22T14:30:22.689115Z","steps":["trace[211873708] 'range keys from in-memory index tree' (duration: 210.252173ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:22.689174Z","caller":"traceutil/trace.go:171","msg":"trace[652502122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:386; }","duration":"387.743653ms","start":"2026-08-22T14:30:22.301412Z","end":"2026-08-22T14:30:22.689156Z","steps":["trace[652502122] 'range keys from in-memory index tree' (duration: 387.273066ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:22.689189Z","caller":"traceutil/trace.go:171","msg":"trace[1222836044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:386; }","duration":"459.017151ms","start":"2026-08-22T14:30:22.230141Z","end":"2026-08-22T14:30:22.689158Z","steps":["trace[1222836044] 'range keys from in-memory index tree' (duration: 458.681984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:22.689229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:22.301385Z","time spent":"387.830991ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T14:30:22.689254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:22.230113Z","time spent":"459.12433ms","remote":"127.0.0.1:46506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T14:30:22.68912Z","caller":"traceutil/trace.go:171","msg":"trace[1385700100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:386; }","duration":"200.318705ms","start":"2026-08-22T14:30:22.488778Z","end":"2026-08-22T14:30:22.689097Z","steps":["trace[1385700100] 'range keys from in-memory index tree' (duration: 199.931659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:22.689402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.51778ms","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-08-22T14:30:22.689527Z","caller":"traceutil/trace.go:171","msg":"trace[1421375710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:386; }","duration":"210.725509ms","start":"2026-08-22T14:30:22.478776Z","end":"2026-08-22T14:30:22.689501Z","steps":["trace[1421375710] 'range keys from in-memory index tree' (duration: 210.37989ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:22.890487Z","caller":"traceutil/trace.go:171","msg":"trace[1219448969] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"193.977285ms","start":"2026-08-22T14:30:22.696477Z","end":"2026-08-22T14:30:22.890455Z","steps":["trace[1219448969] 'process raft request' (duration: 193.870356ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:22.890501Z","caller":"traceutil/trace.go:171","msg":"trace[403994917] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"195.019245ms","start":"2026-08-22T14:30:22.695437Z","end":"2026-08-22T14:30:22.890456Z","steps":["trace[403994917] 'process raft request' (duration: 194.707088ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:22.890671Z","caller":"traceutil/trace.go:171","msg":"trace[450128227] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"193.667438ms","start":"2026-08-22T14:30:22.696971Z","end":"2026-08-22T14:30:22.890639Z","steps":["trace[450128227] 'process raft request' (duration: 193.426851ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:23.119011Z","caller":"traceutil/trace.go:171","msg":"trace[388223950] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"357.79244ms","start":"2026-08-22T14:30:22.761181Z","end":"2026-08-22T14:30:23.118973Z","steps":["trace[388223950] 'process raft request' (duration: 319.687802ms)","trace[388223950] 'compare' (duration: 37.704111ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:23.119193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:22.761152Z","time spent":"357.953837ms","remote":"127.0.0.1:46716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:30:23.593217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.033191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-08-22T14:30:23.593326Z","caller":"traceutil/trace.go:171","msg":"trace[676894697] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:394; }","duration":"219.211179ms","start":"2026-08-22T14:30:23.374091Z","end":"2026-08-22T14:30:23.593302Z","steps":["trace[676894697] 'range keys from in-memory index tree' (duration: 218.852313ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:23.713904Z","caller":"traceutil/trace.go:171","msg":"trace[1976957668] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"134.707077ms","start":"2026-08-22T14:30:23.579169Z","end":"2026-08-22T14:30:23.713876Z","steps":["trace[1976957668] 'process raft request' (duration: 134.538958ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:24.370598Z","caller":"traceutil/trace.go:171","msg":"trace[953393531] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"179.540089ms","start":"2026-08-22T14:30:24.191032Z","end":"2026-08-22T14:30:24.370572Z","steps":["trace[953393531] 'read index received' (duration: 179.3521ms)","trace[953393531] 'applied index is now lower than readState.Index' (duration: 186.979µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:24.370787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.724467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:30:24.370819Z","caller":"traceutil/trace.go:171","msg":"trace[533022593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:397; }","duration":"179.836886ms","start":"2026-08-22T14:30:24.190973Z","end":"2026-08-22T14:30:24.37081Z","steps":["trace[533022593] 'agreement among raft nodes before linearized reading' (duration: 179.746737ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:24.370963Z","caller":"traceutil/trace.go:171","msg":"trace[1254794321] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"245.245361ms","start":"2026-08-22T14:30:24.125648Z","end":"2026-08-22T14:30:24.370894Z","steps":["trace[1254794321] 'process raft request' (duration: 244.773015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:24.842301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.142097ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-08-22T14:30:24.842388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:24.371915Z","time spent":"470.468674ms","remote":"127.0.0.1:46550","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-22T14:30:25.338029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.359773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:30:25.338472Z","caller":"traceutil/trace.go:171","msg":"trace[1555838869] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"495.207773ms","start":"2026-08-22T14:30:24.84324Z","end":"2026-08-22T14:30:25.338447Z","steps":["trace[1555838869] 'process raft request' (duration: 495.050594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:25.33864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:24.843221Z","time spent":"495.347272ms","remote":"127.0.0.1:46550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:30:25.338756Z","caller":"traceutil/trace.go:171","msg":"trace[546042589] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"959.777322ms","start":"2026-08-22T14:30:24.378937Z","end":"2026-08-22T14:30:25.338715Z","steps":["trace[546042589] 'process raft request' (duration: 748.616415ms)","trace[546042589] 'compare' (duration: 210.102115ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:25.338973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:24.378918Z","time spent":"959.93485ms","remote":"127.0.0.1:46974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:30:25.338992Z","caller":"traceutil/trace.go:171","msg":"trace[416031687] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:413; }","duration":"584.06694ms","start":"2026-08-22T14:30:24.754827Z","end":"2026-08-22T14:30:25.338893Z","steps":["trace[416031687] 'read index received' (duration: 372.747264ms)","trace[416031687] 'applied index is now lower than readState.Index' (duration: 211.316596ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:25.339203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.366659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-22T14:30:25.33927Z","caller":"traceutil/trace.go:171","msg":"trace[562498721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:399; }","duration":"584.483208ms","start":"2026-08-22T14:30:24.75477Z","end":"2026-08-22T14:30:25.339254Z","steps":["trace[562498721] 'agreement among raft nodes before linearized reading' (duration: 584.28155ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:25.339315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:24.754747Z","time spent":"584.559968ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T14:30:25.339439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.619713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:30:25.339529Z","caller":"traceutil/trace.go:171","msg":"trace[529197174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"147.777321ms","start":"2026-08-22T14:30:25.191732Z","end":"2026-08-22T14:30:25.339509Z","steps":["trace[529197174] 'agreement among raft nodes before linearized reading' (duration: 147.593533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:25.339578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.924521ms","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-08-22T14:30:25.339636Z","caller":"traceutil/trace.go:171","msg":"trace[1488183082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:399; }","duration":"443.04479ms","start":"2026-08-22T14:30:24.89658Z","end":"2026-08-22T14:30:25.339625Z","steps":["trace[1488183082] 'agreement among raft nodes before linearized reading' (duration: 442.94918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:25.339666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:24.896557Z","time spent":"443.101729ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T14:30:25.340117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.233289ms","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-08-22T14:30:25.340219Z","caller":"traceutil/trace.go:171","msg":"trace[820315880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:399; }","duration":"443.410208ms","start":"2026-08-22T14:30:24.896792Z","end":"2026-08-22T14:30:25.340202Z","steps":["trace[820315880] 'agreement among raft nodes before linearized reading' (duration: 442.673023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:25.340307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:24.896772Z","time spent":"443.523497ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T14:30:25.340667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.46101ms","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-08-22T14:30:25.340763Z","caller":"traceutil/trace.go:171","msg":"trace[975353264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:399; }","duration":"445.85537ms","start":"2026-08-22T14:30:24.894842Z","end":"2026-08-22T14:30:25.340698Z","steps":["trace[975353264] 'agreement among raft nodes before linearized reading' (duration: 444.39138ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:25.340804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:24.894826Z","time spent":"445.964319ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T14:30:25.903404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.942549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:30:25.903566Z","caller":"traceutil/trace.go:171","msg":"trace[2028946237] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"559.443229ms","start":"2026-08-22T14:30:25.344101Z","end":"2026-08-22T14:30:25.903544Z","steps":["trace[2028946237] 'process raft request' (duration: 176.233162ms)","trace[2028946237] 'compare' (duration: 382.77293ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:25.903864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:25.344086Z","time spent":"559.736727ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:30:26.280147Z","caller":"traceutil/trace.go:171","msg":"trace[1960404320] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"935.240007ms","start":"2026-08-22T14:30:25.344867Z","end":"2026-08-22T14:30:26.280107Z","steps":["trace[1960404320] 'process raft request' (duration: 934.982828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:26.280379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:25.34484Z","time spent":"935.401837ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:30:26.280592Z","caller":"traceutil/trace.go:171","msg":"trace[1133512620] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"935.354798ms","start":"2026-08-22T14:30:25.345211Z","end":"2026-08-22T14:30:26.280566Z","steps":["trace[1133512620] 'process raft request' (duration: 935.130089ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:26.280577Z","caller":"traceutil/trace.go:171","msg":"trace[1797592707] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:415; }","duration":"935.333478ms","start":"2026-08-22T14:30:25.345189Z","end":"2026-08-22T14:30:26.280523Z","steps":["trace[1797592707] 'read index received' (duration: 175.161739ms)","trace[1797592707] 'applied index is now lower than readState.Index' (duration: 760.170269ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:26.280583Z","caller":"traceutil/trace.go:171","msg":"trace[1510761664] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"935.588046ms","start":"2026-08-22T14:30:25.344964Z","end":"2026-08-22T14:30:26.280552Z","steps":["trace[1510761664] 'process raft request' (duration: 935.075968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:26.280703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:25.345201Z","time spent":"935.451457ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:30:26.280747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.545757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T14:30:26.280816Z","caller":"traceutil/trace.go:171","msg":"trace[2068867783] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:403; }","duration":"935.622605ms","start":"2026-08-22T14:30:25.345174Z","end":"2026-08-22T14:30:26.280797Z","steps":["trace[2068867783] 'agreement among raft nodes before linearized reading' (duration: 935.509216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:26.280866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:25.345166Z","time spent":"935.688845ms","remote":"127.0.0.1:46786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-08-22T14:30:26.280775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.391681ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:30:26.280997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:25.344957Z","time spent":"935.868454ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:30:26.281057Z","caller":"traceutil/trace.go:171","msg":"trace[106230316] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:403; }","duration":"613.664789ms","start":"2026-08-22T14:30:25.667368Z","end":"2026-08-22T14:30:26.281033Z","steps":["trace[106230316] 'agreement among raft nodes before linearized reading' (duration: 613.375401ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:28.272871Z","caller":"traceutil/trace.go:171","msg":"trace[2029300277] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"217.467831ms","start":"2026-08-22T14:30:28.055367Z","end":"2026-08-22T14:30:28.272835Z","steps":["trace[2029300277] 'process raft request' (duration: 217.119873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:32.441233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680222037096735645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:30:32.481113Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.186506314s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T14:30:32.481608Z","caller":"traceutil/trace.go:171","msg":"trace[1017607290] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"1.585114322s","start":"2026-08-22T14:30:30.896445Z","end":"2026-08-22T14:30:32.48156Z","steps":["trace[1017607290] 'process raft request' (duration: 1.584848333s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:32.481747Z","caller":"traceutil/trace.go:171","msg":"trace[1458137064] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"540.459788ms","start":"2026-08-22T14:30:31.941002Z","end":"2026-08-22T14:30:32.481462Z","steps":["trace[1458137064] 'read index received' (duration: 540.205029ms)","trace[1458137064] 'applied index is now lower than readState.Index' (duration: 253.649µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:32.481759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.737168ms","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-08-22T14:30:32.481844Z","caller":"traceutil/trace.go:171","msg":"trace[1833904242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:417; }","duration":"540.880848ms","start":"2026-08-22T14:30:31.940942Z","end":"2026-08-22T14:30:32.481823Z","steps":["trace[1833904242] 'agreement among raft nodes before linearized reading' (duration: 540.700108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:32.48183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:30.8964Z","time spent":"1.585320092s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:30:32.481885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:31.940902Z","time spent":"540.976768ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T14:30:32.481965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.81978ms","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-08-22T14:30:32.48201Z","caller":"traceutil/trace.go:171","msg":"trace[1616229166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:417; }","duration":"108.88993ms","start":"2026-08-22T14:30:32.373107Z","end":"2026-08-22T14:30:32.481997Z","steps":["trace[1616229166] 'agreement among raft nodes before linearized reading' (duration: 108.81994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:32.482061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.807569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-22T14:30:32.482337Z","caller":"traceutil/trace.go:171","msg":"trace[1301901067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:417; }","duration":"112.179899ms","start":"2026-08-22T14:30:32.370137Z","end":"2026-08-22T14:30:32.482317Z","steps":["trace[1301901067] 'agreement among raft nodes before linearized reading' (duration: 111.812979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:32.482409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.780581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:30:32.482476Z","caller":"traceutil/trace.go:171","msg":"trace[1535250157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"293.430271ms","start":"2026-08-22T14:30:32.189033Z","end":"2026-08-22T14:30:32.482463Z","steps":["trace[1535250157] 'agreement among raft nodes before linearized reading' (duration: 292.821261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:32.482058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.468611ms","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-08-22T14:30:32.482572Z","caller":"traceutil/trace.go:171","msg":"trace[1076027501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"109.010141ms","start":"2026-08-22T14:30:32.373551Z","end":"2026-08-22T14:30:32.482562Z","steps":["trace[1076027501] 'agreement among raft nodes before linearized reading' (duration: 108.349601ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:32.725666Z","caller":"traceutil/trace.go:171","msg":"trace[670931545] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"239.313224ms","start":"2026-08-22T14:30:32.486314Z","end":"2026-08-22T14:30:32.725628Z","steps":["trace[670931545] 'process raft request' (duration: 238.804224ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:32.728378Z","caller":"traceutil/trace.go:171","msg":"trace[463858926] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"241.617602ms","start":"2026-08-22T14:30:32.486734Z","end":"2026-08-22T14:30:32.728352Z","steps":["trace[463858926] 'process raft request' (duration: 241.567232ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:32.728505Z","caller":"traceutil/trace.go:171","msg":"trace[1871715056] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"241.820252ms","start":"2026-08-22T14:30:32.486642Z","end":"2026-08-22T14:30:32.728462Z","steps":["trace[1871715056] 'process raft request' (duration: 241.471143ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:32.728527Z","caller":"traceutil/trace.go:171","msg":"trace[2143174234] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"241.791182ms","start":"2026-08-22T14:30:32.486696Z","end":"2026-08-22T14:30:32.728487Z","steps":["trace[2143174234] 'process raft request' (duration: 241.562442ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:34.852459Z","caller":"traceutil/trace.go:171","msg":"trace[1964338073] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"116.135217ms","start":"2026-08-22T14:30:34.736298Z","end":"2026-08-22T14:30:34.852433Z","steps":["trace[1964338073] 'process raft request' (duration: 113.580095ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:34.852575Z","caller":"traceutil/trace.go:171","msg":"trace[338806479] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"115.629497ms","start":"2026-08-22T14:30:34.736932Z","end":"2026-08-22T14:30:34.852561Z","steps":["trace[338806479] 'process raft request' (duration: 115.429177ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:34.852489Z","caller":"traceutil/trace.go:171","msg":"trace[1865854864] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"115.649517ms","start":"2026-08-22T14:30:34.736822Z","end":"2026-08-22T14:30:34.852472Z","steps":["trace[1865854864] 'process raft request' (duration: 115.477967ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:36.879259Z","caller":"traceutil/trace.go:171","msg":"trace[509975942] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"100.219246ms","start":"2026-08-22T14:30:36.779007Z","end":"2026-08-22T14:30:36.879226Z","steps":["trace[509975942] 'process raft request' (duration: 99.915645ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:37.166317Z","caller":"traceutil/trace.go:171","msg":"trace[1268193547] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"280.592885ms","start":"2026-08-22T14:30:36.885692Z","end":"2026-08-22T14:30:37.166285Z","steps":["trace[1268193547] 'process raft request' (duration: 280.535675ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:37.166396Z","caller":"traceutil/trace.go:171","msg":"trace[1489565225] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"280.705165ms","start":"2026-08-22T14:30:36.885634Z","end":"2026-08-22T14:30:37.166339Z","steps":["trace[1489565225] 'process raft request' (duration: 280.384343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:39.302232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.868601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:30:39.302357Z","caller":"traceutil/trace.go:171","msg":"trace[1962534657] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"125.514738ms","start":"2026-08-22T14:30:39.176822Z","end":"2026-08-22T14:30:39.302336Z","steps":["trace[1962534657] 'read index received' (duration: 35.79µs)","trace[1962534657] 'applied index is now lower than readState.Index' (duration: 125.477448ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:39.302398Z","caller":"traceutil/trace.go:171","msg":"trace[911600183] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"310.912832ms","start":"2026-08-22T14:30:38.991438Z","end":"2026-08-22T14:30:39.302351Z","steps":["trace[911600183] 'process raft request' (duration: 107.81734ms)","trace[911600183] 'compare' (duration: 202.69433ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:39.302609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.686319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-22T14:30:39.302567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.712519ms","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-08-22T14:30:39.302655Z","caller":"traceutil/trace.go:171","msg":"trace[231062388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:433; }","duration":"125.749739ms","start":"2026-08-22T14:30:39.176891Z","end":"2026-08-22T14:30:39.302641Z","steps":["trace[231062388] 'agreement among raft nodes before linearized reading' (duration: 125.664939ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:39.302683Z","caller":"traceutil/trace.go:171","msg":"trace[1703577215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:433; }","duration":"125.879839ms","start":"2026-08-22T14:30:39.176778Z","end":"2026-08-22T14:30:39.302658Z","steps":["trace[1703577215] 'agreement among raft nodes before linearized reading' (duration: 125.672828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:39.30269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:38.991408Z","time spent":"311.130253ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:30:39.593439Z","caller":"traceutil/trace.go:171","msg":"trace[964952004] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"285.82661ms","start":"2026-08-22T14:30:39.307577Z","end":"2026-08-22T14:30:39.593404Z","steps":["trace[964952004] 'process raft request' (duration: 285.600299ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:41.497952Z","caller":"traceutil/trace.go:171","msg":"trace[1666782243] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:454; }","duration":"190.424422ms","start":"2026-08-22T14:30:41.307499Z","end":"2026-08-22T14:30:41.497923Z","steps":["trace[1666782243] 'read index received' (duration: 190.140171ms)","trace[1666782243] 'applied index is now lower than readState.Index' (duration: 283.051µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:41.497991Z","caller":"traceutil/trace.go:171","msg":"trace[18818347] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"502.194237ms","start":"2026-08-22T14:30:40.995752Z","end":"2026-08-22T14:30:41.497947Z","steps":["trace[18818347] 'process raft request' (duration: 501.987116ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:41.498221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.229187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-08-22T14:30:41.498158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.632563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-22T14:30:41.49839Z","caller":"traceutil/trace.go:171","msg":"trace[422328954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:436; }","duration":"131.324747ms","start":"2026-08-22T14:30:41.366927Z","end":"2026-08-22T14:30:41.498252Z","steps":["trace[422328954] 'agreement among raft nodes before linearized reading' (duration: 131.271867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:41.498444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:40.995734Z","time spent":"502.497399ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:30:41.498455Z","caller":"traceutil/trace.go:171","msg":"trace[817143857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:436; }","duration":"190.970215ms","start":"2026-08-22T14:30:41.307446Z","end":"2026-08-22T14:30:41.498416Z","steps":["trace[817143857] 'agreement among raft nodes before linearized reading' (duration: 190.609443ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:43.129952Z","caller":"traceutil/trace.go:171","msg":"trace[1830131485] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"288.778218ms","start":"2026-08-22T14:30:42.841142Z","end":"2026-08-22T14:30:43.12992Z","steps":["trace[1830131485] 'process raft request' (duration: 288.582287ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:53.774209Z","caller":"traceutil/trace.go:171","msg":"trace[898597510] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:517; }","duration":"144.086156ms","start":"2026-08-22T14:30:53.630077Z","end":"2026-08-22T14:30:53.774163Z","steps":["trace[898597510] 'read index received' (duration: 72.410598ms)","trace[898597510] 'applied index is now lower than readState.Index' (duration: 71.674068ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:53.774348Z","caller":"traceutil/trace.go:171","msg":"trace[538779788] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"144.924078ms","start":"2026-08-22T14:30:53.629381Z","end":"2026-08-22T14:30:53.774305Z","steps":["trace[538779788] 'process raft request' (duration: 73.167409ms)","trace[538779788] 'compare' (duration: 71.471895ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:53.774434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.319889ms","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-08-22T14:30:53.774475Z","caller":"traceutil/trace.go:171","msg":"trace[1997404861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"144.447751ms","start":"2026-08-22T14:30:53.630015Z","end":"2026-08-22T14:30:53.774463Z","steps":["trace[1997404861] 'agreement among raft nodes before linearized reading' (duration: 144.335079ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:53.919545Z","caller":"traceutil/trace.go:171","msg":"trace[1438315980] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"139.575186ms","start":"2026-08-22T14:30:53.779932Z","end":"2026-08-22T14:30:53.919507Z","steps":["trace[1438315980] 'process raft request' (duration: 114.109796ms)","trace[1438315980] 'compare' (duration: 25.313838ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:57.552512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.15398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:30:57.552599Z","caller":"traceutil/trace.go:171","msg":"trace[2033913827] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"363.157678ms","start":"2026-08-22T14:30:57.189429Z","end":"2026-08-22T14:30:57.552586Z","steps":["trace[2033913827] 'read index received' (duration: 212.837275ms)","trace[2033913827] 'applied index is now lower than readState.Index' (duration: 150.319333ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:57.55268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.249529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:30:57.552699Z","caller":"traceutil/trace.go:171","msg":"trace[1429318862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"363.31529ms","start":"2026-08-22T14:30:57.189376Z","end":"2026-08-22T14:30:57.552692Z","steps":["trace[1429318862] 'agreement among raft nodes before linearized reading' (duration: 363.241909ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:57.552683Z","caller":"traceutil/trace.go:171","msg":"trace[1106927947] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"383.8228ms","start":"2026-08-22T14:30:57.168819Z","end":"2026-08-22T14:30:57.552641Z","steps":["trace[1106927947] 'process raft request' (duration: 233.487617ms)","trace[1106927947] 'compare' (duration: 150.044008ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:57.552885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:57.168792Z","time spent":"384.005793ms","remote":"127.0.0.1:46974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14213,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:30:57.552743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:57.189317Z","time spent":"363.417372ms","remote":"127.0.0.1:46506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T14:30:57.752254Z","caller":"traceutil/trace.go:171","msg":"trace[1107475488] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"189.875948ms","start":"2026-08-22T14:30:57.562338Z","end":"2026-08-22T14:30:57.752214Z","steps":["trace[1107475488] 'process raft request' (duration: 189.231188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.202008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.211729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:30:58.202273Z","caller":"traceutil/trace.go:171","msg":"trace[293105995] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"601.73306ms","start":"2026-08-22T14:30:57.600481Z","end":"2026-08-22T14:30:58.202214Z","steps":["trace[293105995] 'process raft request' (duration: 368.221706ms)","trace[293105995] 'compare' (duration: 232.953995ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:58.202489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:57.600445Z","time spent":"601.941753ms","remote":"127.0.0.1:46984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:30:58.203274Z","caller":"traceutil/trace.go:171","msg":"trace[905068101] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"443.434364ms","start":"2026-08-22T14:30:57.759814Z","end":"2026-08-22T14:30:58.203249Z","steps":["trace[905068101] 'process raft request' (duration: 443.377913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.203377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:57.759788Z","time spent":"443.548405ms","remote":"127.0.0.1:46716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:30:58.204316Z","caller":"traceutil/trace.go:171","msg":"trace[553138739] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"515.343195ms","start":"2026-08-22T14:30:57.688959Z","end":"2026-08-22T14:30:58.204302Z","steps":["trace[553138739] 'process raft request' (duration: 513.266592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.204608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:57.688922Z","time spent":"515.541468ms","remote":"127.0.0.1:46614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:30:58.204772Z","caller":"traceutil/trace.go:171","msg":"trace[1094568487] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:538; }","duration":"586.084797ms","start":"2026-08-22T14:30:57.618655Z","end":"2026-08-22T14:30:58.20474Z","steps":["trace[1094568487] 'read index received' (duration: 132.949691ms)","trace[1094568487] 'applied index is now lower than readState.Index' (duration: 453.132326ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:58.204986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.326962ms","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-08-22T14:30:58.20505Z","caller":"traceutil/trace.go:171","msg":"trace[1170469179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"586.439014ms","start":"2026-08-22T14:30:57.618597Z","end":"2026-08-22T14:30:58.205036Z","steps":["trace[1170469179] 'agreement among raft nodes before linearized reading' (duration: 586.285012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.205087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:57.618583Z","time spent":"586.496595ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T14:30:58.275153Z","caller":"traceutil/trace.go:171","msg":"trace[382845417] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"192.646694ms","start":"2026-08-22T14:30:58.082474Z","end":"2026-08-22T14:30:58.275121Z","steps":["trace[382845417] 'process raft request' (duration: 192.505112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.275376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.776105ms","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-08-22T14:30:58.275871Z","caller":"traceutil/trace.go:171","msg":"trace[1091799223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"345.333664ms","start":"2026-08-22T14:30:57.930501Z","end":"2026-08-22T14:30:58.275835Z","steps":["trace[1091799223] 'agreement among raft nodes before linearized reading' (duration: 344.740965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.275967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:57.930476Z","time spent":"345.472186ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T14:30:58.275435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.096769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-22T14:30:58.276117Z","caller":"traceutil/trace.go:171","msg":"trace[167943698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"490.820951ms","start":"2026-08-22T14:30:57.785258Z","end":"2026-08-22T14:30:58.276079Z","steps":["trace[167943698] 'agreement among raft nodes before linearized reading' (duration: 489.969737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.275473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.293581ms","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-08-22T14:30:58.276202Z","caller":"traceutil/trace.go:171","msg":"trace[1582757805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"430.129455ms","start":"2026-08-22T14:30:57.846059Z","end":"2026-08-22T14:30:58.276188Z","steps":["trace[1582757805] 'agreement among raft nodes before linearized reading' (duration: 429.22312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.27624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:57.84603Z","time spent":"430.198356ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T14:30:58.276338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:57.785246Z","time spent":"490.909372ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T14:30:58.782307Z","caller":"traceutil/trace.go:171","msg":"trace[419798580] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"574.80724ms","start":"2026-08-22T14:30:58.207448Z","end":"2026-08-22T14:30:58.782255Z","steps":["trace[419798580] 'process raft request' (duration: 476.170201ms)","trace[419798580] 'compare' (duration: 98.352804ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:58.782455Z","caller":"traceutil/trace.go:171","msg":"trace[175821871] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"574.8083ms","start":"2026-08-22T14:30:58.207602Z","end":"2026-08-22T14:30:58.78241Z","steps":["trace[175821871] 'process raft request' (duration: 574.592067ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:58.782481Z","caller":"traceutil/trace.go:171","msg":"trace[363281772] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"571.529167ms","start":"2026-08-22T14:30:58.210924Z","end":"2026-08-22T14:30:58.782453Z","steps":["trace[363281772] 'process raft request' (duration: 571.325204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.78263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:58.207596Z","time spent":"574.947372ms","remote":"127.0.0.1:46716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3576,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:30:58.782822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:58.210901Z","time spent":"571.70439ms","remote":"127.0.0.1:46716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15061,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:30:58.782995Z","caller":"traceutil/trace.go:171","msg":"trace[768593585] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"570.495481ms","start":"2026-08-22T14:30:58.212479Z","end":"2026-08-22T14:30:58.782974Z","steps":["trace[768593585] 'process raft request' (duration: 569.915802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.782842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:58.207426Z","time spent":"575.095824ms","remote":"127.0.0.1:46590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:30:58.783059Z","caller":"traceutil/trace.go:171","msg":"trace[708623041] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"565.551741ms","start":"2026-08-22T14:30:58.217492Z","end":"2026-08-22T14:30:58.783044Z","steps":["trace[708623041] 'process raft request' (duration: 565.47987ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:58.783224Z","caller":"traceutil/trace.go:171","msg":"trace[1293289105] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"570.607312ms","start":"2026-08-22T14:30:58.212606Z","end":"2026-08-22T14:30:58.783213Z","steps":["trace[1293289105] 'process raft request' (duration: 569.82888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.783302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:58.2126Z","time spent":"570.660263ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:30:58.78336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:58.217457Z","time spent":"565.758935ms","remote":"127.0.0.1:46974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14448,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:30:58.783183Z","caller":"traceutil/trace.go:171","msg":"trace[474139898] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"568.877964ms","start":"2026-08-22T14:30:58.214291Z","end":"2026-08-22T14:30:58.783169Z","steps":["trace[474139898] 'process raft request' (duration: 568.546619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.783504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:58.214267Z","time spent":"569.21185ms","remote":"127.0.0.1:46590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:30:58.783124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:58.212469Z","time spent":"570.586972ms","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4320,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:30:58.786483Z","caller":"traceutil/trace.go:171","msg":"trace[1105310257] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"504.01789ms","start":"2026-08-22T14:30:58.282438Z","end":"2026-08-22T14:30:58.786456Z","steps":["trace[1105310257] 'process raft request' (duration: 503.709065ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:58.786542Z","caller":"traceutil/trace.go:171","msg":"trace[1360740526] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:543; }","duration":"494.453675ms","start":"2026-08-22T14:30:58.292062Z","end":"2026-08-22T14:30:58.786516Z","steps":["trace[1360740526] 'read index received' (duration: 391.559438ms)","trace[1360740526] 'applied index is now lower than readState.Index' (duration: 102.892507ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:30:58.786603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:58.282424Z","time spent":"504.120941ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:30:58.786972Z","caller":"traceutil/trace.go:171","msg":"trace[1273696062] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"506.571461ms","start":"2026-08-22T14:30:58.280383Z","end":"2026-08-22T14:30:58.786954Z","steps":["trace[1273696062] 'process raft request' (duration: 504.823712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.787065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:58.280332Z","time spent":"506.685583ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:30:58.78714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.042805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-08-22T14:30:58.787176Z","caller":"traceutil/trace.go:171","msg":"trace[1675355703] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"506.439929ms","start":"2026-08-22T14:30:58.280723Z","end":"2026-08-22T14:30:58.787163Z","steps":["trace[1675355703] 'process raft request' (duration: 505.350851ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:58.787216Z","caller":"traceutil/trace.go:171","msg":"trace[338746989] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:530; }","duration":"495.137806ms","start":"2026-08-22T14:30:58.292056Z","end":"2026-08-22T14:30:58.787194Z","steps":["trace[338746989] 'agreement among raft nodes before linearized reading' (duration: 494.572197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:58.787237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:58.28071Z","time spent":"506.49495ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:30:58.787272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:30:58.292049Z","time spent":"495.208647ms","remote":"127.0.0.1:46614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329978,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-08-22T14:30:59.080958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.439055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:30:59.0811Z","caller":"traceutil/trace.go:171","msg":"trace[216146070] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"252.901175ms","start":"2026-08-22T14:30:58.828175Z","end":"2026-08-22T14:30:59.081076Z","steps":["trace[216146070] 'process raft request' (duration: 113.241635ms)","trace[216146070] 'compare' (duration: 139.270793ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:59.083343Z","caller":"traceutil/trace.go:171","msg":"trace[2064547625] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:557; }","duration":"200.51672ms","start":"2026-08-22T14:30:58.882804Z","end":"2026-08-22T14:30:59.083321Z","steps":["trace[2064547625] 'read index received' (duration: 58.706116ms)","trace[2064547625] 'applied index is now lower than readState.Index' (duration: 141.809584ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:59.083464Z","caller":"traceutil/trace.go:171","msg":"trace[2083129139] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"242.808712ms","start":"2026-08-22T14:30:58.840648Z","end":"2026-08-22T14:30:59.083456Z","steps":["trace[2083129139] 'process raft request' (duration: 242.493746ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:59.083733Z","caller":"traceutil/trace.go:171","msg":"trace[1025841647] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"239.726193ms","start":"2026-08-22T14:30:58.844Z","end":"2026-08-22T14:30:59.083726Z","steps":["trace[1025841647] 'process raft request' (duration: 239.242795ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:59.083885Z","caller":"traceutil/trace.go:171","msg":"trace[395951835] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"239.691593ms","start":"2026-08-22T14:30:58.844187Z","end":"2026-08-22T14:30:59.083879Z","steps":["trace[395951835] 'process raft request' (duration: 239.096303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:59.084456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.172333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-08-22T14:30:59.084496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.682841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-08-22T14:30:59.084532Z","caller":"traceutil/trace.go:171","msg":"trace[1548197078] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:538; }","duration":"201.769762ms","start":"2026-08-22T14:30:58.88275Z","end":"2026-08-22T14:30:59.08452Z","steps":["trace[1548197078] 'agreement among raft nodes before linearized reading' (duration: 201.204153ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:59.084538Z","caller":"traceutil/trace.go:171","msg":"trace[1569387417] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:538; }","duration":"129.477608ms","start":"2026-08-22T14:30:58.955039Z","end":"2026-08-22T14:30:59.084517Z","steps":["trace[1569387417] 'agreement among raft nodes before linearized reading' (duration: 129.057011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:30:59.084922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.66263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-08-22T14:30:59.084951Z","caller":"traceutil/trace.go:171","msg":"trace[2030181789] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:538; }","duration":"129.753862ms","start":"2026-08-22T14:30:58.955189Z","end":"2026-08-22T14:30:59.084943Z","steps":["trace[2030181789] 'agreement among raft nodes before linearized reading' (duration: 129.61843ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:30:59.251616Z","caller":"traceutil/trace.go:171","msg":"trace[85866736] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"158.221001ms","start":"2026-08-22T14:30:59.093357Z","end":"2026-08-22T14:30:59.251578Z","steps":["trace[85866736] 'process raft request' (duration: 142.144524ms)","trace[85866736] 'compare' (duration: 15.932605ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:30:59.258465Z","caller":"traceutil/trace.go:171","msg":"trace[774053391] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"160.51004ms","start":"2026-08-22T14:30:59.097935Z","end":"2026-08-22T14:30:59.258445Z","steps":["trace[774053391] 'process raft request' (duration: 160.274426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:01.783551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.363618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:31:01.784248Z","caller":"traceutil/trace.go:171","msg":"trace[234097526] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"342.116109ms","start":"2026-08-22T14:31:01.442112Z","end":"2026-08-22T14:31:01.784228Z","steps":["trace[234097526] 'process raft request' (duration: 183.198214ms)","trace[234097526] 'compare' (duration: 156.89513ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:31:01.784331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:01.442086Z","time spent":"342.209122ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:31:01.809795Z","caller":"traceutil/trace.go:171","msg":"trace[1812152420] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"322.902351ms","start":"2026-08-22T14:31:01.486871Z","end":"2026-08-22T14:31:01.809773Z","steps":["trace[1812152420] 'process raft request' (duration: 322.710408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:01.80989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:01.48685Z","time spent":"322.999713ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:31:01.811168Z","caller":"traceutil/trace.go:171","msg":"trace[1993186858] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"201.015989ms","start":"2026-08-22T14:31:01.610136Z","end":"2026-08-22T14:31:01.811152Z","steps":["trace[1993186858] 'process raft request' (duration: 200.208745ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:03.581415Z","caller":"traceutil/trace.go:171","msg":"trace[1136545428] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"102.841108ms","start":"2026-08-22T14:31:03.478292Z","end":"2026-08-22T14:31:03.581134Z","steps":["trace[1136545428] 'process raft request' (duration: 102.667545ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:04.388556Z","caller":"traceutil/trace.go:171","msg":"trace[643646483] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"116.258793ms","start":"2026-08-22T14:31:04.272272Z","end":"2026-08-22T14:31:04.388531Z","steps":["trace[643646483] 'process raft request' (duration: 113.144124ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:04.472031Z","caller":"traceutil/trace.go:171","msg":"trace[706468325] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"123.212607ms","start":"2026-08-22T14:31:04.348797Z","end":"2026-08-22T14:31:04.472009Z","steps":["trace[706468325] 'read index received' (duration: 36.631211ms)","trace[706468325] 'applied index is now lower than readState.Index' (duration: 86.580466ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:31:04.472107Z","caller":"traceutil/trace.go:171","msg":"trace[1114146123] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"193.749555ms","start":"2026-08-22T14:31:04.278332Z","end":"2026-08-22T14:31:04.472081Z","steps":["trace[1114146123] 'process raft request' (duration: 193.556822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:04.47216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.347999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-22T14:31:04.472201Z","caller":"traceutil/trace.go:171","msg":"trace[1062220083] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:647; }","duration":"123.447901ms","start":"2026-08-22T14:31:04.348743Z","end":"2026-08-22T14:31:04.472191Z","steps":["trace[1062220083] 'agreement among raft nodes before linearized reading' (duration: 123.3565ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:04.974674Z","caller":"traceutil/trace.go:171","msg":"trace[1964910499] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"300.64291ms","start":"2026-08-22T14:31:04.673981Z","end":"2026-08-22T14:31:04.974624Z","steps":["trace[1964910499] 'process raft request' (duration: 300.16275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:04.974825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:04.673961Z","time spent":"300.798103ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:31:05.016701Z","caller":"traceutil/trace.go:171","msg":"trace[305765578] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"316.693042ms","start":"2026-08-22T14:31:04.699977Z","end":"2026-08-22T14:31:05.01667Z","steps":["trace[305765578] 'process raft request' (duration: 304.517204ms)","trace[305765578] 'compare' (duration: 11.610388ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:31:05.016872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:04.699955Z","time spent":"316.827876ms","remote":"127.0.0.1:46614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:31:05.017525Z","caller":"traceutil/trace.go:171","msg":"trace[147357737] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:684; }","duration":"121.228356ms","start":"2026-08-22T14:31:04.896238Z","end":"2026-08-22T14:31:05.017467Z","steps":["trace[147357737] 'read index received' (duration: 77.552134ms)","trace[147357737] 'applied index is now lower than readState.Index' (duration: 43.675252ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:31:05.017633Z","caller":"traceutil/trace.go:171","msg":"trace[646346903] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"145.176503ms","start":"2026-08-22T14:31:04.872447Z","end":"2026-08-22T14:31:05.017623Z","steps":["trace[646346903] 'process raft request' (duration: 144.187573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:05.018141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.901529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-22T14:31:05.018194Z","caller":"traceutil/trace.go:171","msg":"trace[1471399537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"122.003221ms","start":"2026-08-22T14:31:04.896175Z","end":"2026-08-22T14:31:05.018178Z","steps":["trace[1471399537] 'agreement among raft nodes before linearized reading' (duration: 121.46935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:05.68802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680222037096736100,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:31:06.188224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680222037096736100,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:31:06.247711Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.143457817s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T14:31:06.248105Z","caller":"traceutil/trace.go:171","msg":"trace[856415314] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.221440956s","start":"2026-08-22T14:31:05.026645Z","end":"2026-08-22T14:31:06.248086Z","steps":["trace[856415314] 'process raft request' (duration: 1.221305414s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:06.248262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:05.026634Z","time spent":"1.221526438s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:31:06.248497Z","caller":"traceutil/trace.go:171","msg":"trace[1078802026] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.214913909s","start":"2026-08-22T14:31:05.03355Z","end":"2026-08-22T14:31:06.248464Z","steps":["trace[1078802026] 'process raft request' (duration: 1.214496621s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:06.248586Z","caller":"traceutil/trace.go:171","msg":"trace[2066876306] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.214808696s","start":"2026-08-22T14:31:05.033719Z","end":"2026-08-22T14:31:06.248527Z","steps":["trace[2066876306] 'process raft request' (duration: 1.214606502s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:06.248729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:05.033533Z","time spent":"1.215057151s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:31:06.248897Z","caller":"traceutil/trace.go:171","msg":"trace[568850325] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.215139273s","start":"2026-08-22T14:31:05.033747Z","end":"2026-08-22T14:31:06.248886Z","steps":["trace[568850325] 'process raft request' (duration: 1.214712515s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:06.2489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:05.033713Z","time spent":"1.2149616s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:31:06.248962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:05.033744Z","time spent":"1.215188204s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:31:06.249025Z","caller":"traceutil/trace.go:171","msg":"trace[118249278] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.211748026s","start":"2026-08-22T14:31:05.037259Z","end":"2026-08-22T14:31:06.249007Z","steps":["trace[118249278] 'process raft request' (duration: 1.211493291s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:06.249097Z","caller":"traceutil/trace.go:171","msg":"trace[675929136] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.178993884s","start":"2026-08-22T14:31:05.070094Z","end":"2026-08-22T14:31:06.249088Z","steps":["trace[675929136] 'process raft request' (duration: 1.17879999s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:06.249116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:05.037242Z","time spent":"1.211832078s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:31:06.249138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:05.070078Z","time spent":"1.179042096s","remote":"127.0.0.1:46728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:31:06.249036Z","caller":"traceutil/trace.go:171","msg":"trace[1183352926] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"1.175142879s","start":"2026-08-22T14:31:05.07388Z","end":"2026-08-22T14:31:06.249023Z","steps":["trace[1183352926] 'process raft request' (duration: 1.175060907s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:06.249245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:05.07387Z","time spent":"1.175351403s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:31:06.257368Z","caller":"traceutil/trace.go:171","msg":"trace[325277652] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:689; }","duration":"1.069680166s","start":"2026-08-22T14:31:05.187525Z","end":"2026-08-22T14:31:06.257205Z","steps":["trace[325277652] 'read index received' (duration: 1.060457171s)","trace[325277652] 'applied index is now lower than readState.Index' (duration: 9.222015ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:31:06.258661Z","caller":"traceutil/trace.go:171","msg":"trace[1551894188] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"985.13195ms","start":"2026-08-22T14:31:05.27352Z","end":"2026-08-22T14:31:06.258652Z","steps":["trace[1551894188] 'process raft request' (duration: 983.024057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:06.258881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:05.273496Z","time spent":"985.18954ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:31:06.259033Z","caller":"traceutil/trace.go:171","msg":"trace[1811883744] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"388.319471ms","start":"2026-08-22T14:31:05.870707Z","end":"2026-08-22T14:31:06.259027Z","steps":["trace[1811883744] 'process raft request' (duration: 385.960524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:06.259066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:05.87068Z","time spent":"388.371963ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:31:06.259266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071745298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:06.259292Z","caller":"traceutil/trace.go:171","msg":"trace[226772226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"1.071812379s","start":"2026-08-22T14:31:05.187474Z","end":"2026-08-22T14:31:06.259286Z","steps":["trace[226772226] 'agreement among raft nodes before linearized reading' (duration: 1.071751788s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:06.259355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:05.187454Z","time spent":"1.07189426s","remote":"127.0.0.1:46506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:31:06.259677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.531525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:06.25978Z","caller":"traceutil/trace.go:171","msg":"trace[1453421294] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:668; }","duration":"789.682778ms","start":"2026-08-22T14:31:05.470077Z","end":"2026-08-22T14:31:06.25976Z","steps":["trace[1453421294] 'agreement among raft nodes before linearized reading' (duration: 789.534146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:06.259841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:05.470051Z","time spent":"789.779701ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-08-22T14:31:06.260058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.908282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:06.260111Z","caller":"traceutil/trace.go:171","msg":"trace[1712552050] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:668; }","duration":"590.010314ms","start":"2026-08-22T14:31:05.670093Z","end":"2026-08-22T14:31:06.260103Z","steps":["trace[1712552050] 'agreement among raft nodes before linearized reading' (duration: 589.942403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:06.260154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:05.670068Z","time spent":"590.078385ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-08-22T14:31:06.260736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.383381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-08-22T14:31:06.260776Z","caller":"traceutil/trace.go:171","msg":"trace[1488876721] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:668; }","duration":"190.464192ms","start":"2026-08-22T14:31:06.0703Z","end":"2026-08-22T14:31:06.260764Z","steps":["trace[1488876721] 'agreement among raft nodes before linearized reading' (duration: 190.33749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:06.260846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.299654ms","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-08-22T14:31:06.260875Z","caller":"traceutil/trace.go:171","msg":"trace[2073871579] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:668; }","duration":"381.466548ms","start":"2026-08-22T14:31:05.879402Z","end":"2026-08-22T14:31:06.260869Z","steps":["trace[2073871579] 'agreement among raft nodes before linearized reading' (duration: 380.268414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:06.260927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:05.879389Z","time spent":"381.530029ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T14:31:08.171058Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680222037096736132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:31:08.672085Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680222037096736132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:31:08.691143Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.497007895s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T14:31:08.69296Z","caller":"traceutil/trace.go:171","msg":"trace[895486165] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"1.500223853s","start":"2026-08-22T14:31:07.192711Z","end":"2026-08-22T14:31:08.692935Z","steps":["trace[895486165] 'process raft request' (duration: 1.498858514s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:08.693455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:07.192691Z","time spent":"1.500345665s","remote":"127.0.0.1:46614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:31:08.741669Z","caller":"traceutil/trace.go:171","msg":"trace[1135612896] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:709; }","duration":"1.071170646s","start":"2026-08-22T14:31:07.67046Z","end":"2026-08-22T14:31:08.74163Z","steps":["trace[1135612896] 'read index received' (duration: 1.020961172s)","trace[1135612896] 'applied index is now lower than readState.Index' (duration: 50.208904ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:31:08.741663Z","caller":"traceutil/trace.go:171","msg":"trace[1976061609] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.42402943s","start":"2026-08-22T14:31:07.31759Z","end":"2026-08-22T14:31:08.741619Z","steps":["trace[1976061609] 'process raft request' (duration: 1.423797246s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:08.741748Z","caller":"traceutil/trace.go:171","msg":"trace[812180220] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.23499852s","start":"2026-08-22T14:31:07.506744Z","end":"2026-08-22T14:31:08.741743Z","steps":["trace[812180220] 'process raft request' (duration: 1.234810626s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:08.741831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:07.506707Z","time spent":"1.235077812s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T14:31:08.741876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:07.317569Z","time spent":"1.424181833s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T14:31:08.741991Z","caller":"traceutil/trace.go:171","msg":"trace[516363058] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"246.096155ms","start":"2026-08-22T14:31:08.495889Z","end":"2026-08-22T14:31:08.741986Z","steps":["trace[516363058] 'process raft request' (duration: 245.705127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:08.742123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.615633ms","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-08-22T14:31:08.742146Z","caller":"traceutil/trace.go:171","msg":"trace[1586234689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"488.663554ms","start":"2026-08-22T14:31:08.253477Z","end":"2026-08-22T14:31:08.742141Z","steps":["trace[1586234689] 'agreement among raft nodes before linearized reading' (duration: 488.578752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:08.74216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:08.253468Z","time spent":"488.689224ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T14:31:08.742181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.259752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-08-22T14:31:08.742206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.801411ms","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-08-22T14:31:08.742227Z","caller":"traceutil/trace.go:171","msg":"trace[1178301349] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"871.355524ms","start":"2026-08-22T14:31:07.870858Z","end":"2026-08-22T14:31:08.742213Z","steps":["trace[1178301349] 'agreement among raft nodes before linearized reading' (duration: 871.18483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:08.742243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.183244ms","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-08-22T14:31:08.742244Z","caller":"traceutil/trace.go:171","msg":"trace[2134536930] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"452.896203ms","start":"2026-08-22T14:31:08.289339Z","end":"2026-08-22T14:31:08.742235Z","steps":["trace[2134536930] 'agreement among raft nodes before linearized reading' (duration: 452.793481ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:08.74226Z","caller":"traceutil/trace.go:171","msg":"trace[228298326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"488.219885ms","start":"2026-08-22T14:31:08.254036Z","end":"2026-08-22T14:31:08.742256Z","steps":["trace[228298326] 'agreement among raft nodes before linearized reading' (duration: 488.179314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:08.742273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:08.254026Z","time spent":"488.243606ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T14:31:08.742268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:07.870826Z","time spent":"871.434266ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-08-22T14:31:08.742326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07187531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-08-22T14:31:08.742354Z","caller":"traceutil/trace.go:171","msg":"trace[1452958395] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"1.071945271s","start":"2026-08-22T14:31:07.6704Z","end":"2026-08-22T14:31:08.742346Z","steps":["trace[1452958395] 'agreement among raft nodes before linearized reading' (duration: 1.07188212s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:08.742372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:07.670374Z","time spent":"1.071993153s","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-08-22T14:31:08.742404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.519781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-22T14:31:08.742422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.719087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:31:08.742271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:08.289312Z","time spent":"452.953284ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T14:31:08.742439Z","caller":"traceutil/trace.go:171","msg":"trace[925752738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"551.780798ms","start":"2026-08-22T14:31:08.190653Z","end":"2026-08-22T14:31:08.742434Z","steps":["trace[925752738] 'agreement among raft nodes before linearized reading' (duration: 551.755758ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:08.742438Z","caller":"traceutil/trace.go:171","msg":"trace[646824095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:683; }","duration":"489.596603ms","start":"2026-08-22T14:31:08.252832Z","end":"2026-08-22T14:31:08.742429Z","steps":["trace[646824095] 'agreement among raft nodes before linearized reading' (duration: 489.511031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:08.742456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:08.190633Z","time spent":"551.81971ms","remote":"127.0.0.1:46506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:31:08.742466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:08.252809Z","time spent":"489.651125ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T14:31:08.742603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.601444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-08-22T14:31:08.742666Z","caller":"traceutil/trace.go:171","msg":"trace[536407154] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"672.679176ms","start":"2026-08-22T14:31:08.069973Z","end":"2026-08-22T14:31:08.742653Z","steps":["trace[536407154] 'agreement among raft nodes before linearized reading' (duration: 672.487692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:08.742694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:08.069908Z","time spent":"672.781337ms","remote":"127.0.0.1:46566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-08-22T14:31:09.361743Z","caller":"traceutil/trace.go:171","msg":"trace[943306734] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"112.764986ms","start":"2026-08-22T14:31:09.248953Z","end":"2026-08-22T14:31:09.361718Z","steps":["trace[943306734] 'process raft request' (duration: 71.682716ms)","trace[943306734] 'compare' (duration: 40.849895ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:31:10.004617Z","caller":"traceutil/trace.go:171","msg":"trace[1341167830] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:727; }","duration":"108.575997ms","start":"2026-08-22T14:31:09.896019Z","end":"2026-08-22T14:31:10.004595Z","steps":["trace[1341167830] 'read index received' (duration: 26.958478ms)","trace[1341167830] 'applied index is now lower than readState.Index' (duration: 81.616709ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:31:10.004816Z","caller":"traceutil/trace.go:171","msg":"trace[513109238] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"120.143325ms","start":"2026-08-22T14:31:09.884591Z","end":"2026-08-22T14:31:10.004735Z","steps":["trace[513109238] 'process raft request' (duration: 119.94794ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:10.004862Z","caller":"traceutil/trace.go:171","msg":"trace[204837362] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"139.793246ms","start":"2026-08-22T14:31:09.865042Z","end":"2026-08-22T14:31:10.004835Z","steps":["trace[204837362] 'process raft request' (duration: 57.924691ms)","trace[204837362] 'compare' (duration: 81.384574ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:31:10.004966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.943476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:10.005139Z","caller":"traceutil/trace.go:171","msg":"trace[813761428] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:698; }","duration":"109.141399ms","start":"2026-08-22T14:31:09.895987Z","end":"2026-08-22T14:31:10.005129Z","steps":["trace[813761428] 'agreement among raft nodes before linearized reading' (duration: 108.959155ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:10.004899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.811133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:10.005471Z","caller":"traceutil/trace.go:171","msg":"trace[846505128] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:698; }","duration":"109.412805ms","start":"2026-08-22T14:31:09.896044Z","end":"2026-08-22T14:31:10.005457Z","steps":["trace[846505128] 'agreement among raft nodes before linearized reading' (duration: 108.774481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:10.006234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.582989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:10.006289Z","caller":"traceutil/trace.go:171","msg":"trace[584629856] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:698; }","duration":"109.668602ms","start":"2026-08-22T14:31:09.896608Z","end":"2026-08-22T14:31:10.006277Z","steps":["trace[584629856] 'agreement among raft nodes before linearized reading' (duration: 109.574179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:14.153003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.908126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gtjpj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-22T14:31:14.153085Z","caller":"traceutil/trace.go:171","msg":"trace[1951884736] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gtjpj; range_end:; response_count:1; response_revision:719; }","duration":"876.03479ms","start":"2026-08-22T14:31:13.277031Z","end":"2026-08-22T14:31:14.153065Z","steps":["trace[1951884736] 'range keys from in-memory index tree' (duration: 875.734493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:14.153125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:13.277012Z","time spent":"876.102572ms","remote":"127.0.0.1:46716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-gtjpj\" "} {"level":"warn","ts":"2026-08-22T14:31:14.153118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.682695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:14.153199Z","caller":"traceutil/trace.go:171","msg":"trace[1122801477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"927.796427ms","start":"2026-08-22T14:31:13.225381Z","end":"2026-08-22T14:31:14.153177Z","steps":["trace[1122801477] 'range keys from in-memory index tree' (duration: 927.606273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:14.15319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.370378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-08-22T14:31:14.153204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.475557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-08-22T14:31:14.15324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:13.225345Z","time spent":"927.886349ms","remote":"127.0.0.1:46510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T14:31:14.153267Z","caller":"traceutil/trace.go:171","msg":"trace[1177185069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:719; }","duration":"927.45704ms","start":"2026-08-22T14:31:13.225787Z","end":"2026-08-22T14:31:14.153244Z","steps":["trace[1177185069] 'range keys from in-memory index tree' (duration: 927.308586ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:14.153286Z","caller":"traceutil/trace.go:171","msg":"trace[1292460020] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"355.598892ms","start":"2026-08-22T14:31:13.797671Z","end":"2026-08-22T14:31:14.15327Z","steps":["trace[1292460020] 'process raft request' (duration: 19.983512ms)","trace[1292460020] 'compare' (duration: 334.73787ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:31:14.153308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:13.225774Z","time spent":"927.525811ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T14:31:14.153342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:13.797652Z","time spent":"355.662484ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:31:14.866374Z","caller":"traceutil/trace.go:171","msg":"trace[830585401] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:751; }","duration":"674.912281ms","start":"2026-08-22T14:31:14.191436Z","end":"2026-08-22T14:31:14.866348Z","steps":["trace[830585401] 'read index received' (duration: 31.473032ms)","trace[830585401] 'applied index is now lower than readState.Index' (duration: 643.438269ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:31:14.866843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.311947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:31:14.86689Z","caller":"traceutil/trace.go:171","msg":"trace[1836573741] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:722; }","duration":"244.414039ms","start":"2026-08-22T14:31:14.622464Z","end":"2026-08-22T14:31:14.866878Z","steps":["trace[1836573741] 'agreement among raft nodes before linearized reading' (duration: 244.326127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:14.866973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.495667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-22T14:31:14.867184Z","caller":"traceutil/trace.go:171","msg":"trace[2034380560] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:722; }","duration":"518.823745ms","start":"2026-08-22T14:31:14.348337Z","end":"2026-08-22T14:31:14.867161Z","steps":["trace[2034380560] 'agreement among raft nodes before linearized reading' (duration: 518.277041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:14.867331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:14.34832Z","time spent":"518.993138ms","remote":"127.0.0.1:46550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"info","ts":"2026-08-22T14:31:14.867269Z","caller":"traceutil/trace.go:171","msg":"trace[97948856] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"697.75856ms","start":"2026-08-22T14:31:14.169305Z","end":"2026-08-22T14:31:14.867064Z","steps":["trace[97948856] 'process raft request' (duration: 696.801226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:14.867535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.064849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:14.867662Z","caller":"traceutil/trace.go:171","msg":"trace[2007423538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:722; }","duration":"676.225123ms","start":"2026-08-22T14:31:14.191406Z","end":"2026-08-22T14:31:14.867632Z","steps":["trace[2007423538] 'agreement among raft nodes before linearized reading' (duration: 676.022618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:14.867727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:14.191394Z","time spent":"676.323455ms","remote":"127.0.0.1:46506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:31:14.867875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:14.169273Z","time spent":"698.323793ms","remote":"127.0.0.1:46716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:31:15.281203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.685222ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-08-22T14:31:15.281303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:14.869932Z","time spent":"411.366665ms","remote":"127.0.0.1:46550","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-22T14:31:16.42796Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.457541827s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T14:31:16.42835Z","caller":"traceutil/trace.go:171","msg":"trace[2125438453] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"1.528097201s","start":"2026-08-22T14:31:14.900235Z","end":"2026-08-22T14:31:16.428332Z","steps":["trace[2125438453] 'process raft request' (duration: 1.527857735s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:16.42849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:14.900213Z","time spent":"1.528174732s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:31:16.428707Z","caller":"traceutil/trace.go:171","msg":"trace[1709157112] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"1.52766534s","start":"2026-08-22T14:31:14.901023Z","end":"2026-08-22T14:31:16.428689Z","steps":["trace[1709157112] 'process raft request' (duration: 1.527465085s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:16.428694Z","caller":"traceutil/trace.go:171","msg":"trace[79614679] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"1.527641309s","start":"2026-08-22T14:31:14.901024Z","end":"2026-08-22T14:31:16.428665Z","steps":["trace[79614679] 'process raft request' (duration: 1.52724821s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:16.428829Z","caller":"traceutil/trace.go:171","msg":"trace[1573000613] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"1.527576588s","start":"2026-08-22T14:31:14.901173Z","end":"2026-08-22T14:31:16.42875Z","steps":["trace[1573000613] 'process raft request' (duration: 1.527470415s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:16.428869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:14.901003Z","time spent":"1.527761522s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:31:16.428923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:14.901011Z","time spent":"1.527768373s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:31:16.428992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:14.901154Z","time spent":"1.527735671s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:31:16.499459Z","caller":"traceutil/trace.go:171","msg":"trace[1864064301] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:755; }","duration":"490.644536ms","start":"2026-08-22T14:31:16.008803Z","end":"2026-08-22T14:31:16.499447Z","steps":["trace[1864064301] 'read index received' (duration: 419.474932ms)","trace[1864064301] 'applied index is now lower than readState.Index' (duration: 71.168924ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:31:16.499698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.884991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:31:16.49974Z","caller":"traceutil/trace.go:171","msg":"trace[1946759388] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:727; }","duration":"490.967363ms","start":"2026-08-22T14:31:16.008761Z","end":"2026-08-22T14:31:16.499728Z","steps":["trace[1946759388] 'agreement among raft nodes before linearized reading' (duration: 490.758218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:16.499798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:16.008742Z","time spent":"491.046425ms","remote":"127.0.0.1:47002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":57,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:31:16.499798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.916799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-22T14:31:16.49985Z","caller":"traceutil/trace.go:171","msg":"trace[372563710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:727; }","duration":"272.997051ms","start":"2026-08-22T14:31:16.22684Z","end":"2026-08-22T14:31:16.499837Z","steps":["trace[372563710] 'agreement among raft nodes before linearized reading' (duration: 272.804256ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:16.499712Z","caller":"traceutil/trace.go:171","msg":"trace[842315999] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"1.217056736s","start":"2026-08-22T14:31:15.282437Z","end":"2026-08-22T14:31:16.499494Z","steps":["trace[842315999] 'process raft request' (duration: 1.216954803s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:16.500127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.057946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:16.500204Z","caller":"traceutil/trace.go:171","msg":"trace[1655458386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"310.175989ms","start":"2026-08-22T14:31:16.190018Z","end":"2026-08-22T14:31:16.500194Z","steps":["trace[1655458386] 'agreement among raft nodes before linearized reading' (duration: 309.552973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:16.500349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:16.189999Z","time spent":"310.338112ms","remote":"127.0.0.1:46506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:31:16.500322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:15.282413Z","time spent":"1.217672172s","remote":"127.0.0.1:46550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:31:16.643724Z","caller":"traceutil/trace.go:171","msg":"trace[1221086239] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"138.061974ms","start":"2026-08-22T14:31:16.505635Z","end":"2026-08-22T14:31:16.643697Z","steps":["trace[1221086239] 'read index received' (duration: 82.117721ms)","trace[1221086239] 'applied index is now lower than readState.Index' (duration: 55.943243ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:31:16.643882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.224008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T14:31:16.643925Z","caller":"traceutil/trace.go:171","msg":"trace[2011761754] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:728; }","duration":"138.31649ms","start":"2026-08-22T14:31:16.505598Z","end":"2026-08-22T14:31:16.643915Z","steps":["trace[2011761754] 'agreement among raft nodes before linearized reading' (duration: 138.198428ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:16.644133Z","caller":"traceutil/trace.go:171","msg":"trace[845613918] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"139.91603ms","start":"2026-08-22T14:31:16.504173Z","end":"2026-08-22T14:31:16.644089Z","steps":["trace[845613918] 'process raft request' (duration: 83.630778ms)","trace[845613918] 'compare' (duration: 55.769629ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:31:19.692698Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680222037096736246,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:31:20.193165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680222037096736246,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:31:20.59395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.637450476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:20.594044Z","caller":"traceutil/trace.go:171","msg":"trace[1759072051] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:728; }","duration":"3.63762092s","start":"2026-08-22T14:31:16.956397Z","end":"2026-08-22T14:31:20.594018Z","steps":["trace[1759072051] 'count revisions from in-memory index tree' (duration: 3.637320612s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:20.594018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.383865826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:31:20.594087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:16.956377Z","time spent":"3.637698623s","remote":"127.0.0.1:46970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-08-22T14:31:20.594117Z","caller":"traceutil/trace.go:171","msg":"trace[113155922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"2.383969609s","start":"2026-08-22T14:31:18.210123Z","end":"2026-08-22T14:31:20.594092Z","steps":["trace[113155922] 'range keys from in-memory index tree' (duration: 2.383775844s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:20.594283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.402628332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:20.594337Z","caller":"traceutil/trace.go:171","msg":"trace[1128135465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"3.402714863s","start":"2026-08-22T14:31:17.191607Z","end":"2026-08-22T14:31:20.594322Z","steps":["trace[1128135465] 'range keys from in-memory index tree' (duration: 3.402511148s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:20.594385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:17.191588Z","time spent":"3.402783515s","remote":"127.0.0.1:46506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:31:20.594525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.161531845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-22T14:31:20.594557Z","caller":"traceutil/trace.go:171","msg":"trace[1949089707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:728; }","duration":"2.161572556s","start":"2026-08-22T14:31:18.432977Z","end":"2026-08-22T14:31:20.594549Z","steps":["trace[1949089707] 'range keys from in-memory index tree' (duration: 2.161428162s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:20.594574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:18.432974Z","time spent":"2.161596576s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T14:31:20.594683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.161475633s","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-08-22T14:31:20.59471Z","caller":"traceutil/trace.go:171","msg":"trace[1837017335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:728; }","duration":"2.161551166s","start":"2026-08-22T14:31:18.433154Z","end":"2026-08-22T14:31:20.594705Z","steps":["trace[1837017335] 'range keys from in-memory index tree' (duration: 2.16135633s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:20.594724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:18.433121Z","time spent":"2.161599556s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T14:31:20.594284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:18.210059Z","time spent":"2.384207855s","remote":"127.0.0.1:46506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:31:20.595082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.16213327s","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-08-22T14:31:20.595127Z","caller":"traceutil/trace.go:171","msg":"trace[1978306993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:728; }","duration":"2.162186541s","start":"2026-08-22T14:31:18.432929Z","end":"2026-08-22T14:31:20.595116Z","steps":["trace[1978306993] 'range keys from in-memory index tree' (duration: 2.162056098s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:20.595129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.330938ms","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-08-22T14:31:20.595163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:18.432926Z","time spent":"2.162229752s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T14:31:20.595074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.162258834s","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-08-22T14:31:20.595291Z","caller":"traceutil/trace.go:171","msg":"trace[498054612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:728; }","duration":"2.16251778s","start":"2026-08-22T14:31:18.432759Z","end":"2026-08-22T14:31:20.595277Z","steps":["trace[498054612] 'range keys from in-memory index tree' (duration: 2.16211399s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:20.595342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:18.432745Z","time spent":"2.162583013s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T14:31:20.595167Z","caller":"traceutil/trace.go:171","msg":"trace[85345529] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:728; }","duration":"410.37173ms","start":"2026-08-22T14:31:20.184791Z","end":"2026-08-22T14:31:20.595163Z","steps":["trace[85345529] 'range keys from in-memory index tree' (duration: 410.324338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:20.593969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.945892702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-22T14:31:20.595539Z","caller":"traceutil/trace.go:171","msg":"trace[1252292846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:728; }","duration":"1.947506884s","start":"2026-08-22T14:31:18.648013Z","end":"2026-08-22T14:31:20.595519Z","steps":["trace[1252292846] 'range keys from in-memory index tree' (duration: 1.945699318s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:20.595647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:18.647992Z","time spent":"1.947638708s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T14:31:20.610939Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.865828222s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T14:31:20.611355Z","caller":"traceutil/trace.go:171","msg":"trace[1767108386] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"1.866368576s","start":"2026-08-22T14:31:18.74497Z","end":"2026-08-22T14:31:20.611338Z","steps":["trace[1767108386] 'process raft request' (duration: 1.866259313s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:20.611472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:18.744938Z","time spent":"1.866452077s","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4790,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:31:20.646335Z","caller":"traceutil/trace.go:171","msg":"trace[516230060] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"1.654182317s","start":"2026-08-22T14:31:18.992129Z","end":"2026-08-22T14:31:20.646311Z","steps":["trace[516230060] 'process raft request' (duration: 1.654024813s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:20.646468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:18.992104Z","time spent":"1.654297439s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:31:20.666869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:20.213999Z","time spent":"452.862921ms","remote":"127.0.0.1:46590","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-22T14:31:20.667471Z","caller":"traceutil/trace.go:171","msg":"trace[893533441] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:760; }","duration":"1.475144567s","start":"2026-08-22T14:31:19.192281Z","end":"2026-08-22T14:31:20.667425Z","steps":["trace[893533441] 'read index received' (duration: 1.418940171s)","trace[893533441] 'applied index is now lower than readState.Index' (duration: 56.203596ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:31:20.667671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475380053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:20.66774Z","caller":"traceutil/trace.go:171","msg":"trace[1454243632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"1.475491396s","start":"2026-08-22T14:31:19.192231Z","end":"2026-08-22T14:31:20.667722Z","steps":["trace[1454243632] 'agreement among raft nodes before linearized reading' (duration: 1.475378003s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:20.667787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:19.19221Z","time spent":"1.475564868s","remote":"127.0.0.1:46510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:31:20.667846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.418638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:20.667936Z","caller":"traceutil/trace.go:171","msg":"trace[362208168] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:735; }","duration":"179.641633ms","start":"2026-08-22T14:31:20.488283Z","end":"2026-08-22T14:31:20.667924Z","steps":["trace[362208168] 'agreement among raft nodes before linearized reading' (duration: 179.383647ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:33.558216Z","caller":"traceutil/trace.go:171","msg":"trace[269186273] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"165.864682ms","start":"2026-08-22T14:31:33.392326Z","end":"2026-08-22T14:31:33.558191Z","steps":["trace[269186273] 'process raft request' (duration: 165.687437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:34.793114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:34.368604Z","time spent":"424.504084ms","remote":"127.0.0.1:46550","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-22T14:31:34.807338Z","caller":"traceutil/trace.go:171","msg":"trace[109767968] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"251.260755ms","start":"2026-08-22T14:31:34.556056Z","end":"2026-08-22T14:31:34.807317Z","steps":["trace[109767968] 'process raft request' (duration: 251.056199ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:38.55518Z","caller":"traceutil/trace.go:171","msg":"trace[1100466065] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"145.147817ms","start":"2026-08-22T14:31:38.410002Z","end":"2026-08-22T14:31:38.55515Z","steps":["trace[1100466065] 'process raft request' (duration: 144.93462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:40.246608Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680222037096736571,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:31:40.747193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680222037096736571,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T14:31:40.867683Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.405174183s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-22T14:31:40.892929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.945809306s","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":"warn","ts":"2026-08-22T14:31:40.893116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.945210706s","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-08-22T14:31:40.893212Z","caller":"traceutil/trace.go:171","msg":"trace[472990917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:852; }","duration":"1.94533643s","start":"2026-08-22T14:31:38.947855Z","end":"2026-08-22T14:31:40.893192Z","steps":["trace[472990917] 'range keys from in-memory index tree' (duration: 1.945067422s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:40.893257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:38.947842Z","time spent":"1.945406282s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T14:31:40.893406Z","caller":"traceutil/trace.go:171","msg":"trace[1211800141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:852; }","duration":"1.946129246s","start":"2026-08-22T14:31:38.947045Z","end":"2026-08-22T14:31:40.893174Z","steps":["trace[1211800141] 'range keys from in-memory index tree' (duration: 1.945546457s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:40.893613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:38.947029Z","time spent":"1.94656342s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T14:31:40.893733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.702332054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:40.893775Z","caller":"traceutil/trace.go:171","msg":"trace[439449316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"1.702401537s","start":"2026-08-22T14:31:39.191363Z","end":"2026-08-22T14:31:40.893764Z","steps":["trace[439449316] 'range keys from in-memory index tree' (duration: 1.702278243s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:40.893802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:39.191347Z","time spent":"1.702448668s","remote":"127.0.0.1:46510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:31:40.893994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.981728956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-22T14:31:40.893988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656525638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:31:40.894065Z","caller":"traceutil/trace.go:171","msg":"trace[74089985] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:852; }","duration":"1.656604241s","start":"2026-08-22T14:31:39.237451Z","end":"2026-08-22T14:31:40.894055Z","steps":["trace[74089985] 'count revisions from in-memory index tree' (duration: 1.656471136s)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:40.89407Z","caller":"traceutil/trace.go:171","msg":"trace[1502833005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:852; }","duration":"1.981832069s","start":"2026-08-22T14:31:38.91222Z","end":"2026-08-22T14:31:40.894052Z","steps":["trace[1502833005] 'range keys from in-memory index tree' (duration: 1.981601432s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:40.894092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:39.23741Z","time spent":"1.656674433s","remote":"127.0.0.1:46966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:31:40.894122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:38.912202Z","time spent":"1.981907182s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T14:31:40.894354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.793703ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:40.894457Z","caller":"traceutil/trace.go:171","msg":"trace[252478495] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:852; }","duration":"709.898257ms","start":"2026-08-22T14:31:40.184543Z","end":"2026-08-22T14:31:40.894441Z","steps":["trace[252478495] 'range keys from in-memory index tree' (duration: 709.781543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:40.894602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.977888255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-22T14:31:40.89469Z","caller":"traceutil/trace.go:171","msg":"trace[330904214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:852; }","duration":"1.978003678s","start":"2026-08-22T14:31:38.916672Z","end":"2026-08-22T14:31:40.894675Z","steps":["trace[330904214] 'range keys from in-memory index tree' (duration: 1.977678318s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:40.89498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:38.916655Z","time spent":"1.978269077s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T14:31:40.894611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.951570589s","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-08-22T14:31:40.895083Z","caller":"traceutil/trace.go:171","msg":"trace[1374788757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:852; }","duration":"1.952054585s","start":"2026-08-22T14:31:38.943006Z","end":"2026-08-22T14:31:40.89506Z","steps":["trace[1374788757] 'range keys from in-memory index tree' (duration: 1.951450156s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:40.895177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:38.942989Z","time spent":"1.952156099s","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T14:31:40.950457Z","caller":"traceutil/trace.go:171","msg":"trace[1974818049] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:890; }","duration":"1.213030533s","start":"2026-08-22T14:31:39.737405Z","end":"2026-08-22T14:31:40.950435Z","steps":["trace[1974818049] 'read index received' (duration: 1.130642211s)","trace[1974818049] 'applied index is now lower than readState.Index' (duration: 82.387082ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:31:40.950535Z","caller":"traceutil/trace.go:171","msg":"trace[24893108] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"1.387718957s","start":"2026-08-22T14:31:39.562783Z","end":"2026-08-22T14:31:40.950502Z","steps":["trace[24893108] 'process raft request' (duration: 1.387477568s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:40.950714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:39.562764Z","time spent":"1.387849931s","remote":"127.0.0.1:46754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:31:40.95075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213192198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:31:40.950794Z","caller":"traceutil/trace.go:171","msg":"trace[665022696] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:853; }","duration":"1.213434846s","start":"2026-08-22T14:31:39.737347Z","end":"2026-08-22T14:31:40.950782Z","steps":["trace[665022696] 'agreement among raft nodes before linearized reading' (duration: 1.213193488s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:31:40.950922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:31:39.737325Z","time spent":"1.213581801s","remote":"127.0.0.1:46940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-08-22T14:31:41.057833Z","caller":"traceutil/trace.go:171","msg":"trace[517656185] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"157.642396ms","start":"2026-08-22T14:31:40.900166Z","end":"2026-08-22T14:31:41.057809Z","steps":["trace[517656185] 'process raft request' (duration: 157.570903ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:41.057868Z","caller":"traceutil/trace.go:171","msg":"trace[730822669] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"158.103321ms","start":"2026-08-22T14:31:40.899712Z","end":"2026-08-22T14:31:41.057815Z","steps":["trace[730822669] 'process raft request' (duration: 139.292528ms)","trace[730822669] 'compare' (duration: 18.545774ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:31:41.057883Z","caller":"traceutil/trace.go:171","msg":"trace[1148529846] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"158.06258ms","start":"2026-08-22T14:31:40.899788Z","end":"2026-08-22T14:31:41.05785Z","steps":["trace[1148529846] 'process raft request' (duration: 157.905615ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:41.058316Z","caller":"traceutil/trace.go:171","msg":"trace[959317400] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"158.07985ms","start":"2026-08-22T14:31:40.900222Z","end":"2026-08-22T14:31:41.058302Z","steps":["trace[959317400] 'process raft request' (duration: 157.546832ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:41.058396Z","caller":"traceutil/trace.go:171","msg":"trace[1010057797] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"157.549063ms","start":"2026-08-22T14:31:40.900833Z","end":"2026-08-22T14:31:41.058382Z","steps":["trace[1010057797] 'process raft request' (duration: 157.331886ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:41.058497Z","caller":"traceutil/trace.go:171","msg":"trace[1379444932] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:892; }","duration":"107.955521ms","start":"2026-08-22T14:31:40.95052Z","end":"2026-08-22T14:31:41.058475Z","steps":["trace[1379444932] 'read index received' (duration: 88.585441ms)","trace[1379444932] 'applied index is now lower than readState.Index' (duration: 19.36897ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:31:41.058782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.110724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:31:41.058893Z","caller":"traceutil/trace.go:171","msg":"trace[294136892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:859; }","duration":"155.240778ms","start":"2026-08-22T14:31:40.903639Z","end":"2026-08-22T14:31:41.05888Z","steps":["trace[294136892] 'agreement among raft nodes before linearized reading' (duration: 154.946999ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:31:42.063154Z","caller":"traceutil/trace.go:171","msg":"trace[137737820] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"105.618895ms","start":"2026-08-22T14:31:41.957509Z","end":"2026-08-22T14:31:42.063128Z","steps":["trace[137737820] 'process raft request' (duration: 105.46541ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:04.60324Z","caller":"traceutil/trace.go:171","msg":"trace[8087379] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"100.229671ms","start":"2026-08-22T14:32:04.502961Z","end":"2026-08-22T14:32:04.60319Z","steps":["trace[8087379] 'process raft request' (duration: 63.411063ms)","trace[8087379] 'compare' (duration: 36.58767ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:07.673901Z","caller":"traceutil/trace.go:171","msg":"trace[2053243065] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"236.820235ms","start":"2026-08-22T14:32:07.437056Z","end":"2026-08-22T14:32:07.673877Z","steps":["trace[2053243065] 'read index received' (duration: 215.7991ms)","trace[2053243065] 'applied index is now lower than readState.Index' (duration: 21.019905ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:07.674027Z","caller":"traceutil/trace.go:171","msg":"trace[1992086334] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"265.149919ms","start":"2026-08-22T14:32:07.408864Z","end":"2026-08-22T14:32:07.674013Z","steps":["trace[1992086334] 'process raft request' (duration: 244.05373ms)","trace[1992086334] 'compare' (duration: 20.844409ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:32:07.674192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.914749ms","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-08-22T14:32:07.67429Z","caller":"traceutil/trace.go:171","msg":"trace[1890722868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1069; }","duration":"236.011483ms","start":"2026-08-22T14:32:07.438251Z","end":"2026-08-22T14:32:07.674263Z","steps":["trace[1890722868] 'agreement among raft nodes before linearized reading' (duration: 235.821826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:07.674442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.216353ms","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-08-22T14:32:07.674486Z","caller":"traceutil/trace.go:171","msg":"trace[661735609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1069; }","duration":"235.302287ms","start":"2026-08-22T14:32:07.439171Z","end":"2026-08-22T14:32:07.674473Z","steps":["trace[661735609] 'agreement among raft nodes before linearized reading' (duration: 235.153961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:07.674485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.409697ms","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-08-22T14:32:07.674555Z","caller":"traceutil/trace.go:171","msg":"trace[1131534069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1069; }","duration":"237.515771ms","start":"2026-08-22T14:32:07.437019Z","end":"2026-08-22T14:32:07.674535Z","steps":["trace[1131534069] 'agreement among raft nodes before linearized reading' (duration: 237.301603ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:14.273691Z","caller":"traceutil/trace.go:171","msg":"trace[953358651] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"210.276276ms","start":"2026-08-22T14:32:14.063389Z","end":"2026-08-22T14:32:14.273666Z","steps":["trace[953358651] 'process raft request' (duration: 155.788445ms)","trace[953358651] 'compare' (duration: 54.386747ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:14.470289Z","caller":"traceutil/trace.go:171","msg":"trace[608228602] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1094; }","duration":"194.88621ms","start":"2026-08-22T14:32:14.275372Z","end":"2026-08-22T14:32:14.470258Z","steps":["trace[608228602] 'process raft request' (duration: 191.470495ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:23.985348Z","caller":"traceutil/trace.go:171","msg":"trace[30762967] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"144.322106ms","start":"2026-08-22T14:32:23.841003Z","end":"2026-08-22T14:32:23.985325Z","steps":["trace[30762967] 'process raft request' (duration: 97.894903ms)","trace[30762967] 'compare' (duration: 46.195884ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:32:23.985423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.916183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-22T14:32:23.985456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.868222ms","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-08-22T14:32:23.985514Z","caller":"traceutil/trace.go:171","msg":"trace[598611567] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1127; }","duration":"124.955156ms","start":"2026-08-22T14:32:23.860546Z","end":"2026-08-22T14:32:23.985501Z","steps":["trace[598611567] 'agreement among raft nodes before linearized reading' (duration: 124.80456ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:23.985513Z","caller":"traceutil/trace.go:171","msg":"trace[2092951027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1127; }","duration":"135.059049ms","start":"2026-08-22T14:32:23.850435Z","end":"2026-08-22T14:32:23.985494Z","steps":["trace[2092951027] 'agreement among raft nodes before linearized reading' (duration: 134.827079ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:24.001485Z","caller":"traceutil/trace.go:171","msg":"trace[1184327415] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"105.344627ms","start":"2026-08-22T14:32:23.896116Z","end":"2026-08-22T14:32:24.00146Z","steps":["trace[1184327415] 'process raft request' (duration: 105.230432ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:32.277029Z","caller":"traceutil/trace.go:171","msg":"trace[2011659270] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"124.494192ms","start":"2026-08-22T14:32:32.152505Z","end":"2026-08-22T14:32:32.277Z","steps":["trace[2011659270] 'process raft request' (duration: 56.663896ms)","trace[2011659270] 'compare' (duration: 67.703101ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:32.314302Z","caller":"traceutil/trace.go:171","msg":"trace[544123817] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1203; }","duration":"123.920878ms","start":"2026-08-22T14:32:32.190356Z","end":"2026-08-22T14:32:32.314277Z","steps":["trace[544123817] 'read index received' (duration: 18.82738ms)","trace[544123817] 'applied index is now lower than readState.Index' (duration: 105.092328ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:32.314342Z","caller":"traceutil/trace.go:171","msg":"trace[2044661496] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"160.92798ms","start":"2026-08-22T14:32:32.153382Z","end":"2026-08-22T14:32:32.31431Z","steps":["trace[2044661496] 'process raft request' (duration: 160.737412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:32.314419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.053484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:32:32.314463Z","caller":"traceutil/trace.go:171","msg":"trace[1173446960] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"160.726133ms","start":"2026-08-22T14:32:32.153702Z","end":"2026-08-22T14:32:32.314428Z","steps":["trace[1173446960] 'process raft request' (duration: 160.532823ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:32.314498Z","caller":"traceutil/trace.go:171","msg":"trace[893726140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1156; }","duration":"124.140198ms","start":"2026-08-22T14:32:32.190309Z","end":"2026-08-22T14:32:32.314449Z","steps":["trace[893726140] 'agreement among raft nodes before linearized reading' (duration: 124.060655ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:42.358101Z","caller":"traceutil/trace.go:171","msg":"trace[372315726] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"107.789522ms","start":"2026-08-22T14:32:42.250289Z","end":"2026-08-22T14:32:42.358078Z","steps":["trace[372315726] 'process raft request' (duration: 107.662046ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:45.996999Z","caller":"traceutil/trace.go:171","msg":"trace[1821604786] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"118.986069ms","start":"2026-08-22T14:32:45.877998Z","end":"2026-08-22T14:32:45.996984Z","steps":["trace[1821604786] 'process raft request' (duration: 118.888075ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:46.325988Z","caller":"traceutil/trace.go:171","msg":"trace[502804634] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"130.346064ms","start":"2026-08-22T14:32:46.195622Z","end":"2026-08-22T14:32:46.325968Z","steps":["trace[502804634] 'read index received' (duration: 130.26586ms)","trace[502804634] 'applied index is now lower than readState.Index' (duration: 79.574µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:32:46.326037Z","caller":"traceutil/trace.go:171","msg":"trace[1872775086] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"170.871625ms","start":"2026-08-22T14:32:46.155149Z","end":"2026-08-22T14:32:46.326021Z","steps":["trace[1872775086] 'process raft request' (duration: 170.658716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:32:46.326182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.535071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:32:46.326319Z","caller":"traceutil/trace.go:171","msg":"trace[1809454228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1199; }","duration":"130.653416ms","start":"2026-08-22T14:32:46.19561Z","end":"2026-08-22T14:32:46.326263Z","steps":["trace[1809454228] 'agreement among raft nodes before linearized reading' (duration: 130.477229ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:48.632539Z","caller":"traceutil/trace.go:171","msg":"trace[1746333419] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"104.530912ms","start":"2026-08-22T14:32:48.527987Z","end":"2026-08-22T14:32:48.632518Z","steps":["trace[1746333419] 'process raft request' (duration: 100.731127ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:48.632772Z","caller":"traceutil/trace.go:171","msg":"trace[2054304850] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"104.538043ms","start":"2026-08-22T14:32:48.528218Z","end":"2026-08-22T14:32:48.632756Z","steps":["trace[2054304850] 'process raft request' (duration: 104.45312ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:48.632808Z","caller":"traceutil/trace.go:171","msg":"trace[2075053624] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"104.722021ms","start":"2026-08-22T14:32:48.528059Z","end":"2026-08-22T14:32:48.632781Z","steps":["trace[2075053624] 'process raft request' (duration: 104.434958ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:32:50.133328Z","caller":"traceutil/trace.go:171","msg":"trace[1176826739] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"105.162678ms","start":"2026-08-22T14:32:50.028148Z","end":"2026-08-22T14:32:50.133311Z","steps":["trace[1176826739] 'process raft request' (duration: 105.061864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:04.483218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.191439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-22T14:33:04.483346Z","caller":"traceutil/trace.go:171","msg":"trace[1656648019] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1257; }","duration":"111.370528ms","start":"2026-08-22T14:33:04.371953Z","end":"2026-08-22T14:33:04.483324Z","steps":["trace[1656648019] 'range keys from in-memory index tree' (duration: 111.0171ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:04.689759Z","caller":"traceutil/trace.go:171","msg":"trace[800017772] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"143.101524ms","start":"2026-08-22T14:33:04.546622Z","end":"2026-08-22T14:33:04.689724Z","steps":["trace[800017772] 'process raft request' (duration: 48.002468ms)","trace[800017772] 'compare' (duration: 94.707238ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:33:05.036662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.06834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:33:05.036786Z","caller":"traceutil/trace.go:171","msg":"trace[77111656] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"267.97627ms","start":"2026-08-22T14:33:04.768795Z","end":"2026-08-22T14:33:05.036771Z","steps":["trace[77111656] 'process raft request' (duration: 28.735462ms)","trace[77111656] 'compare' (duration: 238.989397ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:05.036986Z","caller":"traceutil/trace.go:171","msg":"trace[1077237998] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1317; }","duration":"164.948729ms","start":"2026-08-22T14:33:04.872027Z","end":"2026-08-22T14:33:05.036976Z","steps":["trace[1077237998] 'read index received' (duration: 164.680667ms)","trace[1077237998] 'applied index is now lower than readState.Index' (duration: 267.522µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:33:05.037053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.019992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:33:05.037044Z","caller":"traceutil/trace.go:171","msg":"trace[370594133] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"268.070904ms","start":"2026-08-22T14:33:04.768948Z","end":"2026-08-22T14:33:05.037019Z","steps":["trace[370594133] 'process raft request' (duration: 267.793931ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:05.037073Z","caller":"traceutil/trace.go:171","msg":"trace[1162362668] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1263; }","duration":"165.076635ms","start":"2026-08-22T14:33:04.87199Z","end":"2026-08-22T14:33:05.037066Z","steps":["trace[1162362668] 'agreement among raft nodes before linearized reading' (duration: 165.021232ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:05.037063Z","caller":"traceutil/trace.go:171","msg":"trace[643377618] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"267.801452ms","start":"2026-08-22T14:33:04.769235Z","end":"2026-08-22T14:33:05.037036Z","steps":["trace[643377618] 'process raft request' (duration: 267.708868ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:11.135312Z","caller":"traceutil/trace.go:171","msg":"trace[1599153496] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"154.397184ms","start":"2026-08-22T14:33:10.980901Z","end":"2026-08-22T14:33:11.135299Z","steps":["trace[1599153496] 'read index received' (duration: 154.257388ms)","trace[1599153496] 'applied index is now lower than readState.Index' (duration: 139.086µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:11.135367Z","caller":"traceutil/trace.go:171","msg":"trace[1270547665] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"281.455699ms","start":"2026-08-22T14:33:10.853886Z","end":"2026-08-22T14:33:11.135342Z","steps":["trace[1270547665] 'process raft request' (duration: 281.316243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:11.136375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.458492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050268"} {"level":"info","ts":"2026-08-22T14:33:11.136476Z","caller":"traceutil/trace.go:171","msg":"trace[452972937] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1279; }","duration":"155.603358ms","start":"2026-08-22T14:33:10.980853Z","end":"2026-08-22T14:33:11.136456Z","steps":["trace[452972937] 'agreement among raft nodes before linearized reading' (duration: 154.571612ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:25.514376Z","caller":"traceutil/trace.go:171","msg":"trace[2025899450] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"157.1855ms","start":"2026-08-22T14:33:25.357158Z","end":"2026-08-22T14:33:25.514344Z","steps":["trace[2025899450] 'process raft request' (duration: 126.353474ms)","trace[2025899450] 'compare' (duration: 30.68822ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:28.749913Z","caller":"traceutil/trace.go:171","msg":"trace[314024095] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"160.11752ms","start":"2026-08-22T14:33:28.589767Z","end":"2026-08-22T14:33:28.749884Z","steps":["trace[314024095] 'process raft request' (duration: 112.91829ms)","trace[314024095] 'compare' (duration: 47.086465ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:33:29.736425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.004098ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-22T14:33:30.637547Z","caller":"traceutil/trace.go:171","msg":"trace[959625131] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"176.222686ms","start":"2026-08-22T14:33:30.461293Z","end":"2026-08-22T14:33:30.637516Z","steps":["trace[959625131] 'process raft request' (duration: 176.033866ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:30.901735Z","caller":"traceutil/trace.go:171","msg":"trace[1259119150] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"147.250279ms","start":"2026-08-22T14:33:30.754461Z","end":"2026-08-22T14:33:30.901711Z","steps":["trace[1259119150] 'process raft request' (duration: 147.099042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:31.377491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.514483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:33:31.377569Z","caller":"traceutil/trace.go:171","msg":"trace[301141912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1375; }","duration":"186.602797ms","start":"2026-08-22T14:33:31.190949Z","end":"2026-08-22T14:33:31.377552Z","steps":["trace[301141912] 'range keys from in-memory index tree' (duration: 186.424909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:31.817489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.030504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T14:33:31.817608Z","caller":"traceutil/trace.go:171","msg":"trace[1854475540] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1379; }","duration":"165.237153ms","start":"2026-08-22T14:33:31.652343Z","end":"2026-08-22T14:33:31.81758Z","steps":["trace[1854475540] 'count revisions from in-memory index tree' (duration: 164.893627ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:33.680714Z","caller":"traceutil/trace.go:171","msg":"trace[2113811604] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"225.181624ms","start":"2026-08-22T14:33:33.455516Z","end":"2026-08-22T14:33:33.680698Z","steps":["trace[2113811604] 'process raft request' (duration: 224.799447ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:33.680618Z","caller":"traceutil/trace.go:171","msg":"trace[1672395944] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1445; }","duration":"130.585492ms","start":"2026-08-22T14:33:33.550007Z","end":"2026-08-22T14:33:33.680593Z","steps":["trace[1672395944] 'read index received' (duration: 130.360061ms)","trace[1672395944] 'applied index is now lower than readState.Index' (duration: 224.371µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:33:33.681106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.080175ms","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-08-22T14:33:33.681148Z","caller":"traceutil/trace.go:171","msg":"trace[1242455974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1382; }","duration":"131.19253ms","start":"2026-08-22T14:33:33.549944Z","end":"2026-08-22T14:33:33.681137Z","steps":["trace[1242455974] 'agreement among raft nodes before linearized reading' (duration: 131.020192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:33.688491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.728558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-22T14:33:33.688531Z","caller":"traceutil/trace.go:171","msg":"trace[355989555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1383; }","duration":"115.811412ms","start":"2026-08-22T14:33:33.57271Z","end":"2026-08-22T14:33:33.688521Z","steps":["trace[355989555] 'agreement among raft nodes before linearized reading' (duration: 115.672046ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:34.48955Z","caller":"traceutil/trace.go:171","msg":"trace[60948488] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"100.350556ms","start":"2026-08-22T14:33:34.389166Z","end":"2026-08-22T14:33:34.489516Z","steps":["trace[60948488] 'process raft request' (duration: 18.712534ms)","trace[60948488] 'compare' (duration: 81.505976ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:38.104127Z","caller":"traceutil/trace.go:171","msg":"trace[368824633] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"224.194162ms","start":"2026-08-22T14:33:37.879892Z","end":"2026-08-22T14:33:38.104086Z","steps":["trace[368824633] 'process raft request' (duration: 223.955561ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:38.109528Z","caller":"traceutil/trace.go:171","msg":"trace[1999230787] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1466; }","duration":"222.448632ms","start":"2026-08-22T14:33:37.887048Z","end":"2026-08-22T14:33:38.109496Z","steps":["trace[1999230787] 'read index received' (duration: 216.806467ms)","trace[1999230787] 'applied index is now lower than readState.Index' (duration: 5.640555ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:38.110138Z","caller":"traceutil/trace.go:171","msg":"trace[1204813108] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"227.35286ms","start":"2026-08-22T14:33:37.882712Z","end":"2026-08-22T14:33:38.110064Z","steps":["trace[1204813108] 'process raft request' (duration: 226.614336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:38.11029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.997127ms","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-08-22T14:33:38.110402Z","caller":"traceutil/trace.go:171","msg":"trace[101505110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1403; }","duration":"223.137454ms","start":"2026-08-22T14:33:37.887238Z","end":"2026-08-22T14:33:38.110376Z","steps":["trace[101505110] 'agreement among raft nodes before linearized reading' (duration: 222.745665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:38.110418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.328472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2568"} {"level":"info","ts":"2026-08-22T14:33:38.110504Z","caller":"traceutil/trace.go:171","msg":"trace[229033185] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1403; }","duration":"223.460359ms","start":"2026-08-22T14:33:37.887025Z","end":"2026-08-22T14:33:38.110485Z","steps":["trace[229033185] 'agreement among raft nodes before linearized reading' (duration: 223.04999ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:39.873634Z","caller":"traceutil/trace.go:171","msg":"trace[1380259068] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"149.391117ms","start":"2026-08-22T14:33:39.724213Z","end":"2026-08-22T14:33:39.873604Z","steps":["trace[1380259068] 'process raft request' (duration: 149.23301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:40.525128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.870506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:33:40.525244Z","caller":"traceutil/trace.go:171","msg":"trace[1199590333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1410; }","duration":"334.098536ms","start":"2026-08-22T14:33:40.191121Z","end":"2026-08-22T14:33:40.52522Z","steps":["trace[1199590333] 'range keys from in-memory index tree' (duration: 333.691357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:40.525312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:40.191093Z","time spent":"334.203911ms","remote":"127.0.0.1:46506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:33:40.525392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.862708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-22T14:33:40.525427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.414439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-22T14:33:40.525458Z","caller":"traceutil/trace.go:171","msg":"trace[1409145617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1410; }","duration":"404.958682ms","start":"2026-08-22T14:33:40.120482Z","end":"2026-08-22T14:33:40.52544Z","steps":["trace[1409145617] 'range keys from in-memory index tree' (duration: 404.652268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:40.52543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.334412ms","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-08-22T14:33:40.525524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:40.120471Z","time spent":"405.039417ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T14:33:40.525525Z","caller":"traceutil/trace.go:171","msg":"trace[1361625895] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1410; }","duration":"339.434307ms","start":"2026-08-22T14:33:40.186077Z","end":"2026-08-22T14:33:40.525511Z","steps":["trace[1361625895] 'range keys from in-memory index tree' (duration: 339.320562ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:40.525523Z","caller":"traceutil/trace.go:171","msg":"trace[1110781017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1410; }","duration":"410.540834ms","start":"2026-08-22T14:33:40.114959Z","end":"2026-08-22T14:33:40.5255Z","steps":["trace[1110781017] 'range keys from in-memory index tree' (duration: 410.158336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:40.525573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:40.114939Z","time spent":"410.620048ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T14:33:40.704187Z","caller":"traceutil/trace.go:171","msg":"trace[1512880725] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"143.343439ms","start":"2026-08-22T14:33:40.560818Z","end":"2026-08-22T14:33:40.704162Z","steps":["trace[1512880725] 'process raft request' (duration: 143.185522ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:41.061237Z","caller":"traceutil/trace.go:171","msg":"trace[1553797761] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1477; }","duration":"320.395685ms","start":"2026-08-22T14:33:40.740817Z","end":"2026-08-22T14:33:41.061213Z","steps":["trace[1553797761] 'read index received' (duration: 239.100966ms)","trace[1553797761] 'applied index is now lower than readState.Index' (duration: 81.293709ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:33:41.06152Z","caller":"traceutil/trace.go:171","msg":"trace[915920291] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"499.872491ms","start":"2026-08-22T14:33:40.561611Z","end":"2026-08-22T14:33:41.061483Z","steps":["trace[915920291] 'process raft request' (duration: 418.32726ms)","trace[915920291] 'compare' (duration: 81.116841ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:33:41.061973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:40.561593Z","time spent":"500.316553ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:33:41.061717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.886118ms","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-08-22T14:33:41.062205Z","caller":"traceutil/trace.go:171","msg":"trace[466358954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1412; }","duration":"321.421504ms","start":"2026-08-22T14:33:40.740769Z","end":"2026-08-22T14:33:41.062191Z","steps":["trace[466358954] 'agreement among raft nodes before linearized reading' (duration: 320.747151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:41.062259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:40.740752Z","time spent":"321.492077ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-22T14:33:41.093961Z","caller":"traceutil/trace.go:171","msg":"trace[1195373819] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"114.751242ms","start":"2026-08-22T14:33:40.978956Z","end":"2026-08-22T14:33:41.093707Z","steps":["trace[1195373819] 'process raft request' (duration: 114.608645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:41.694446Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680222037096737892,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T14:33:41.995928Z","caller":"traceutil/trace.go:171","msg":"trace[1211068972] linearizableReadLoop","detail":"{readStateIndex:1480; appliedIndex:1479; }","duration":"802.569474ms","start":"2026-08-22T14:33:41.193331Z","end":"2026-08-22T14:33:41.995901Z","steps":["trace[1211068972] 'read index received' (duration: 731.40313ms)","trace[1211068972] 'applied index is now lower than readState.Index' (duration: 71.165124ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:33:41.996159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.808065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:33:41.996223Z","caller":"traceutil/trace.go:171","msg":"trace[70405826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1414; }","duration":"802.91956ms","start":"2026-08-22T14:33:41.193287Z","end":"2026-08-22T14:33:41.996207Z","steps":["trace[70405826] 'agreement among raft nodes before linearized reading' (duration: 802.793994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:41.996273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:41.193268Z","time spent":"802.992784ms","remote":"127.0.0.1:46506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T14:33:41.996002Z","caller":"traceutil/trace.go:171","msg":"trace[1329428222] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"929.379693ms","start":"2026-08-22T14:33:41.066592Z","end":"2026-08-22T14:33:41.995972Z","steps":["trace[1329428222] 'process raft request' (duration: 858.085133ms)","trace[1329428222] 'compare' (duration: 70.989276ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:33:41.996864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:41.066574Z","time spent":"930.060954ms","remote":"127.0.0.1:46780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T14:33:41.997176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.222177ms","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":"warn","ts":"2026-08-22T14:33:41.997184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.526138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:33:41.997272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.8751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:33:41.997294Z","caller":"traceutil/trace.go:171","msg":"trace[400828250] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1414; }","duration":"677.720808ms","start":"2026-08-22T14:33:41.319548Z","end":"2026-08-22T14:33:41.997269Z","steps":["trace[400828250] 'agreement among raft nodes before linearized reading' (duration: 677.477015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:41.997371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:41.319526Z","time spent":"677.832893ms","remote":"127.0.0.1:46910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-08-22T14:33:41.997271Z","caller":"traceutil/trace.go:171","msg":"trace[1196668049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1414; }","duration":"114.351554ms","start":"2026-08-22T14:33:41.8829Z","end":"2026-08-22T14:33:41.997251Z","steps":["trace[1196668049] 'agreement among raft nodes before linearized reading' (duration: 114.130923ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:41.997314Z","caller":"traceutil/trace.go:171","msg":"trace[226840441] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1414; }","duration":"599.964124ms","start":"2026-08-22T14:33:41.39734Z","end":"2026-08-22T14:33:41.997304Z","steps":["trace[226840441] 'agreement among raft nodes before linearized reading' (duration: 599.899531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:41.997613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:33:41.397315Z","time spent":"600.271429ms","remote":"127.0.0.1:57262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-22T14:33:41.997205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.772775ms","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-08-22T14:33:41.997688Z","caller":"traceutil/trace.go:171","msg":"trace[95670023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1414; }","duration":"111.28896ms","start":"2026-08-22T14:33:41.886392Z","end":"2026-08-22T14:33:41.997681Z","steps":["trace[95670023] 'agreement among raft nodes before linearized reading' (duration: 110.691081ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:42.918906Z","caller":"traceutil/trace.go:171","msg":"trace[240962057] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"193.558423ms","start":"2026-08-22T14:33:42.725325Z","end":"2026-08-22T14:33:42.918883Z","steps":["trace[240962057] 'process raft request' (duration: 193.427137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:43.239448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.173072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T14:33:43.239538Z","caller":"traceutil/trace.go:171","msg":"trace[694463655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1417; }","duration":"173.321669ms","start":"2026-08-22T14:33:43.066197Z","end":"2026-08-22T14:33:43.239519Z","steps":["trace[694463655] 'range keys from in-memory index tree' (duration: 172.988813ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:43.239807Z","caller":"traceutil/trace.go:171","msg":"trace[499366758] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"140.887601ms","start":"2026-08-22T14:33:43.098814Z","end":"2026-08-22T14:33:43.239702Z","steps":["trace[499366758] 'process raft request' (duration: 80.980612ms)","trace[499366758] 'compare' (duration: 59.509781ms)"],"step_count":2}