{"level":"warn","ts":"2026-05-08T18:13:17.86526Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T18:13:17.865358Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-08T18:13:17.865441Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T18:13:17.865454Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-08T18:13:17.865474Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T18:13:17.866302Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-05-08T18:13:17.866384Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-08T18:13:17.872681Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.093422ms"} {"level":"info","ts":"2026-05-08T18:13:17.884641Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-05-08T18:13:17.884732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T18:13:17.884755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-05-08T18:13:17.884773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T18:13:17.884782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-05-08T18:13:17.884818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-05-08T18:13:17.898203Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T18:13:17.900605Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T18:13:17.901939Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-08T18:13:17.903897Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T18:13:17.904073Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T18:13:17.904145Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T18:13:17.90419Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T18:13:17.904197Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T18:13:17.906885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-05-08T18:13:17.906977Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-08T18:13:17.907863Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T18:13:17.907942Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-08T18:13:17.907991Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-08T18:13:17.908029Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T18:13:17.908061Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T18:13:18.585045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T18:13:18.585109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T18:13:18.585183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-05-08T18:13:18.585277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-05-08T18:13:18.5853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-08T18:13:18.585349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-05-08T18:13:18.585373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-08T18:13:18.601085Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T18:13:18.601161Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T18:13:18.601406Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T18:13:18.60151Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T18:13:18.601683Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T18:13:18.601104Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:13:18.603497Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:13:18.60358Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-05-08T18:13:18.603703Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:13:18.603785Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:13:18.605223Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T18:13:20.118158Z","caller":"traceutil/trace.go:171","msg":"trace[813362541] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"160.702161ms","start":"2026-05-08T18:13:19.95743Z","end":"2026-05-08T18:13:20.118132Z","steps":["trace[813362541] 'process raft request' (duration: 160.620551ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:20.118147Z","caller":"traceutil/trace.go:171","msg":"trace[2026228382] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"160.772042ms","start":"2026-05-08T18:13:19.957348Z","end":"2026-05-08T18:13:20.11812Z","steps":["trace[2026228382] 'process raft request' (duration: 160.649401ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:20.118163Z","caller":"traceutil/trace.go:171","msg":"trace[257479237] linearizableReadLoop","detail":"{readStateIndex:32; appliedIndex:26; }","duration":"116.950102ms","start":"2026-05-08T18:13:20.001191Z","end":"2026-05-08T18:13:20.118141Z","steps":["trace[257479237] 'read index received' (duration: 60.954713ms)","trace[257479237] 'applied index is now lower than readState.Index' (duration: 55.994699ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:20.118211Z","caller":"traceutil/trace.go:171","msg":"trace[1783273098] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"160.748922ms","start":"2026-05-08T18:13:19.957403Z","end":"2026-05-08T18:13:20.118152Z","steps":["trace[1783273098] 'process raft request' (duration: 160.627611ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:20.118319Z","caller":"traceutil/trace.go:171","msg":"trace[1907175003] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"161.027554ms","start":"2026-05-08T18:13:19.95727Z","end":"2026-05-08T18:13:20.118297Z","steps":["trace[1907175003] 'process raft request' (duration: 104.757122ms)","trace[1907175003] 'compare' (duration: 55.816328ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:20.118443Z","caller":"traceutil/trace.go:171","msg":"trace[1234725492] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"160.58236ms","start":"2026-05-08T18:13:19.957844Z","end":"2026-05-08T18:13:20.118427Z","steps":["trace[1234725492] 'process raft request' (duration: 160.227208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:20.118571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.545194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-08T18:13:20.118652Z","caller":"traceutil/trace.go:171","msg":"trace[433365614] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:28; }","duration":"162.655565ms","start":"2026-05-08T18:13:19.955979Z","end":"2026-05-08T18:13:20.118635Z","steps":["trace[433365614] 'agreement among raft nodes before linearized reading' (duration: 162.466484ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:20.118835Z","caller":"traceutil/trace.go:171","msg":"trace[285715493] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"115.967886ms","start":"2026-05-08T18:13:20.002855Z","end":"2026-05-08T18:13:20.118823Z","steps":["trace[285715493] 'process raft request' (duration: 115.753825ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:20.119047Z","caller":"traceutil/trace.go:171","msg":"trace[563519204] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"114.566857ms","start":"2026-05-08T18:13:20.004466Z","end":"2026-05-08T18:13:20.119033Z","steps":["trace[563519204] 'process raft request' (duration: 114.337725ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:20.119092Z","caller":"traceutil/trace.go:171","msg":"trace[437275561] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"114.073783ms","start":"2026-05-08T18:13:20.005003Z","end":"2026-05-08T18:13:20.119076Z","steps":["trace[437275561] 'process raft request' (duration: 113.896082ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:20.119188Z","caller":"traceutil/trace.go:171","msg":"trace[1548441557] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"114.380315ms","start":"2026-05-08T18:13:20.004794Z","end":"2026-05-08T18:13:20.119175Z","steps":["trace[1548441557] 'process raft request' (duration: 114.066453ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:20.119344Z","caller":"traceutil/trace.go:171","msg":"trace[1056626698] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"156.653134ms","start":"2026-05-08T18:13:19.961747Z","end":"2026-05-08T18:13:20.1184Z","steps":["trace[1056626698] 'process raft request' (duration: 156.364612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:21.038299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.728445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-08T18:13:21.038445Z","caller":"traceutil/trace.go:171","msg":"trace[1976251056] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"200.546878ms","start":"2026-05-08T18:13:20.837882Z","end":"2026-05-08T18:13:21.038429Z","steps":["trace[1976251056] 'process raft request' (duration: 40.557365ms)","trace[1976251056] 'compare' (duration: 158.574604ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:13:21.039124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.603869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T18:13:21.039208Z","caller":"traceutil/trace.go:171","msg":"trace[1579941111] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:73; }","duration":"200.727999ms","start":"2026-05-08T18:13:20.838452Z","end":"2026-05-08T18:13:21.03918Z","steps":["trace[1579941111] 'agreement among raft nodes before linearized reading' (duration: 200.109885ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:21.041581Z","caller":"traceutil/trace.go:171","msg":"trace[1325489494] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"199.868614ms","start":"2026-05-08T18:13:20.8385Z","end":"2026-05-08T18:13:21.038368Z","steps":["trace[1325489494] 'read index received' (duration: 39.894611ms)","trace[1325489494] 'applied index is now lower than readState.Index' (duration: 159.972433ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:13:21.04243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.954087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T18:13:21.042505Z","caller":"traceutil/trace.go:171","msg":"trace[1099592515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"109.078218ms","start":"2026-05-08T18:13:20.933409Z","end":"2026-05-08T18:13:21.042487Z","steps":["trace[1099592515] 'agreement among raft nodes before linearized reading' (duration: 108.508434ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:21.216945Z","caller":"traceutil/trace.go:171","msg":"trace[2122170807] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"106.96693ms","start":"2026-05-08T18:13:21.109949Z","end":"2026-05-08T18:13:21.216916Z","steps":["trace[2122170807] 'process raft request' (duration: 88.064713ms)","trace[2122170807] 'compare' (duration: 18.774866ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:21.54303Z","caller":"traceutil/trace.go:171","msg":"trace[563482083] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"145.363728ms","start":"2026-05-08T18:13:21.397636Z","end":"2026-05-08T18:13:21.542999Z","steps":["trace[563482083] 'process raft request' (duration: 53.049177ms)","trace[563482083] 'compare' (duration: 92.20583ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:13:21.893118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.032853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-08T18:13:21.89335Z","caller":"traceutil/trace.go:171","msg":"trace[1689042213] transaction","detail":"{read_only:false; response_revision:94; number_of_response:1; }","duration":"133.394838ms","start":"2026-05-08T18:13:21.759921Z","end":"2026-05-08T18:13:21.893316Z","steps":["trace[1689042213] 'process raft request' (duration: 30.090523ms)","trace[1689042213] 'compare' (duration: 102.882252ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:23.259799Z","caller":"traceutil/trace.go:171","msg":"trace[1872571099] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"115.464876ms","start":"2026-05-08T18:13:23.144301Z","end":"2026-05-08T18:13:23.259766Z","steps":["trace[1872571099] 'process raft request' (duration: 51.057369ms)","trace[1872571099] 'compare' (duration: 64.081295ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:23.511843Z","caller":"traceutil/trace.go:171","msg":"trace[512432719] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"140.826383ms","start":"2026-05-08T18:13:23.37099Z","end":"2026-05-08T18:13:23.511817Z","steps":["trace[512432719] 'process raft request' (duration: 140.689362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:23.926181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.510385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:13:23.926548Z","caller":"traceutil/trace.go:171","msg":"trace[581861133] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"244.738853ms","start":"2026-05-08T18:13:23.681751Z","end":"2026-05-08T18:13:23.926489Z","steps":["trace[581861133] 'process raft request' (duration: 83.857786ms)","trace[581861133] 'compare' (duration: 160.409804ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:24.228344Z","caller":"traceutil/trace.go:171","msg":"trace[110608806] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"123.913876ms","start":"2026-05-08T18:13:24.104396Z","end":"2026-05-08T18:13:24.22831Z","steps":["trace[110608806] 'process raft request' (duration: 114.056751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:24.480307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.629823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:13:24.480429Z","caller":"traceutil/trace.go:171","msg":"trace[819845123] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"188.734963ms","start":"2026-05-08T18:13:24.29166Z","end":"2026-05-08T18:13:24.480394Z","steps":["trace[819845123] 'process raft request' (duration: 46.948979ms)","trace[819845123] 'compare' (duration: 141.494482ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:24.79627Z","caller":"traceutil/trace.go:171","msg":"trace[334050627] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"131.798888ms","start":"2026-05-08T18:13:24.66444Z","end":"2026-05-08T18:13:24.796239Z","steps":["trace[334050627] 'process raft request' (duration: 59.444391ms)","trace[334050627] 'compare' (duration: 72.120775ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:25.057325Z","caller":"traceutil/trace.go:171","msg":"trace[1415943089] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"176.305038ms","start":"2026-05-08T18:13:24.880992Z","end":"2026-05-08T18:13:25.057297Z","steps":["trace[1415943089] 'process raft request' (duration: 176.131927ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:25.057349Z","caller":"traceutil/trace.go:171","msg":"trace[1251929929] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"177.642158ms","start":"2026-05-08T18:13:24.879671Z","end":"2026-05-08T18:13:25.057313Z","steps":["trace[1251929929] 'process raft request' (duration: 137.367244ms)","trace[1251929929] 'compare' (duration: 39.965022ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:25.242102Z","caller":"traceutil/trace.go:171","msg":"trace[970900894] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:287; }","duration":"178.923451ms","start":"2026-05-08T18:13:25.063152Z","end":"2026-05-08T18:13:25.242075Z","steps":["trace[970900894] 'read index received' (duration: 19.975371ms)","trace[970900894] 'applied index is now lower than readState.Index' (duration: 158.94699ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:25.242332Z","caller":"traceutil/trace.go:171","msg":"trace[1406340839] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"180.37771ms","start":"2026-05-08T18:13:25.061908Z","end":"2026-05-08T18:13:25.242286Z","steps":["trace[1406340839] 'process raft request' (duration: 118.429715ms)","trace[1406340839] 'compare' (duration: 61.606313ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:13:25.242433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.230883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-08T18:13:25.242826Z","caller":"traceutil/trace.go:171","msg":"trace[1497272707] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:283; }","duration":"179.688786ms","start":"2026-05-08T18:13:25.063117Z","end":"2026-05-08T18:13:25.242806Z","steps":["trace[1497272707] 'agreement among raft nodes before linearized reading' (duration: 179.057572ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:25.443343Z","caller":"traceutil/trace.go:171","msg":"trace[873017488] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"126.605808ms","start":"2026-05-08T18:13:25.316708Z","end":"2026-05-08T18:13:25.443313Z","steps":["trace[873017488] 'process raft request' (duration: 126.551688ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:25.443374Z","caller":"traceutil/trace.go:171","msg":"trace[141043929] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"129.097955ms","start":"2026-05-08T18:13:25.314241Z","end":"2026-05-08T18:13:25.443339Z","steps":["trace[141043929] 'process raft request' (duration: 35.050229ms)","trace[141043929] 'compare' (duration: 93.769484ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:25.633095Z","caller":"traceutil/trace.go:171","msg":"trace[1131288867] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:293; }","duration":"183.044017ms","start":"2026-05-08T18:13:25.450024Z","end":"2026-05-08T18:13:25.633068Z","steps":["trace[1131288867] 'read index received' (duration: 109.803548ms)","trace[1131288867] 'applied index is now lower than readState.Index' (duration: 73.236309ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:25.63325Z","caller":"traceutil/trace.go:171","msg":"trace[1537424372] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"184.257255ms","start":"2026-05-08T18:13:25.448943Z","end":"2026-05-08T18:13:25.6332Z","steps":["trace[1537424372] 'process raft request' (duration: 110.921455ms)","trace[1537424372] 'compare' (duration: 73.070549ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:13:25.633453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.37782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-08T18:13:25.633527Z","caller":"traceutil/trace.go:171","msg":"trace[344434605] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:288; }","duration":"183.50316ms","start":"2026-05-08T18:13:25.45001Z","end":"2026-05-08T18:13:25.633513Z","steps":["trace[344434605] 'agreement among raft nodes before linearized reading' (duration: 183.207438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:26.372932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.7214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:13:26.373115Z","caller":"traceutil/trace.go:171","msg":"trace[1059014039] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"249.463811ms","start":"2026-05-08T18:13:26.123634Z","end":"2026-05-08T18:13:26.373098Z","steps":["trace[1059014039] 'process raft request' (duration: 249.403591ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:26.373173Z","caller":"traceutil/trace.go:171","msg":"trace[380811476] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"250.431347ms","start":"2026-05-08T18:13:26.122701Z","end":"2026-05-08T18:13:26.373132Z","steps":["trace[380811476] 'process raft request' (duration: 108.433665ms)","trace[380811476] 'compare' (duration: 141.543389ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:26.382187Z","caller":"traceutil/trace.go:171","msg":"trace[702736082] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:307; }","duration":"241.077156ms","start":"2026-05-08T18:13:26.141093Z","end":"2026-05-08T18:13:26.38217Z","steps":["trace[702736082] 'read index received' (duration: 90.228476ms)","trace[702736082] 'applied index is now lower than readState.Index' (duration: 150.84774ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:26.382209Z","caller":"traceutil/trace.go:171","msg":"trace[851741536] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"247.071025ms","start":"2026-05-08T18:13:26.135115Z","end":"2026-05-08T18:13:26.382186Z","steps":["trace[851741536] 'process raft request' (duration: 246.896804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:26.382372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.267778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:13:26.38229Z","caller":"traceutil/trace.go:171","msg":"trace[114721045] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"209.650492ms","start":"2026-05-08T18:13:26.172612Z","end":"2026-05-08T18:13:26.382263Z","steps":["trace[114721045] 'process raft request' (duration: 209.508382ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:26.382416Z","caller":"traceutil/trace.go:171","msg":"trace[1158759830] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:304; }","duration":"241.349058ms","start":"2026-05-08T18:13:26.141055Z","end":"2026-05-08T18:13:26.382404Z","steps":["trace[1158759830] 'agreement among raft nodes before linearized reading' (duration: 241.206977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:26.398521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.844704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-08T18:13:26.398567Z","caller":"traceutil/trace.go:171","msg":"trace[1745081691] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:305; }","duration":"206.927454ms","start":"2026-05-08T18:13:26.191629Z","end":"2026-05-08T18:13:26.398556Z","steps":["trace[1745081691] 'agreement among raft nodes before linearized reading' (duration: 206.824774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:26.398565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.460612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-08T18:13:26.398615Z","caller":"traceutil/trace.go:171","msg":"trace[30107961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:305; }","duration":"137.555104ms","start":"2026-05-08T18:13:26.261045Z","end":"2026-05-08T18:13:26.3986Z","steps":["trace[30107961] 'agreement among raft nodes before linearized reading' (duration: 137.408333ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:26.582589Z","caller":"traceutil/trace.go:171","msg":"trace[740794636] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"194.570924ms","start":"2026-05-08T18:13:26.387982Z","end":"2026-05-08T18:13:26.582553Z","steps":["trace[740794636] 'process raft request' (duration: 125.406875ms)","trace[740794636] 'compare' (duration: 69.016968ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:26.582697Z","caller":"traceutil/trace.go:171","msg":"trace[1593615768] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"194.683155ms","start":"2026-05-08T18:13:26.387977Z","end":"2026-05-08T18:13:26.58266Z","steps":["trace[1593615768] 'process raft request' (duration: 194.524734ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:26.582732Z","caller":"traceutil/trace.go:171","msg":"trace[836305819] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"194.221292ms","start":"2026-05-08T18:13:26.388486Z","end":"2026-05-08T18:13:26.582708Z","steps":["trace[836305819] 'process raft request' (duration: 194.077381ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:26.582786Z","caller":"traceutil/trace.go:171","msg":"trace[1566883376] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"180.394273ms","start":"2026-05-08T18:13:26.402363Z","end":"2026-05-08T18:13:26.582757Z","steps":["trace[1566883376] 'process raft request' (duration: 180.355482ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:26.582791Z","caller":"traceutil/trace.go:171","msg":"trace[952696761] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:312; }","duration":"184.335908ms","start":"2026-05-08T18:13:26.398439Z","end":"2026-05-08T18:13:26.582775Z","steps":["trace[952696761] 'read index received' (duration: 115.043637ms)","trace[952696761] 'applied index is now lower than readState.Index' (duration: 69.290141ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:26.582805Z","caller":"traceutil/trace.go:171","msg":"trace[2107952229] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"181.187208ms","start":"2026-05-08T18:13:26.401609Z","end":"2026-05-08T18:13:26.582796Z","steps":["trace[2107952229] 'process raft request' (duration: 181.063187ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:26.582852Z","caller":"traceutil/trace.go:171","msg":"trace[1646269376] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"193.156835ms","start":"2026-05-08T18:13:26.389681Z","end":"2026-05-08T18:13:26.582838Z","steps":["trace[1646269376] 'process raft request' (duration: 192.909973ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:26.582801Z","caller":"traceutil/trace.go:171","msg":"trace[1660758681] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"194.728856ms","start":"2026-05-08T18:13:26.388055Z","end":"2026-05-08T18:13:26.582783Z","steps":["trace[1660758681] 'process raft request' (duration: 194.473154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:26.582934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.211604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-08T18:13:26.583871Z","caller":"traceutil/trace.go:171","msg":"trace[842486397] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:308; }","duration":"200.194742ms","start":"2026-05-08T18:13:26.38366Z","end":"2026-05-08T18:13:26.583854Z","steps":["trace[842486397] 'agreement among raft nodes before linearized reading' (duration: 199.174665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:26.583002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.144901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-08T18:13:26.584026Z","caller":"traceutil/trace.go:171","msg":"trace[1881870766] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:308; }","duration":"198.187639ms","start":"2026-05-08T18:13:26.385815Z","end":"2026-05-08T18:13:26.584002Z","steps":["trace[1881870766] 'agreement among raft nodes before linearized reading' (duration: 197.150432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:26.583028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.134288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-05-08T18:13:26.584206Z","caller":"traceutil/trace.go:171","msg":"trace[891560001] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:308; }","duration":"196.308626ms","start":"2026-05-08T18:13:26.387864Z","end":"2026-05-08T18:13:26.584173Z","steps":["trace[891560001] 'agreement among raft nodes before linearized reading' (duration: 195.072048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:26.780661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.660838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-05-08T18:13:26.781288Z","caller":"traceutil/trace.go:171","msg":"trace[1620764934] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"170.295807ms","start":"2026-05-08T18:13:26.610716Z","end":"2026-05-08T18:13:26.781012Z","steps":["trace[1620764934] 'process raft request' (duration: 77.336443ms)","trace[1620764934] 'compare' (duration: 92.428541ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:26.781252Z","caller":"traceutil/trace.go:171","msg":"trace[470434653] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:311; }","duration":"194.280282ms","start":"2026-05-08T18:13:26.586946Z","end":"2026-05-08T18:13:26.781226Z","steps":["trace[470434653] 'agreement among raft nodes before linearized reading' (duration: 95.876273ms)","trace[470434653] 'range keys from in-memory index tree' (duration: 97.729265ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:13:26.783179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.788146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T18:13:26.784489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.547391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-08T18:13:26.783258Z","caller":"traceutil/trace.go:171","msg":"trace[427148503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:313; }","duration":"137.917256ms","start":"2026-05-08T18:13:26.645318Z","end":"2026-05-08T18:13:26.783235Z","steps":["trace[427148503] 'agreement among raft nodes before linearized reading' (duration: 136.344456ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:26.784739Z","caller":"traceutil/trace.go:171","msg":"trace[1022500916] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"195.890703ms","start":"2026-05-08T18:13:26.588687Z","end":"2026-05-08T18:13:26.784577Z","steps":["trace[1022500916] 'agreement among raft nodes before linearized reading' (duration: 93.86375ms)","trace[1022500916] 'range keys from in-memory index tree' (duration: 98.042077ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:26.938868Z","caller":"traceutil/trace.go:171","msg":"trace[685006114] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"146.052699ms","start":"2026-05-08T18:13:26.792798Z","end":"2026-05-08T18:13:26.93885Z","steps":["trace[685006114] 'process raft request' (duration: 146.002949ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:26.938897Z","caller":"traceutil/trace.go:171","msg":"trace[948777782] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"148.090753ms","start":"2026-05-08T18:13:26.790782Z","end":"2026-05-08T18:13:26.938873Z","steps":["trace[948777782] 'process raft request' (duration: 147.929451ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:27.437526Z","caller":"traceutil/trace.go:171","msg":"trace[1349934148] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:344; }","duration":"146.198834ms","start":"2026-05-08T18:13:27.291303Z","end":"2026-05-08T18:13:27.437501Z","steps":["trace[1349934148] 'read index received' (duration: 126.011494ms)","trace[1349934148] 'applied index is now lower than readState.Index' (duration: 20.18575ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:13:27.43765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.335854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-08T18:13:27.437693Z","caller":"traceutil/trace.go:171","msg":"trace[180231168] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:330; }","duration":"146.421675ms","start":"2026-05-08T18:13:27.291261Z","end":"2026-05-08T18:13:27.437683Z","steps":["trace[180231168] 'agreement among raft nodes before linearized reading' (duration: 146.331564ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:27.437937Z","caller":"traceutil/trace.go:171","msg":"trace[940226970] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"196.445418ms","start":"2026-05-08T18:13:27.241458Z","end":"2026-05-08T18:13:27.437904Z","steps":["trace[940226970] 'process raft request' (duration: 175.931976ms)","trace[940226970] 'compare' (duration: 19.970909ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:27.775447Z","caller":"traceutil/trace.go:171","msg":"trace[611765876] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:346; }","duration":"283.757601ms","start":"2026-05-08T18:13:27.491664Z","end":"2026-05-08T18:13:27.775421Z","steps":["trace[611765876] 'read index received' (duration: 185.817959ms)","trace[611765876] 'applied index is now lower than readState.Index' (duration: 97.938742ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:27.775544Z","caller":"traceutil/trace.go:171","msg":"trace[2111595395] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"330.769535ms","start":"2026-05-08T18:13:27.444735Z","end":"2026-05-08T18:13:27.775505Z","steps":["trace[2111595395] 'process raft request' (duration: 232.788232ms)","trace[2111595395] 'compare' (duration: 97.777852ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:13:27.775665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.669712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T18:13:27.775738Z","caller":"traceutil/trace.go:171","msg":"trace[604036208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:332; }","duration":"122.776622ms","start":"2026-05-08T18:13:27.652937Z","end":"2026-05-08T18:13:27.775714Z","steps":["trace[604036208] 'agreement among raft nodes before linearized reading' (duration: 122.648132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:27.775623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.942274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-08T18:13:27.775823Z","caller":"traceutil/trace.go:171","msg":"trace[1152963626] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:332; }","duration":"284.183725ms","start":"2026-05-08T18:13:27.491611Z","end":"2026-05-08T18:13:27.775795Z","steps":["trace[1152963626] 'agreement among raft nodes before linearized reading' (duration: 283.920173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:27.775923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:13:27.444713Z","time spent":"330.920707ms","remote":"127.0.0.1:57504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:13:29.593079Z","caller":"traceutil/trace.go:171","msg":"trace[1978372867] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"230.191886ms","start":"2026-05-08T18:13:29.362865Z","end":"2026-05-08T18:13:29.593057Z","steps":["trace[1978372867] 'process raft request' (duration: 229.937894ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:29.80629Z","caller":"traceutil/trace.go:171","msg":"trace[786226760] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"101.764339ms","start":"2026-05-08T18:13:29.704501Z","end":"2026-05-08T18:13:29.806265Z","steps":["trace[786226760] 'process raft request' (duration: 95.130677ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:30.08512Z","caller":"traceutil/trace.go:171","msg":"trace[1070970396] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"270.057509ms","start":"2026-05-08T18:13:29.815036Z","end":"2026-05-08T18:13:30.085093Z","steps":["trace[1070970396] 'process raft request' (duration: 269.908178ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:30.257326Z","caller":"traceutil/trace.go:171","msg":"trace[2010153739] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"140.983553ms","start":"2026-05-08T18:13:30.116312Z","end":"2026-05-08T18:13:30.257295Z","steps":["trace[2010153739] 'process raft request' (duration: 115.417631ms)","trace[2010153739] 'compare' (duration: 25.429111ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:30.414812Z","caller":"traceutil/trace.go:171","msg":"trace[1020949456] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"145.692103ms","start":"2026-05-08T18:13:30.269084Z","end":"2026-05-08T18:13:30.414776Z","steps":["trace[1020949456] 'process raft request' (duration: 145.490462ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:30.559243Z","caller":"traceutil/trace.go:171","msg":"trace[621197372] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"128.270773ms","start":"2026-05-08T18:13:30.430943Z","end":"2026-05-08T18:13:30.559213Z","steps":["trace[621197372] 'process raft request' (duration: 94.795221ms)","trace[621197372] 'compare' (duration: 33.291201ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:13:33.1473Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960363967004534,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T18:13:33.222574Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.128152221s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T18:13:33.223053Z","caller":"traceutil/trace.go:171","msg":"trace[1307047540] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"576.651395ms","start":"2026-05-08T18:13:32.646373Z","end":"2026-05-08T18:13:33.223024Z","steps":["trace[1307047540] 'read index received' (duration: 576.418433ms)","trace[1307047540] 'applied index is now lower than readState.Index' (duration: 231.572µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:33.223148Z","caller":"traceutil/trace.go:171","msg":"trace[1754846537] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"1.128791114s","start":"2026-05-08T18:13:32.094316Z","end":"2026-05-08T18:13:33.223107Z","steps":["trace[1754846537] 'process raft request' (duration: 1.128567833s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:33.223321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.083894ms","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-05-08T18:13:33.223389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:13:32.094299Z","time spent":"1.128989195s","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:13:33.223411Z","caller":"traceutil/trace.go:171","msg":"trace[220554734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:401; }","duration":"575.199955ms","start":"2026-05-08T18:13:32.64819Z","end":"2026-05-08T18:13:33.22339Z","steps":["trace[220554734] 'agreement among raft nodes before linearized reading' (duration: 575.032924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:33.223412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.054982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-08T18:13:33.223462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:13:32.648183Z","time spent":"575.269395ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T18:13:33.223491Z","caller":"traceutil/trace.go:171","msg":"trace[1632553720] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:401; }","duration":"188.178253ms","start":"2026-05-08T18:13:33.035292Z","end":"2026-05-08T18:13:33.22347Z","steps":["trace[1632553720] 'agreement among raft nodes before linearized reading' (duration: 187.999091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:33.223583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.812347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T18:13:33.223651Z","caller":"traceutil/trace.go:171","msg":"trace[1913885575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:401; }","duration":"332.914618ms","start":"2026-05-08T18:13:32.890718Z","end":"2026-05-08T18:13:33.223633Z","steps":["trace[1913885575] 'agreement among raft nodes before linearized reading' (duration: 332.812877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:33.223694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:13:32.890651Z","time spent":"333.030948ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T18:13:33.223817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.814066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:13:33.223865Z","caller":"traceutil/trace.go:171","msg":"trace[1745531607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"577.51703ms","start":"2026-05-08T18:13:32.646334Z","end":"2026-05-08T18:13:33.223851Z","steps":["trace[1745531607] 'agreement among raft nodes before linearized reading' (duration: 576.810926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:33.22393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:13:32.646318Z","time spent":"577.60042ms","remote":"127.0.0.1:57308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T18:13:33.479465Z","caller":"traceutil/trace.go:171","msg":"trace[919183954] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"250.538791ms","start":"2026-05-08T18:13:33.228902Z","end":"2026-05-08T18:13:33.479441Z","steps":["trace[919183954] 'process raft request' (duration: 250.319099ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:33.480006Z","caller":"traceutil/trace.go:171","msg":"trace[158005714] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"250.524211ms","start":"2026-05-08T18:13:33.22947Z","end":"2026-05-08T18:13:33.479994Z","steps":["trace[158005714] 'process raft request' (duration: 250.166709ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:33.480015Z","caller":"traceutil/trace.go:171","msg":"trace[957402547] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"246.478426ms","start":"2026-05-08T18:13:33.23351Z","end":"2026-05-08T18:13:33.479988Z","steps":["trace[957402547] 'process raft request' (duration: 246.229545ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:33.480027Z","caller":"traceutil/trace.go:171","msg":"trace[388742122] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"250.910423ms","start":"2026-05-08T18:13:33.229071Z","end":"2026-05-08T18:13:33.479982Z","steps":["trace[388742122] 'process raft request' (duration: 250.33211ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:34.261721Z","caller":"traceutil/trace.go:171","msg":"trace[1236834048] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"133.568068ms","start":"2026-05-08T18:13:34.12813Z","end":"2026-05-08T18:13:34.261698Z","steps":["trace[1236834048] 'process raft request' (duration: 133.439647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:37.725831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.471006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T18:13:37.725903Z","caller":"traceutil/trace.go:171","msg":"trace[1683278207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"194.591688ms","start":"2026-05-08T18:13:37.531295Z","end":"2026-05-08T18:13:37.725887Z","steps":["trace[1683278207] 'range keys from in-memory index tree' (duration: 194.344076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:37.725873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.116694ms","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-05-08T18:13:37.726052Z","caller":"traceutil/trace.go:171","msg":"trace[842626030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"194.230265ms","start":"2026-05-08T18:13:37.531713Z","end":"2026-05-08T18:13:37.725943Z","steps":["trace[842626030] 'range keys from in-memory index tree' (duration: 194.024404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:37.726255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.895229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T18:13:37.726357Z","caller":"traceutil/trace.go:171","msg":"trace[427106240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"226.03536ms","start":"2026-05-08T18:13:37.500307Z","end":"2026-05-08T18:13:37.726342Z","steps":["trace[427106240] 'range keys from in-memory index tree' (duration: 225.746987ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:37.85119Z","caller":"traceutil/trace.go:171","msg":"trace[1869241690] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"121.024359ms","start":"2026-05-08T18:13:37.730146Z","end":"2026-05-08T18:13:37.85117Z","steps":["trace[1869241690] 'process raft request' (duration: 120.885108ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:37.868304Z","caller":"traceutil/trace.go:171","msg":"trace[1276141177] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"137.436279ms","start":"2026-05-08T18:13:37.730839Z","end":"2026-05-08T18:13:37.868276Z","steps":["trace[1276141177] 'process raft request' (duration: 137.380538ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:37.868376Z","caller":"traceutil/trace.go:171","msg":"trace[14769202] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"137.545809ms","start":"2026-05-08T18:13:37.730809Z","end":"2026-05-08T18:13:37.868355Z","steps":["trace[14769202] 'process raft request' (duration: 137.292088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:38.161532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.120466ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:13:38.161669Z","caller":"traceutil/trace.go:171","msg":"trace[861741298] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:418; }","duration":"226.286087ms","start":"2026-05-08T18:13:37.935366Z","end":"2026-05-08T18:13:38.161652Z","steps":["trace[861741298] 'range keys from in-memory index tree' (duration: 226.102065ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:41.918312Z","caller":"traceutil/trace.go:171","msg":"trace[1693890541] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:442; }","duration":"326.032403ms","start":"2026-05-08T18:13:41.592261Z","end":"2026-05-08T18:13:41.918293Z","steps":["trace[1693890541] 'read index received' (duration: 325.813402ms)","trace[1693890541] 'applied index is now lower than readState.Index' (duration: 218.041µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:13:41.918539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.964755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:13:41.91863Z","caller":"traceutil/trace.go:171","msg":"trace[2095566814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"273.102026ms","start":"2026-05-08T18:13:41.645513Z","end":"2026-05-08T18:13:41.918615Z","steps":["trace[2095566814] 'agreement among raft nodes before linearized reading' (duration: 272.979145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:41.918552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.287804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-05-08T18:13:41.918752Z","caller":"traceutil/trace.go:171","msg":"trace[1824927026] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:425; }","duration":"326.516907ms","start":"2026-05-08T18:13:41.592207Z","end":"2026-05-08T18:13:41.918724Z","steps":["trace[1824927026] 'agreement among raft nodes before linearized reading' (duration: 326.279395ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:41.919601Z","caller":"traceutil/trace.go:171","msg":"trace[1932850378] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"638.172494ms","start":"2026-05-08T18:13:41.281394Z","end":"2026-05-08T18:13:41.919566Z","steps":["trace[1932850378] 'process raft request' (duration: 636.781505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:41.919718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:13:41.592186Z","time spent":"327.471792ms","remote":"127.0.0.1:57490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4398,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-08T18:13:41.919794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:13:41.281378Z","time spent":"638.312074ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:13:46.247408Z","caller":"traceutil/trace.go:171","msg":"trace[2085478968] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"150.20423ms","start":"2026-05-08T18:13:46.097182Z","end":"2026-05-08T18:13:46.247386Z","steps":["trace[2085478968] 'process raft request' (duration: 113.193147ms)","trace[2085478968] 'compare' (duration: 36.921542ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:47.438303Z","caller":"traceutil/trace.go:171","msg":"trace[593968626] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"281.25597ms","start":"2026-05-08T18:13:47.157015Z","end":"2026-05-08T18:13:47.438271Z","steps":["trace[593968626] 'process raft request' (duration: 281.113233ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:48.227262Z","caller":"traceutil/trace.go:171","msg":"trace[1400759449] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"204.389027ms","start":"2026-05-08T18:13:48.022848Z","end":"2026-05-08T18:13:48.227237Z","steps":["trace[1400759449] 'read index received' (duration: 204.216858ms)","trace[1400759449] 'applied index is now lower than readState.Index' (duration: 171.079µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:48.227287Z","caller":"traceutil/trace.go:171","msg":"trace[1776945710] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"209.28899ms","start":"2026-05-08T18:13:48.017973Z","end":"2026-05-08T18:13:48.227262Z","steps":["trace[1776945710] 'process raft request' (duration: 209.113581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:48.227473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.101071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T18:13:48.227506Z","caller":"traceutil/trace.go:171","msg":"trace[2032789220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:469; }","duration":"160.169575ms","start":"2026-05-08T18:13:48.067328Z","end":"2026-05-08T18:13:48.227497Z","steps":["trace[2032789220] 'agreement among raft nodes before linearized reading' (duration: 160.07961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:48.227577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.695362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-08T18:13:48.227654Z","caller":"traceutil/trace.go:171","msg":"trace[1145693569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"204.803208ms","start":"2026-05-08T18:13:48.022832Z","end":"2026-05-08T18:13:48.227635Z","steps":["trace[1145693569] 'agreement among raft nodes before linearized reading' (duration: 204.519783ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:51.562036Z","caller":"traceutil/trace.go:171","msg":"trace[471722361] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"205.114628ms","start":"2026-05-08T18:13:51.356898Z","end":"2026-05-08T18:13:51.562013Z","steps":["trace[471722361] 'process raft request' (duration: 204.992832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:56.904131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960363967004780,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T18:13:57.270441Z","caller":"traceutil/trace.go:171","msg":"trace[324611348] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"866.537726ms","start":"2026-05-08T18:13:56.403887Z","end":"2026-05-08T18:13:57.270424Z","steps":["trace[324611348] 'read index received' (duration: 866.41773ms)","trace[324611348] 'applied index is now lower than readState.Index' (duration: 119.206µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:13:57.270565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.667932ms","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-05-08T18:13:57.270549Z","caller":"traceutil/trace.go:171","msg":"trace[580260603] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"960.256343ms","start":"2026-05-08T18:13:56.310259Z","end":"2026-05-08T18:13:57.270516Z","steps":["trace[580260603] 'process raft request' (duration: 960.058454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:57.270625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.067154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:13:57.270644Z","caller":"traceutil/trace.go:171","msg":"trace[1519794047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"623.130597ms","start":"2026-05-08T18:13:56.647508Z","end":"2026-05-08T18:13:57.270639Z","steps":["trace[1519794047] 'agreement among raft nodes before linearized reading' (duration: 623.103146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:57.270665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:13:56.647483Z","time spent":"623.176659ms","remote":"127.0.0.1:57308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T18:13:57.270716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:13:56.310232Z","time spent":"960.397879ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:13:57.270691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.283548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T18:13:57.270708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.224655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T18:13:57.270782Z","caller":"traceutil/trace.go:171","msg":"trace[788241628] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"802.409614ms","start":"2026-05-08T18:13:56.468353Z","end":"2026-05-08T18:13:57.270763Z","steps":["trace[788241628] 'agreement among raft nodes before linearized reading' (duration: 802.238766ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:57.270791Z","caller":"traceutil/trace.go:171","msg":"trace[145224384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"802.352161ms","start":"2026-05-08T18:13:56.468422Z","end":"2026-05-08T18:13:57.270774Z","steps":["trace[145224384] 'agreement among raft nodes before linearized reading' (duration: 802.217355ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:57.270591Z","caller":"traceutil/trace.go:171","msg":"trace[862630764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"866.733625ms","start":"2026-05-08T18:13:56.403847Z","end":"2026-05-08T18:13:57.270581Z","steps":["trace[862630764] 'agreement among raft nodes before linearized reading' (duration: 866.677322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:13:57.270831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:13:56.4684Z","time spent":"802.422964ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T18:13:57.270827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:13:56.468342Z","time spent":"802.475606ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T18:13:57.270836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:13:56.403829Z","time spent":"867.000727ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T18:13:57.717919Z","caller":"traceutil/trace.go:171","msg":"trace[1147755188] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"109.08016ms","start":"2026-05-08T18:13:57.608811Z","end":"2026-05-08T18:13:57.717892Z","steps":["trace[1147755188] 'process raft request' (duration: 108.937794ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:57.718014Z","caller":"traceutil/trace.go:171","msg":"trace[1005416711] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"113.068538ms","start":"2026-05-08T18:13:57.604887Z","end":"2026-05-08T18:13:57.717955Z","steps":["trace[1005416711] 'process raft request' (duration: 112.828808ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:57.718137Z","caller":"traceutil/trace.go:171","msg":"trace[2096734709] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"109.171674ms","start":"2026-05-08T18:13:57.608937Z","end":"2026-05-08T18:13:57.718109Z","steps":["trace[2096734709] 'process raft request' (duration: 108.84946ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:57.718376Z","caller":"traceutil/trace.go:171","msg":"trace[1740621051] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"105.291681ms","start":"2026-05-08T18:13:57.613072Z","end":"2026-05-08T18:13:57.718363Z","steps":["trace[1740621051] 'process raft request' (duration: 104.793179ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:57.718771Z","caller":"traceutil/trace.go:171","msg":"trace[1929128838] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"103.757043ms","start":"2026-05-08T18:13:57.615002Z","end":"2026-05-08T18:13:57.718759Z","steps":["trace[1929128838] 'process raft request' (duration: 103.23168ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:13:57.71839Z","caller":"traceutil/trace.go:171","msg":"trace[363268610] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"117.985528ms","start":"2026-05-08T18:13:57.600381Z","end":"2026-05-08T18:13:57.718367Z","steps":["trace[363268610] 'process raft request' (duration: 83.46867ms)","trace[363268610] 'compare' (duration: 33.717412ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:13:57.7181Z","caller":"traceutil/trace.go:171","msg":"trace[540580140] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"105.03202ms","start":"2026-05-08T18:13:57.61299Z","end":"2026-05-08T18:13:57.718022Z","steps":["trace[540580140] 'process raft request' (duration: 104.842362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:01.775038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.174742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:14:01.775128Z","caller":"traceutil/trace.go:171","msg":"trace[132419413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"128.299876ms","start":"2026-05-08T18:14:01.646808Z","end":"2026-05-08T18:14:01.775108Z","steps":["trace[132419413] 'range keys from in-memory index tree' (duration: 128.122339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:04.694045Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960363967004997,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T18:14:05.195196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960363967004997,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T18:14:05.695912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960363967004997,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T18:14:05.980699Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.05645847s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T18:14:05.98185Z","caller":"traceutil/trace.go:171","msg":"trace[1346387104] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"1.788120028s","start":"2026-05-08T18:14:04.1937Z","end":"2026-05-08T18:14:05.98182Z","steps":["trace[1346387104] 'read index received' (duration: 1.787119947s)","trace[1346387104] 'applied index is now lower than readState.Index' (duration: 999.071µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:14:05.981989Z","caller":"traceutil/trace.go:171","msg":"trace[1532366777] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"2.058566337s","start":"2026-05-08T18:14:03.923378Z","end":"2026-05-08T18:14:05.981944Z","steps":["trace[1532366777] 'process raft request' (duration: 2.057766804s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:05.982095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.788374987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q78x6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-08T18:14:05.982151Z","caller":"traceutil/trace.go:171","msg":"trace[128618353] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q78x6; range_end:; response_count:1; response_revision:632; }","duration":"1.788482232s","start":"2026-05-08T18:14:04.193653Z","end":"2026-05-08T18:14:05.982135Z","steps":["trace[128618353] 'agreement among raft nodes before linearized reading' (duration: 1.788299155s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:05.982208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:03.923355Z","time spent":"2.058744414s","remote":"127.0.0.1:57414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:14:05.982212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:04.193632Z","time spent":"1.788568646s","remote":"127.0.0.1:57504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-q78x6\" "} {"level":"warn","ts":"2026-05-08T18:14:05.982256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.343937ms","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-05-08T18:14:05.982339Z","caller":"traceutil/trace.go:171","msg":"trace[414960186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"620.467752ms","start":"2026-05-08T18:14:05.36185Z","end":"2026-05-08T18:14:05.982318Z","steps":["trace[414960186] 'agreement among raft nodes before linearized reading' (duration: 620.208041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:05.982391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:05.361839Z","time spent":"620.543285ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T18:14:05.982384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.055824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-08T18:14:05.982498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.279321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T18:14:05.982509Z","caller":"traceutil/trace.go:171","msg":"trace[1935389869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"618.215891ms","start":"2026-05-08T18:14:05.364259Z","end":"2026-05-08T18:14:05.982475Z","steps":["trace[1935389869] 'agreement among raft nodes before linearized reading' (duration: 617.855087ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:14:05.982573Z","caller":"traceutil/trace.go:171","msg":"trace[758658129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"652.367894ms","start":"2026-05-08T18:14:05.330171Z","end":"2026-05-08T18:14:05.982539Z","steps":["trace[758658129] 'agreement among raft nodes before linearized reading' (duration: 652.169756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:05.982576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:05.364241Z","time spent":"618.320995ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T18:14:05.98261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:05.330149Z","time spent":"652.453477ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T18:14:05.982791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.336148521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T18:14:05.982744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.791899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T18:14:05.982948Z","caller":"traceutil/trace.go:171","msg":"trace[1653459339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"1.336216953s","start":"2026-05-08T18:14:04.646598Z","end":"2026-05-08T18:14:05.982815Z","steps":["trace[1653459339] 'agreement among raft nodes before linearized reading' (duration: 1.335390019s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:05.983004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:04.646577Z","time spent":"1.336416551s","remote":"127.0.0.1:57308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T18:14:05.982928Z","caller":"traceutil/trace.go:171","msg":"trace[493940553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"649.998798ms","start":"2026-05-08T18:14:05.332908Z","end":"2026-05-08T18:14:05.982907Z","steps":["trace[493940553] 'agreement among raft nodes before linearized reading' (duration: 649.433865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:05.983132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:05.332898Z","time spent":"650.219997ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T18:14:06.494961Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960363967005001,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T18:14:06.882991Z","caller":"traceutil/trace.go:171","msg":"trace[219187827] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"896.035282ms","start":"2026-05-08T18:14:05.986927Z","end":"2026-05-08T18:14:06.882963Z","steps":["trace[219187827] 'process raft request' (duration: 895.852525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:06.883575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:05.986915Z","time spent":"896.171478ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:14:07.992238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998303455s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-08T18:14:07.992321Z","caller":"traceutil/trace.go:171","msg":"trace[1873840248] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99842457s","start":"2026-05-08T18:14:05.993878Z","end":"2026-05-08T18:14:07.992302Z","steps":["trace[1873840248] 'agreement among raft nodes before linearized reading' (duration: 1.998324936s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:07.992369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:05.993862Z","time spent":"1.998497832s","remote":"127.0.0.1:57308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/08 18:14:07 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-08T18:14:08.208292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.761655ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:14:08.208411Z","caller":"traceutil/trace.go:171","msg":"trace[1150172384] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:633; }","duration":"272.89094ms","start":"2026-05-08T18:14:07.935501Z","end":"2026-05-08T18:14:08.208392Z","steps":["trace[1150172384] 'range keys from in-memory index tree' (duration: 272.743573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:08.208567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143761981s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:14:08.208902Z","caller":"traceutil/trace.go:171","msg":"trace[522788595] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"2.221713184s","start":"2026-05-08T18:14:05.987164Z","end":"2026-05-08T18:14:08.208878Z","steps":["trace[522788595] 'process raft request' (duration: 1.077497946s)","trace[522788595] 'compare' (duration: 1.143619215s)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:14:08.20903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:05.987142Z","time spent":"2.221839979s","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:14:08.209287Z","caller":"traceutil/trace.go:171","msg":"trace[544478611] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"2.214879226s","start":"2026-05-08T18:14:05.994391Z","end":"2026-05-08T18:14:08.20927Z","steps":["trace[544478611] 'process raft request' (duration: 2.214361176s)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:14:08.209363Z","caller":"traceutil/trace.go:171","msg":"trace[96646229] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"2.210218948s","start":"2026-05-08T18:14:05.99913Z","end":"2026-05-08T18:14:08.209349Z","steps":["trace[96646229] 'process raft request' (duration: 2.209677956s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:08.209379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:05.994382Z","time spent":"2.214950819s","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:14:08.209472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:05.999114Z","time spent":"2.210294961s","remote":"127.0.0.1:57504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:14:08.209766Z","caller":"traceutil/trace.go:171","msg":"trace[408200199] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:659; }","duration":"2.215848355s","start":"2026-05-08T18:14:05.993907Z","end":"2026-05-08T18:14:08.209755Z","steps":["trace[408200199] 'read index received' (duration: 889.523289ms)","trace[408200199] 'applied index is now lower than readState.Index' (duration: 1.326324106s)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:14:08.209955Z","caller":"traceutil/trace.go:171","msg":"trace[1687215402] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"2.172051748s","start":"2026-05-08T18:14:06.037894Z","end":"2026-05-08T18:14:08.209946Z","steps":["trace[1687215402] 'process raft request' (duration: 2.171141872s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:08.210014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:06.037874Z","time spent":"2.172112159s","remote":"127.0.0.1:57832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T18:14:08.210792Z","caller":"traceutil/trace.go:171","msg":"trace[2123880165] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"2.214645587s","start":"2026-05-08T18:14:05.994305Z","end":"2026-05-08T18:14:08.208951Z","steps":["trace[2123880165] 'process raft request' (duration: 2.214375406s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:08.21091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:05.994281Z","time spent":"2.216571012s","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:14:08.710503Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960363967005009,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T18:14:09.011682Z","caller":"traceutil/trace.go:171","msg":"trace[2085848343] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"929.564213ms","start":"2026-05-08T18:14:08.082097Z","end":"2026-05-08T18:14:09.011661Z","steps":["trace[2085848343] 'process raft request' (duration: 866.035852ms)","trace[2085848343] 'compare' (duration: 63.071283ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:14:09.011802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:08.08206Z","time spent":"929.681257ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:14:09.014355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021761081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:14:09.014431Z","caller":"traceutil/trace.go:171","msg":"trace[1774436305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"1.021909118s","start":"2026-05-08T18:14:07.992509Z","end":"2026-05-08T18:14:09.014418Z","steps":["trace[1774436305] 'agreement among raft nodes before linearized reading' (duration: 1.021789113s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:09.014471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:07.992487Z","time spent":"1.021975679s","remote":"127.0.0.1:57320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T18:14:09.014583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.951176138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:14:09.014664Z","caller":"traceutil/trace.go:171","msg":"trace[1633838754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"1.951297802s","start":"2026-05-08T18:14:07.063348Z","end":"2026-05-08T18:14:09.014645Z","steps":["trace[1633838754] 'agreement among raft nodes before linearized reading' (duration: 1.951144506s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:09.014724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:07.063328Z","time spent":"1.951376926s","remote":"127.0.0.1:57308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T18:14:09.014843Z","caller":"traceutil/trace.go:171","msg":"trace[55532131] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"804.390539ms","start":"2026-05-08T18:14:08.209798Z","end":"2026-05-08T18:14:09.014188Z","steps":["trace[55532131] 'read index received' (duration: 738.33276ms)","trace[55532131] 'applied index is now lower than readState.Index' (duration: 66.055489ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:14:09.01505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.332746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:14:09.015095Z","caller":"traceutil/trace.go:171","msg":"trace[711058324] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:639; }","duration":"793.427009ms","start":"2026-05-08T18:14:08.221656Z","end":"2026-05-08T18:14:09.015083Z","steps":["trace[711058324] 'agreement among raft nodes before linearized reading' (duration: 793.355117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:09.015162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.456779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T18:14:09.015205Z","caller":"traceutil/trace.go:171","msg":"trace[818442865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"126.49663ms","start":"2026-05-08T18:14:08.888694Z","end":"2026-05-08T18:14:09.015191Z","steps":["trace[818442865] 'agreement among raft nodes before linearized reading' (duration: 126.354835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:09.015216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:08.221631Z","time spent":"793.560895ms","remote":"127.0.0.1:57570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-08T18:14:10.588745Z","caller":"traceutil/trace.go:171","msg":"trace[233628839] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"230.185222ms","start":"2026-05-08T18:14:10.358511Z","end":"2026-05-08T18:14:10.588696Z","steps":["trace[233628839] 'process raft request' (duration: 229.932662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:24.830486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960363967005328,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T18:14:24.964614Z","caller":"traceutil/trace.go:171","msg":"trace[2142902256] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"634.382795ms","start":"2026-05-08T18:14:24.330207Z","end":"2026-05-08T18:14:24.96459Z","steps":["trace[2142902256] 'read index received' (duration: 634.187368ms)","trace[2142902256] 'applied index is now lower than readState.Index' (duration: 194.587µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:14:24.964688Z","caller":"traceutil/trace.go:171","msg":"trace[175895162] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"637.773415ms","start":"2026-05-08T18:14:24.326885Z","end":"2026-05-08T18:14:24.964658Z","steps":["trace[175895162] 'process raft request' (duration: 637.562438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:24.964775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.572011ms","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-05-08T18:14:24.964817Z","caller":"traceutil/trace.go:171","msg":"trace[804637255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"634.661694ms","start":"2026-05-08T18:14:24.330146Z","end":"2026-05-08T18:14:24.964808Z","steps":["trace[804637255] 'agreement among raft nodes before linearized reading' (duration: 634.54277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:24.96483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:24.326862Z","time spent":"637.893099ms","remote":"127.0.0.1:57570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:14:24.964907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.486787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T18:14:24.964932Z","caller":"traceutil/trace.go:171","msg":"trace[1262284153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"626.540359ms","start":"2026-05-08T18:14:24.338386Z","end":"2026-05-08T18:14:24.964927Z","steps":["trace[1262284153] 'agreement among raft nodes before linearized reading' (duration: 626.481517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:24.96495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:24.338362Z","time spent":"626.58364ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T18:14:24.964982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.60022ms","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-05-08T18:14:24.964847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:24.33012Z","time spent":"634.716126ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T18:14:24.965037Z","caller":"traceutil/trace.go:171","msg":"trace[1136994586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:801; }","duration":"298.685233ms","start":"2026-05-08T18:14:24.666338Z","end":"2026-05-08T18:14:24.965023Z","steps":["trace[1136994586] 'agreement among raft nodes before linearized reading' (duration: 298.531658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:24.965183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.654889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T18:14:24.965209Z","caller":"traceutil/trace.go:171","msg":"trace[1602072249] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"621.721221ms","start":"2026-05-08T18:14:24.343482Z","end":"2026-05-08T18:14:24.965204Z","steps":["trace[1602072249] 'agreement among raft nodes before linearized reading' (duration: 621.661029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:24.965229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:24.343471Z","time spent":"621.754672ms","remote":"127.0.0.1:57588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T18:14:35.979678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.930188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:14:35.979909Z","caller":"traceutil/trace.go:171","msg":"trace[1714041210] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:960; }","duration":"330.750999ms","start":"2026-05-08T18:14:35.64914Z","end":"2026-05-08T18:14:35.979891Z","steps":["trace[1714041210] 'read index received' (duration: 36.669936ms)","trace[1714041210] 'applied index is now lower than readState.Index' (duration: 294.080363ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:14:35.97988Z","caller":"traceutil/trace.go:171","msg":"trace[235736059] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"342.806549ms","start":"2026-05-08T18:14:35.637034Z","end":"2026-05-08T18:14:35.979841Z","steps":["trace[235736059] 'process raft request' (duration: 48.638394ms)","trace[235736059] 'compare' (duration: 293.814365ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:14:35.979993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.846541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:14:35.98002Z","caller":"traceutil/trace.go:171","msg":"trace[1644001705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"330.899063ms","start":"2026-05-08T18:14:35.649112Z","end":"2026-05-08T18:14:35.980011Z","steps":["trace[1644001705] 'agreement among raft nodes before linearized reading' (duration: 330.835521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:35.980053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:35.649101Z","time spent":"330.942934ms","remote":"127.0.0.1:57308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T18:14:35.980136Z","caller":"traceutil/trace.go:171","msg":"trace[985327608] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"336.406213ms","start":"2026-05-08T18:14:35.64372Z","end":"2026-05-08T18:14:35.980127Z","steps":["trace[985327608] 'process raft request' (duration: 336.136185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:35.980249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:35.643707Z","time spent":"336.486265ms","remote":"127.0.0.1:57414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12761,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T18:14:35.980027Z","caller":"traceutil/trace.go:171","msg":"trace[761416716] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"341.12733ms","start":"2026-05-08T18:14:35.638872Z","end":"2026-05-08T18:14:35.979999Z","steps":["trace[761416716] 'process raft request' (duration: 340.900333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:35.980345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:35.638856Z","time spent":"341.46411ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1745,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T18:14:35.980392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:14:35.63701Z","time spent":"343.16182ms","remote":"127.0.0.1:57526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T18:14:36.33449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.98382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:14:36.33462Z","caller":"traceutil/trace.go:171","msg":"trace[669534317] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"244.88587ms","start":"2026-05-08T18:14:36.08971Z","end":"2026-05-08T18:14:36.334596Z","steps":["trace[669534317] 'process raft request' (duration: 129.726915ms)","trace[669534317] 'compare' (duration: 114.838605ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:14:36.578126Z","caller":"traceutil/trace.go:171","msg":"trace[1178049875] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"136.810658ms","start":"2026-05-08T18:14:36.44129Z","end":"2026-05-08T18:14:36.5781Z","steps":["trace[1178049875] 'process raft request' (duration: 133.577915ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:14:49.195599Z","caller":"traceutil/trace.go:171","msg":"trace[1016627800] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"104.328034ms","start":"2026-05-08T18:14:49.091247Z","end":"2026-05-08T18:14:49.195575Z","steps":["trace[1016627800] 'read index received' (duration: 104.121048ms)","trace[1016627800] 'applied index is now lower than readState.Index' (duration: 205.916µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:14:49.195707Z","caller":"traceutil/trace.go:171","msg":"trace[1229566079] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"106.15052ms","start":"2026-05-08T18:14:49.08952Z","end":"2026-05-08T18:14:49.19567Z","steps":["trace[1229566079] 'process raft request' (duration: 105.918764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:49.195812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.542979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T18:14:49.195849Z","caller":"traceutil/trace.go:171","msg":"trace[2061117436] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"104.627301ms","start":"2026-05-08T18:14:49.09121Z","end":"2026-05-08T18:14:49.195837Z","steps":["trace[2061117436] 'agreement among raft nodes before linearized reading' (duration: 104.489578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:49.195892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.644441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T18:14:49.195954Z","caller":"traceutil/trace.go:171","msg":"trace[1774728707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1007; }","duration":"102.750404ms","start":"2026-05-08T18:14:49.093179Z","end":"2026-05-08T18:14:49.19593Z","steps":["trace[1774728707] 'agreement among raft nodes before linearized reading' (duration: 102.62358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:14:49.196012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.185729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-08T18:14:49.19604Z","caller":"traceutil/trace.go:171","msg":"trace[590053007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1007; }","duration":"102.22727ms","start":"2026-05-08T18:14:49.093804Z","end":"2026-05-08T18:14:49.196031Z","steps":["trace[590053007] 'agreement among raft nodes before linearized reading' (duration: 102.148078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:15:29.701706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.446261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:15:29.701828Z","caller":"traceutil/trace.go:171","msg":"trace[112919493] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"144.972007ms","start":"2026-05-08T18:15:29.556838Z","end":"2026-05-08T18:15:29.70181Z","steps":["trace[112919493] 'process raft request' (duration: 31.335352ms)","trace[112919493] 'compare' (duration: 113.321259ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:15:29.764291Z","caller":"traceutil/trace.go:171","msg":"trace[966978006] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"206.368038ms","start":"2026-05-08T18:15:29.557892Z","end":"2026-05-08T18:15:29.76426Z","steps":["trace[966978006] 'process raft request' (duration: 206.094523ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:15:29.764285Z","caller":"traceutil/trace.go:171","msg":"trace[429341572] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"205.862968ms","start":"2026-05-08T18:15:29.558361Z","end":"2026-05-08T18:15:29.764224Z","steps":["trace[429341572] 'process raft request' (duration: 205.790847ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:15:29.916695Z","caller":"traceutil/trace.go:171","msg":"trace[411972358] linearizableReadLoop","detail":"{readStateIndex:1195; appliedIndex:1194; }","duration":"270.206304ms","start":"2026-05-08T18:15:29.646459Z","end":"2026-05-08T18:15:29.916666Z","steps":["trace[411972358] 'read index received' (duration: 118.451963ms)","trace[411972358] 'applied index is now lower than readState.Index' (duration: 151.753351ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:15:29.916744Z","caller":"traceutil/trace.go:171","msg":"trace[1344120886] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"273.562285ms","start":"2026-05-08T18:15:29.643145Z","end":"2026-05-08T18:15:29.916707Z","steps":["trace[1344120886] 'process raft request' (duration: 206.773715ms)","trace[1344120886] 'compare' (duration: 66.571266ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:15:29.917117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.644382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:15:29.917165Z","caller":"traceutil/trace.go:171","msg":"trace[1456636512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1149; }","duration":"270.758434ms","start":"2026-05-08T18:15:29.646394Z","end":"2026-05-08T18:15:29.917153Z","steps":["trace[1456636512] 'agreement among raft nodes before linearized reading' (duration: 270.374987ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:16:16.06217Z","caller":"traceutil/trace.go:171","msg":"trace[1939124133] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"149.660047ms","start":"2026-05-08T18:16:15.912487Z","end":"2026-05-08T18:16:16.062147Z","steps":["trace[1939124133] 'read index received' (duration: 149.450824ms)","trace[1939124133] 'applied index is now lower than readState.Index' (duration: 208.163µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:16:16.062306Z","caller":"traceutil/trace.go:171","msg":"trace[673024137] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"259.301403ms","start":"2026-05-08T18:16:15.802989Z","end":"2026-05-08T18:16:16.062291Z","steps":["trace[673024137] 'process raft request' (duration: 259.019989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:16.062577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.078873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T18:16:16.062618Z","caller":"traceutil/trace.go:171","msg":"trace[117346699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1329; }","duration":"150.158643ms","start":"2026-05-08T18:16:15.91245Z","end":"2026-05-08T18:16:16.062608Z","steps":["trace[117346699] 'agreement among raft nodes before linearized reading' (duration: 149.978341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:16.062564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.111752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:16:16.06281Z","caller":"traceutil/trace.go:171","msg":"trace[1230474595] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1329; }","duration":"123.440607ms","start":"2026-05-08T18:16:15.939345Z","end":"2026-05-08T18:16:16.062785Z","steps":["trace[1230474595] 'agreement among raft nodes before linearized reading' (duration: 123.111592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:16.062843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.086453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T18:16:16.06402Z","caller":"traceutil/trace.go:171","msg":"trace[738672712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1329; }","duration":"111.292955ms","start":"2026-05-08T18:16:15.95172Z","end":"2026-05-08T18:16:16.063013Z","steps":["trace[738672712] 'agreement among raft nodes before linearized reading' (duration: 111.060792ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:16:24.430252Z","caller":"traceutil/trace.go:171","msg":"trace[1367356834] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"296.761701ms","start":"2026-05-08T18:16:24.133468Z","end":"2026-05-08T18:16:24.43023Z","steps":["trace[1367356834] 'process raft request' (duration: 246.145853ms)","trace[1367356834] 'compare' (duration: 50.481986ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:16:24.430419Z","caller":"traceutil/trace.go:171","msg":"trace[472455011] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"294.142525ms","start":"2026-05-08T18:16:24.136251Z","end":"2026-05-08T18:16:24.430394Z","steps":["trace[472455011] 'read index received' (duration: 88.204718ms)","trace[472455011] 'applied index is now lower than readState.Index' (duration: 205.936707ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:16:24.430568Z","caller":"traceutil/trace.go:171","msg":"trace[1822466481] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"260.842023ms","start":"2026-05-08T18:16:24.169716Z","end":"2026-05-08T18:16:24.430558Z","steps":["trace[1822466481] 'process raft request' (duration: 260.483559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:24.430867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.758309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-08T18:16:24.430895Z","caller":"traceutil/trace.go:171","msg":"trace[23368609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1363; }","duration":"262.830951ms","start":"2026-05-08T18:16:24.168054Z","end":"2026-05-08T18:16:24.430885Z","steps":["trace[23368609] 'agreement among raft nodes before linearized reading' (duration: 262.73962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:24.430982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.714643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T18:16:24.431046Z","caller":"traceutil/trace.go:171","msg":"trace[853527896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1363; }","duration":"294.812905ms","start":"2026-05-08T18:16:24.136216Z","end":"2026-05-08T18:16:24.431029Z","steps":["trace[853527896] 'agreement among raft nodes before linearized reading' (duration: 294.605782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:24.431042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.472758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T18:16:24.431125Z","caller":"traceutil/trace.go:171","msg":"trace[1715666989] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1363; }","duration":"229.600439ms","start":"2026-05-08T18:16:24.201506Z","end":"2026-05-08T18:16:24.431107Z","steps":["trace[1715666989] 'agreement among raft nodes before linearized reading' (duration: 229.350395ms)"],"step_count":1}