{"level":"warn","ts":"2026-09-22T01:50:57.707159Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:50:57.7073Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.112: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://162.253.55.112:2380","--initial-cluster=instance=https://162.253.55.112:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.112:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.112: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-09-22T01:50:57.707464Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:50:57.70748Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.112:2380"]} {"level":"info","ts":"2026-09-22T01:50:57.707582Z","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-09-22T01:50:57.708735Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"]} {"level":"info","ts":"2026-09-22T01:50:57.709455Z","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://162.253.55.112:2380"],"listen-peer-urls":["https://162.253.55.112:2380"],"advertise-client-urls":["https://162.253.55.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.112: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-09-22T01:50:57.717557Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.468062ms"} {"level":"info","ts":"2026-09-22T01:50:57.729828Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1b80133015df7bbe","cluster-id":"96c35284d1622e20"} {"level":"info","ts":"2026-09-22T01:50:57.729905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T01:50:57.729923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became follower at term 0"} {"level":"info","ts":"2026-09-22T01:50:57.729929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1b80133015df7bbe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T01:50:57.729937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became follower at term 1"} {"level":"info","ts":"2026-09-22T01:50:57.72997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=(1981604933289343934)"} {"level":"warn","ts":"2026-09-22T01:50:57.864264Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T01:50:57.869201Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T01:50:57.871716Z","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-09-22T01:50:57.87995Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1b80133015df7bbe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T01:50:57.880381Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1b80133015df7bbe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"warn","ts":"2026-09-22T01:50:57.880472Z","logger":"raft","caller":"etcdserver/zap_raft.go:85","msg":"1b80133015df7bbe cannot campaign at term 1 since there are still 1 pending configuration changes to apply"} {"level":"info","ts":"2026-09-22T01:50:57.880573Z","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-09-22T01:50:57.880617Z","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-09-22T01:50:57.880629Z","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-09-22T01:50:57.882896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=(1981604933289343934)"} {"level":"info","ts":"2026-09-22T01:50:57.883059Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"96c35284d1622e20","local-member-id":"1b80133015df7bbe","added-peer-id":"1b80133015df7bbe","added-peer-peer-urls":["https://162.253.55.112:2380"]} {"level":"info","ts":"2026-09-22T01:50:57.88579Z","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-09-22T01:50:57.886055Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.112:2380"} {"level":"info","ts":"2026-09-22T01:50:57.886099Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.112:2380"} {"level":"info","ts":"2026-09-22T01:50:57.88641Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1b80133015df7bbe","initial-advertise-peer-urls":["https://162.253.55.112:2380"],"listen-peer-urls":["https://162.253.55.112:2380"],"advertise-client-urls":["https://162.253.55.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T01:50:57.886475Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T01:50:58.830144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T01:50:58.830246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T01:50:58.83031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe received MsgPreVoteResp from 1b80133015df7bbe at term 1"} {"level":"info","ts":"2026-09-22T01:50:58.830334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became candidate at term 2"} {"level":"info","ts":"2026-09-22T01:50:58.830355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe received MsgVoteResp from 1b80133015df7bbe at term 2"} {"level":"info","ts":"2026-09-22T01:50:58.830373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became leader at term 2"} {"level":"info","ts":"2026-09-22T01:50:58.830396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1b80133015df7bbe elected leader 1b80133015df7bbe at term 2"} {"level":"info","ts":"2026-09-22T01:50:58.840864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:50:58.840911Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:50:58.840858Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1b80133015df7bbe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.112:2379]}","request-path":"/0/members/1b80133015df7bbe/attributes","cluster-id":"96c35284d1622e20","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T01:50:58.841416Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T01:50:58.841515Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T01:50:58.841608Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:50:58.844641Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T01:50:58.844713Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.112:2379"} {"level":"info","ts":"2026-09-22T01:50:58.850275Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"96c35284d1622e20","local-member-id":"1b80133015df7bbe","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:50:58.850404Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:50:58.850451Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-09-22T01:50:59.133099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.834275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:50:59.133252Z","caller":"traceutil/trace.go:171","msg":"trace[1122231890] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"113.049787ms","start":"2026-09-22T01:50:59.020158Z","end":"2026-09-22T01:50:59.133208Z","steps":["trace[1122231890] 'range keys from in-memory index tree' (duration: 112.713304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:59.134705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.979524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-22T01:50:59.134807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.201936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:50:59.134898Z","caller":"traceutil/trace.go:171","msg":"trace[750842005] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"231.295957ms","start":"2026-09-22T01:50:58.903568Z","end":"2026-09-22T01:50:59.134864Z","steps":["trace[750842005] 'count revisions from in-memory index tree' (duration: 230.971394ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:50:59.13496Z","caller":"traceutil/trace.go:171","msg":"trace[995470842] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"231.179176ms","start":"2026-09-22T01:50:58.903659Z","end":"2026-09-22T01:50:59.134839Z","steps":["trace[995470842] 'range keys from in-memory index tree' (duration: 230.925253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:59.136764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.90262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:50:59.136872Z","caller":"traceutil/trace.go:171","msg":"trace[1273943299] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"181.200356ms","start":"2026-09-22T01:50:58.955637Z","end":"2026-09-22T01:50:59.136837Z","steps":["trace[1273943299] 'count revisions from in-memory index tree' (duration: 179.374703ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:59.137363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.701781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-22T01:50:59.137412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.697872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:50:59.137533Z","caller":"traceutil/trace.go:171","msg":"trace[151743547] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"163.846653ms","start":"2026-09-22T01:50:58.973657Z","end":"2026-09-22T01:50:59.137504Z","steps":["trace[151743547] 'count revisions from in-memory index tree' (duration: 163.47374ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:50:59.137484Z","caller":"traceutil/trace.go:171","msg":"trace[138805760] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"163.828003ms","start":"2026-09-22T01:50:58.973603Z","end":"2026-09-22T01:50:59.137431Z","steps":["trace[138805760] 'range keys from in-memory index tree' (duration: 163.469899ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:59.138999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.739742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-22T01:50:59.139089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.806332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:50:59.139154Z","caller":"traceutil/trace.go:171","msg":"trace[1348403450] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"150.942623ms","start":"2026-09-22T01:50:58.988194Z","end":"2026-09-22T01:50:59.139137Z","steps":["trace[1348403450] 'count revisions from in-memory index tree' (duration: 150.573649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:59.139211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.870749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:50:59.139304Z","caller":"traceutil/trace.go:171","msg":"trace[1162427370] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"135.99996ms","start":"2026-09-22T01:50:59.003287Z","end":"2026-09-22T01:50:59.139287Z","steps":["trace[1162427370] 'range keys from in-memory index tree' (duration: 135.756168ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:59.139355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.832389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:50:59.139396Z","caller":"traceutil/trace.go:171","msg":"trace[1488977573] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"135.89889ms","start":"2026-09-22T01:50:59.003483Z","end":"2026-09-22T01:50:59.139382Z","steps":["trace[1488977573] 'count revisions from in-memory index tree' (duration: 135.747667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:59.139483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.591548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:50:59.139535Z","caller":"traceutil/trace.go:171","msg":"trace[921768391] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"119.6668ms","start":"2026-09-22T01:50:59.019852Z","end":"2026-09-22T01:50:59.139519Z","steps":["trace[921768391] 'count revisions from in-memory index tree' (duration: 119.518248ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:50:59.139097Z","caller":"traceutil/trace.go:171","msg":"trace[365730553] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"150.923045ms","start":"2026-09-22T01:50:58.988158Z","end":"2026-09-22T01:50:59.139081Z","steps":["trace[365730553] 'range keys from in-memory index tree' (duration: 150.507549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:01.848616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.256496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-22T01:51:01.848737Z","caller":"traceutil/trace.go:171","msg":"trace[2056420152] transaction","detail":"{read_only:false; response_revision:91; number_of_response:1; }","duration":"226.778138ms","start":"2026-09-22T01:51:01.62194Z","end":"2026-09-22T01:51:01.848718Z","steps":["trace[2056420152] 'process raft request' (duration: 123.771491ms)","trace[2056420152] 'compare' (duration: 102.119164ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:02.756254Z","caller":"traceutil/trace.go:171","msg":"trace[505506104] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"123.011685ms","start":"2026-09-22T01:51:02.633217Z","end":"2026-09-22T01:51:02.756229Z","steps":["trace[505506104] 'process raft request' (duration: 100.629522ms)","trace[505506104] 'compare' (duration: 22.266761ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:51:05.403643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.82828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:51:05.403751Z","caller":"traceutil/trace.go:171","msg":"trace[1801425562] linearizableReadLoop","detail":"{readStateIndex:260; appliedIndex:258; }","duration":"277.901446ms","start":"2026-09-22T01:51:05.125834Z","end":"2026-09-22T01:51:05.403736Z","steps":["trace[1801425562] 'read index received' (duration: 22.661401ms)","trace[1801425562] 'applied index is now lower than readState.Index' (duration: 255.238985ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:05.40376Z","caller":"traceutil/trace.go:171","msg":"trace[1930875478] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"286.346354ms","start":"2026-09-22T01:51:05.11739Z","end":"2026-09-22T01:51:05.403737Z","steps":["trace[1930875478] 'process raft request' (duration: 90.365291ms)","trace[1930875478] 'compare' (duration: 195.721748ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:51:05.403857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.015598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-09-22T01:51:05.40389Z","caller":"traceutil/trace.go:171","msg":"trace[1321784910] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:253; }","duration":"278.082158ms","start":"2026-09-22T01:51:05.125798Z","end":"2026-09-22T01:51:05.40388Z","steps":["trace[1321784910] 'agreement among raft nodes before linearized reading' (duration: 277.988467ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:05.405582Z","caller":"traceutil/trace.go:171","msg":"trace[155463251] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"198.902191ms","start":"2026-09-22T01:51:05.206656Z","end":"2026-09-22T01:51:05.405558Z","steps":["trace[155463251] 'process raft request' (duration: 198.85576ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:05.405683Z","caller":"traceutil/trace.go:171","msg":"trace[386207793] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"199.236156ms","start":"2026-09-22T01:51:05.206408Z","end":"2026-09-22T01:51:05.405645Z","steps":["trace[386207793] 'process raft request' (duration: 198.978982ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:05.564443Z","caller":"traceutil/trace.go:171","msg":"trace[1133091182] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"333.377334ms","start":"2026-09-22T01:51:05.231044Z","end":"2026-09-22T01:51:05.564421Z","steps":["trace[1133091182] 'process raft request' (duration: 333.163111ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:05.564574Z","caller":"traceutil/trace.go:171","msg":"trace[880766493] linearizableReadLoop","detail":"{readStateIndex:268; appliedIndex:262; }","duration":"158.209223ms","start":"2026-09-22T01:51:05.406335Z","end":"2026-09-22T01:51:05.564544Z","steps":["trace[880766493] 'read index received' (duration: 157.826377ms)","trace[880766493] 'applied index is now lower than readState.Index' (duration: 382.056µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:05.564537Z","caller":"traceutil/trace.go:171","msg":"trace[1413283835] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"333.121969ms","start":"2026-09-22T01:51:05.231375Z","end":"2026-09-22T01:51:05.564497Z","steps":["trace[1413283835] 'process raft request' (duration: 332.998777ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:05.564602Z","caller":"traceutil/trace.go:171","msg":"trace[1016722544] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"332.274776ms","start":"2026-09-22T01:51:05.232274Z","end":"2026-09-22T01:51:05.564549Z","steps":["trace[1016722544] 'process raft request' (duration: 332.119334ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:05.564685Z","caller":"traceutil/trace.go:171","msg":"trace[992110077] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"270.227359ms","start":"2026-09-22T01:51:05.294445Z","end":"2026-09-22T01:51:05.564673Z","steps":["trace[992110077] 'process raft request' (duration: 270.064166ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:05.564694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:05.231365Z","time spent":"333.291513ms","remote":"127.0.0.1:39274","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:05.564735Z","caller":"traceutil/trace.go:171","msg":"trace[1782157615] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"331.074037ms","start":"2026-09-22T01:51:05.233608Z","end":"2026-09-22T01:51:05.564682Z","steps":["trace[1782157615] 'process raft request' (duration: 330.868813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:05.564755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:05.23226Z","time spent":"332.409439ms","remote":"127.0.0.1:39274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:51:05.564793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.445108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T01:51:05.56482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:05.233597Z","time spent":"331.18344ms","remote":"127.0.0.1:39274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:05.564836Z","caller":"traceutil/trace.go:171","msg":"trace[457951789] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:257; }","duration":"158.517299ms","start":"2026-09-22T01:51:05.406306Z","end":"2026-09-22T01:51:05.564824Z","steps":["trace[457951789] 'agreement among raft nodes before linearized reading' (duration: 158.415038ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:05.564504Z","caller":"traceutil/trace.go:171","msg":"trace[1856364852] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"333.257633ms","start":"2026-09-22T01:51:05.231218Z","end":"2026-09-22T01:51:05.564476Z","steps":["trace[1856364852] 'process raft request' (duration: 333.127851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:05.565048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:05.231019Z","time spent":"333.475556ms","remote":"127.0.0.1:39274","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:51:05.565125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:05.231207Z","time spent":"333.713011ms","remote":"127.0.0.1:39274","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:05.625096Z","caller":"traceutil/trace.go:171","msg":"trace[1027118704] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"216.140564ms","start":"2026-09-22T01:51:05.408912Z","end":"2026-09-22T01:51:05.625052Z","steps":["trace[1027118704] 'process raft request' (duration: 216.039052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:05.625284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.858676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-09-22T01:51:05.625387Z","caller":"traceutil/trace.go:171","msg":"trace[1233819535] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:258; }","duration":"218.991529ms","start":"2026-09-22T01:51:05.406369Z","end":"2026-09-22T01:51:05.62536Z","steps":["trace[1233819535] 'agreement among raft nodes before linearized reading' (duration: 218.702865ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:08.266246Z","caller":"traceutil/trace.go:171","msg":"trace[910073206] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"109.076482ms","start":"2026-09-22T01:51:08.157142Z","end":"2026-09-22T01:51:08.266219Z","steps":["trace[910073206] 'process raft request' (duration: 92.442249ms)","trace[910073206] 'compare' (duration: 16.399949ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:51:22.400159Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.310454828s","expected-duration":"1s"} {"level":"info","ts":"2026-09-22T01:51:22.400582Z","caller":"traceutil/trace.go:171","msg":"trace[105150333] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:409; }","duration":"333.01381ms","start":"2026-09-22T01:51:22.067544Z","end":"2026-09-22T01:51:22.400557Z","steps":["trace[105150333] 'read index received' (duration: 332.848946ms)","trace[105150333] 'applied index is now lower than readState.Index' (duration: 163.784µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:51:22.400712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.150963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:51:22.400748Z","caller":"traceutil/trace.go:171","msg":"trace[1617461956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:392; }","duration":"333.244286ms","start":"2026-09-22T01:51:22.067493Z","end":"2026-09-22T01:51:22.400737Z","steps":["trace[1617461956] 'agreement among raft nodes before linearized reading' (duration: 333.166244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:22.400779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:22.067471Z","time spent":"333.300648ms","remote":"127.0.0.1:39084","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-09-22T01:51:22.400937Z","caller":"traceutil/trace.go:171","msg":"trace[1094614835] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"1.311299259s","start":"2026-09-22T01:51:21.089605Z","end":"2026-09-22T01:51:22.400904Z","steps":["trace[1094614835] 'process raft request' (duration: 1.310815427s)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:22.401415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:21.089586Z","time spent":"1.311431203s","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:51:22.611099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.353937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:51:22.611293Z","caller":"traceutil/trace.go:171","msg":"trace[1849377973] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"170.800532ms","start":"2026-09-22T01:51:22.44047Z","end":"2026-09-22T01:51:22.61127Z","steps":["trace[1849377973] 'process raft request' (duration: 19.148477ms)","trace[1849377973] 'compare' (duration: 151.211973ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:22.611263Z","caller":"traceutil/trace.go:171","msg":"trace[960688023] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:410; }","duration":"167.415374ms","start":"2026-09-22T01:51:22.443823Z","end":"2026-09-22T01:51:22.611238Z","steps":["trace[960688023] 'read index received' (duration: 15.81608ms)","trace[960688023] 'applied index is now lower than readState.Index' (duration: 151.594514ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:51:22.611454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.62724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-22T01:51:22.611514Z","caller":"traceutil/trace.go:171","msg":"trace[1895127014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:393; }","duration":"167.738693ms","start":"2026-09-22T01:51:22.443758Z","end":"2026-09-22T01:51:22.611497Z","steps":["trace[1895127014] 'agreement among raft nodes before linearized reading' (duration: 167.591489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:27.478363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.650824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-22T01:51:27.478445Z","caller":"traceutil/trace.go:171","msg":"trace[1878323715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:404; }","duration":"483.779568ms","start":"2026-09-22T01:51:26.994645Z","end":"2026-09-22T01:51:27.478424Z","steps":["trace[1878323715] 'range keys from in-memory index tree' (duration: 483.546552ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:27.478483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:26.994629Z","time spent":"483.846659ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-22T01:51:29.228375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.92989ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-22T01:51:29.2285Z","caller":"traceutil/trace.go:171","msg":"trace[87411251] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"159.745234ms","start":"2026-09-22T01:51:29.068733Z","end":"2026-09-22T01:51:29.228478Z","steps":["trace[87411251] 'read index received' (duration: 76.332µs)","trace[87411251] 'applied index is now lower than readState.Index' (duration: 159.666992ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:51:29.228585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.839836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:51:29.228629Z","caller":"traceutil/trace.go:171","msg":"trace[527064409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"159.932969ms","start":"2026-09-22T01:51:29.068675Z","end":"2026-09-22T01:51:29.228608Z","steps":["trace[527064409] 'agreement among raft nodes before linearized reading' (duration: 159.856827ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:29.633083Z","caller":"traceutil/trace.go:171","msg":"trace[84478283] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"128.374528ms","start":"2026-09-22T01:51:29.504635Z","end":"2026-09-22T01:51:29.633009Z","steps":["trace[84478283] 'process raft request' (duration: 127.732059ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:30.631186Z","caller":"traceutil/trace.go:171","msg":"trace[1048511669] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"122.435158ms","start":"2026-09-22T01:51:30.50872Z","end":"2026-09-22T01:51:30.631155Z","steps":["trace[1048511669] 'process raft request' (duration: 122.218131ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:31.826193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.755353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-22T01:51:31.826336Z","caller":"traceutil/trace.go:171","msg":"trace[1970034179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"184.943309ms","start":"2026-09-22T01:51:31.641366Z","end":"2026-09-22T01:51:31.826309Z","steps":["trace[1970034179] 'range keys from in-memory index tree' (duration: 184.67007ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:31.978184Z","caller":"traceutil/trace.go:171","msg":"trace[739176551] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"146.776531ms","start":"2026-09-22T01:51:31.831386Z","end":"2026-09-22T01:51:31.978163Z","steps":["trace[739176551] 'process raft request' (duration: 146.651568ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:33.059157Z","caller":"traceutil/trace.go:171","msg":"trace[2051076871] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"216.455721ms","start":"2026-09-22T01:51:32.842678Z","end":"2026-09-22T01:51:33.059134Z","steps":["trace[2051076871] 'read index received' (duration: 216.244524ms)","trace[2051076871] 'applied index is now lower than readState.Index' (duration: 210.597µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:33.059415Z","caller":"traceutil/trace.go:171","msg":"trace[30675044] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"418.587422ms","start":"2026-09-22T01:51:32.640822Z","end":"2026-09-22T01:51:33.059409Z","steps":["trace[30675044] 'process raft request' (duration: 418.20325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:33.0595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:32.640798Z","time spent":"418.640743ms","remote":"127.0.0.1:39360","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-09-22T01:51:33.059631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.966867ms","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-09-22T01:51:33.059657Z","caller":"traceutil/trace.go:171","msg":"trace[333574998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"217.046989ms","start":"2026-09-22T01:51:32.842603Z","end":"2026-09-22T01:51:33.05965Z","steps":["trace[333574998] 'agreement among raft nodes before linearized reading' (duration: 217.004337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:33.059735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.657371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-22T01:51:33.059754Z","caller":"traceutil/trace.go:171","msg":"trace[1224534901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"183.702883ms","start":"2026-09-22T01:51:32.876047Z","end":"2026-09-22T01:51:33.05975Z","steps":["trace[1224534901] 'agreement among raft nodes before linearized reading' (duration: 183.671932ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:33.245907Z","caller":"traceutil/trace.go:171","msg":"trace[1888355785] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"129.738738ms","start":"2026-09-22T01:51:33.116151Z","end":"2026-09-22T01:51:33.24589Z","steps":["trace[1888355785] 'read index received' (duration: 39.246812ms)","trace[1888355785] 'applied index is now lower than readState.Index' (duration: 90.490456ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:51:33.246003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.864432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:51:33.246039Z","caller":"traceutil/trace.go:171","msg":"trace[1534518836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"129.898333ms","start":"2026-09-22T01:51:33.116122Z","end":"2026-09-22T01:51:33.24602Z","steps":["trace[1534518836] 'agreement among raft nodes before linearized reading' (duration: 129.834211ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:33.246129Z","caller":"traceutil/trace.go:171","msg":"trace[2044028198] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"178.422772ms","start":"2026-09-22T01:51:33.067578Z","end":"2026-09-22T01:51:33.246001Z","steps":["trace[2044028198] 'process raft request' (duration: 87.919385ms)","trace[2044028198] 'compare' (duration: 90.3017ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:36.930651Z","caller":"traceutil/trace.go:171","msg":"trace[1829024014] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"154.195841ms","start":"2026-09-22T01:51:36.776415Z","end":"2026-09-22T01:51:36.930611Z","steps":["trace[1829024014] 'process raft request' (duration: 153.923311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:37.69955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.147237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:51:37.699691Z","caller":"traceutil/trace.go:171","msg":"trace[1294058511] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:447; }","duration":"632.45532ms","start":"2026-09-22T01:51:37.067213Z","end":"2026-09-22T01:51:37.699668Z","steps":["trace[1294058511] 'read index received' (duration: 190.026104ms)","trace[1294058511] 'applied index is now lower than readState.Index' (duration: 442.427316ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:37.699797Z","caller":"traceutil/trace.go:171","msg":"trace[1817807955] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"758.794861ms","start":"2026-09-22T01:51:36.940991Z","end":"2026-09-22T01:51:37.699786Z","steps":["trace[1817807955] 'process raft request' (duration: 316.278212ms)","trace[1817807955] 'compare' (duration: 441.677021ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:51:37.699887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:36.940969Z","time spent":"758.858732ms","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:51:37.700279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.044369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-22T01:51:37.700336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.038305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-22T01:51:37.700369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.204984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-09-22T01:51:37.700337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.685965ms","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-09-22T01:51:37.700421Z","caller":"traceutil/trace.go:171","msg":"trace[1178537812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:427; }","duration":"484.299137ms","start":"2026-09-22T01:51:37.216108Z","end":"2026-09-22T01:51:37.700407Z","steps":["trace[1178537812] 'agreement among raft nodes before linearized reading' (duration: 484.165442ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:37.70042Z","caller":"traceutil/trace.go:171","msg":"trace[2099637193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:427; }","duration":"435.21427ms","start":"2026-09-22T01:51:37.265187Z","end":"2026-09-22T01:51:37.700401Z","steps":["trace[2099637193] 'agreement among raft nodes before linearized reading' (duration: 434.970783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:37.700468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:37.21608Z","time spent":"484.378509ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T01:51:37.700487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:37.265161Z","time spent":"435.314235ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-22T01:51:37.700478Z","caller":"traceutil/trace.go:171","msg":"trace[1317784483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:427; }","duration":"623.885421ms","start":"2026-09-22T01:51:37.076564Z","end":"2026-09-22T01:51:37.70045Z","steps":["trace[1317784483] 'agreement among raft nodes before linearized reading' (duration: 623.514729ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:37.700399Z","caller":"traceutil/trace.go:171","msg":"trace[1037553049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"633.203014ms","start":"2026-09-22T01:51:37.067166Z","end":"2026-09-22T01:51:37.700369Z","steps":["trace[1037553049] 'agreement among raft nodes before linearized reading' (duration: 633.016498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:37.700566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:37.076559Z","time spent":"623.987374ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-22T01:51:37.700805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:37.067145Z","time spent":"633.646769ms","remote":"127.0.0.1:39084","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-09-22T01:51:37.99163Z","caller":"traceutil/trace.go:171","msg":"trace[1647379309] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"152.35338ms","start":"2026-09-22T01:51:37.839242Z","end":"2026-09-22T01:51:37.991595Z","steps":["trace[1647379309] 'process raft request' (duration: 137.24692ms)","trace[1647379309] 'compare' (duration: 14.845041ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:37.992086Z","caller":"traceutil/trace.go:171","msg":"trace[2088815587] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"150.795769ms","start":"2026-09-22T01:51:37.841262Z","end":"2026-09-22T01:51:37.992058Z","steps":["trace[2088815587] 'process raft request' (duration: 150.54322ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:37.992145Z","caller":"traceutil/trace.go:171","msg":"trace[630852784] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"151.235524ms","start":"2026-09-22T01:51:37.840892Z","end":"2026-09-22T01:51:37.992127Z","steps":["trace[630852784] 'process raft request' (duration: 150.84522ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:37.992423Z","caller":"traceutil/trace.go:171","msg":"trace[72468761] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:456; }","duration":"149.095864ms","start":"2026-09-22T01:51:37.842805Z","end":"2026-09-22T01:51:37.991901Z","steps":["trace[72468761] 'read index received' (duration: 355.091µs)","trace[72468761] 'applied index is now lower than readState.Index' (duration: 148.739633ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:37.992451Z","caller":"traceutil/trace.go:171","msg":"trace[216560444] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"153.010642ms","start":"2026-09-22T01:51:37.839372Z","end":"2026-09-22T01:51:37.992383Z","steps":["trace[216560444] 'process raft request' (duration: 152.178464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:37.992671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.788487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-09-22T01:51:37.992749Z","caller":"traceutil/trace.go:171","msg":"trace[521063622] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:439; }","duration":"149.968793ms","start":"2026-09-22T01:51:37.84276Z","end":"2026-09-22T01:51:37.992729Z","steps":["trace[521063622] 'agreement among raft nodes before linearized reading' (duration: 149.702714ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:37.995088Z","caller":"traceutil/trace.go:171","msg":"trace[830779974] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"149.87522ms","start":"2026-09-22T01:51:37.845192Z","end":"2026-09-22T01:51:37.995068Z","steps":["trace[830779974] 'process raft request' (duration: 149.733656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:38.262721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.681517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:51:38.263135Z","caller":"traceutil/trace.go:171","msg":"trace[1130947018] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:462; }","duration":"194.543065ms","start":"2026-09-22T01:51:38.068569Z","end":"2026-09-22T01:51:38.263113Z","steps":["trace[1130947018] 'read index received' (duration: 73.275269ms)","trace[1130947018] 'applied index is now lower than readState.Index' (duration: 121.266336ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:51:38.263242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.652939ms","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-09-22T01:51:38.263273Z","caller":"traceutil/trace.go:171","msg":"trace[640626677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:443; }","duration":"194.731082ms","start":"2026-09-22T01:51:38.068528Z","end":"2026-09-22T01:51:38.26326Z","steps":["trace[640626677] 'agreement among raft nodes before linearized reading' (duration: 194.6508ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:38.26329Z","caller":"traceutil/trace.go:171","msg":"trace[1274535959] transaction","detail":"{read_only:false; number_of_response:1; response_revision:442; }","duration":"258.751126ms","start":"2026-09-22T01:51:38.004506Z","end":"2026-09-22T01:51:38.263258Z","steps":["trace[1274535959] 'process raft request' (duration: 258.489218ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:38.263395Z","caller":"traceutil/trace.go:171","msg":"trace[342436742] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"263.015094ms","start":"2026-09-22T01:51:38.00034Z","end":"2026-09-22T01:51:38.263355Z","steps":["trace[342436742] 'process raft request' (duration: 262.541019ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:38.263408Z","caller":"traceutil/trace.go:171","msg":"trace[2031932961] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"263.564093ms","start":"2026-09-22T01:51:37.999808Z","end":"2026-09-22T01:51:38.263372Z","steps":["trace[2031932961] 'process raft request' (duration: 142.125091ms)","trace[2031932961] 'compare' (duration: 120.45121ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:38.263463Z","caller":"traceutil/trace.go:171","msg":"trace[2008886226] transaction","detail":"{read_only:false; number_of_response:1; response_revision:443; }","duration":"230.699124ms","start":"2026-09-22T01:51:38.032738Z","end":"2026-09-22T01:51:38.263438Z","steps":["trace[2008886226] 'process raft request' (duration: 230.310781ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:38.416288Z","caller":"traceutil/trace.go:171","msg":"trace[1545573746] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"143.100706ms","start":"2026-09-22T01:51:38.273147Z","end":"2026-09-22T01:51:38.416248Z","steps":["trace[1545573746] 'process raft request' (duration: 113.852194ms)","trace[1545573746] 'compare' (duration: 28.872609ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:38.416487Z","caller":"traceutil/trace.go:171","msg":"trace[1896808350] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"143.270751ms","start":"2026-09-22T01:51:38.273172Z","end":"2026-09-22T01:51:38.416442Z","steps":["trace[1896808350] 'process raft request' (duration: 142.977892ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:38.417301Z","caller":"traceutil/trace.go:171","msg":"trace[656477572] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"144.092307ms","start":"2026-09-22T01:51:38.273191Z","end":"2026-09-22T01:51:38.417284Z","steps":["trace[656477572] 'process raft request' (duration: 143.658993ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:38.417511Z","caller":"traceutil/trace.go:171","msg":"trace[1593071180] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"140.228051ms","start":"2026-09-22T01:51:38.277267Z","end":"2026-09-22T01:51:38.417495Z","steps":["trace[1593071180] 'process raft request' (duration: 139.867968ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:50.468312Z","caller":"traceutil/trace.go:171","msg":"trace[1208857384] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"122.696234ms","start":"2026-09-22T01:51:50.345588Z","end":"2026-09-22T01:51:50.468285Z","steps":["trace[1208857384] 'process raft request' (duration: 119.274807ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:50.586333Z","caller":"traceutil/trace.go:171","msg":"trace[1773269532] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"110.490202ms","start":"2026-09-22T01:51:50.475814Z","end":"2026-09-22T01:51:50.586305Z","steps":["trace[1773269532] 'process raft request' (duration: 94.165257ms)","trace[1773269532] 'compare' (duration: 16.175839ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:50.586997Z","caller":"traceutil/trace.go:171","msg":"trace[1595801905] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"108.035742ms","start":"2026-09-22T01:51:50.478945Z","end":"2026-09-22T01:51:50.586981Z","steps":["trace[1595801905] 'process raft request' (duration: 107.857335ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:50.586985Z","caller":"traceutil/trace.go:171","msg":"trace[469234709] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"107.749721ms","start":"2026-09-22T01:51:50.479209Z","end":"2026-09-22T01:51:50.586959Z","steps":["trace[469234709] 'process raft request' (duration: 107.690949ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:52.883396Z","caller":"traceutil/trace.go:171","msg":"trace[372699815] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"487.555092ms","start":"2026-09-22T01:51:52.395815Z","end":"2026-09-22T01:51:52.883371Z","steps":["trace[372699815] 'process raft request' (duration: 487.422647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:52.883561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:52.395788Z","time spent":"487.684587ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:53.046133Z","caller":"traceutil/trace.go:171","msg":"trace[2060444961] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"649.288176ms","start":"2026-09-22T01:51:52.396807Z","end":"2026-09-22T01:51:53.046095Z","steps":["trace[2060444961] 'process raft request' (duration: 580.413239ms)","trace[2060444961] 'compare' (duration: 68.369788ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:53.046424Z","caller":"traceutil/trace.go:171","msg":"trace[1990676606] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"633.842386ms","start":"2026-09-22T01:51:52.412557Z","end":"2026-09-22T01:51:53.0464Z","steps":["trace[1990676606] 'process raft request' (duration: 633.385188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.04645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:52.396781Z","time spent":"649.442602ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:53.046484Z","caller":"traceutil/trace.go:171","msg":"trace[1544607031] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"629.964599ms","start":"2026-09-22T01:51:52.416496Z","end":"2026-09-22T01:51:53.046461Z","steps":["trace[1544607031] 'process raft request' (duration: 629.49095ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.046603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:52.416477Z","time spent":"630.074903ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:53.046768Z","caller":"traceutil/trace.go:171","msg":"trace[101576945] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"624.66018ms","start":"2026-09-22T01:51:52.422088Z","end":"2026-09-22T01:51:53.046748Z","steps":["trace[101576945] 'process raft request' (duration: 624.255424ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:53.046746Z","caller":"traceutil/trace.go:171","msg":"trace[287094687] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"630.088854ms","start":"2026-09-22T01:51:52.416609Z","end":"2026-09-22T01:51:53.046698Z","steps":["trace[287094687] 'process raft request' (duration: 629.548703ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:53.046791Z","caller":"traceutil/trace.go:171","msg":"trace[1618340882] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"641.374639ms","start":"2026-09-22T01:51:52.405381Z","end":"2026-09-22T01:51:53.046756Z","steps":["trace[1618340882] 'process raft request' (duration: 640.473034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.04685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:52.422078Z","time spent":"624.737972ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:51:53.046867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:52.405361Z","time spent":"641.473302ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:51:53.046922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:52.416602Z","time spent":"630.234349ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:53.047068Z","caller":"traceutil/trace.go:171","msg":"trace[1418553697] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"622.80774ms","start":"2026-09-22T01:51:52.424227Z","end":"2026-09-22T01:51:53.047035Z","steps":["trace[1418553697] 'process raft request' (duration: 622.25799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.047206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:52.412531Z","time spent":"633.96417ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:51:53.047231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:52.424189Z","time spent":"622.928464ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:53.047275Z","caller":"traceutil/trace.go:171","msg":"trace[899557695] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"619.091539ms","start":"2026-09-22T01:51:52.42811Z","end":"2026-09-22T01:51:53.047201Z","steps":["trace[899557695] 'process raft request' (duration: 618.595321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.047588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:52.428093Z","time spent":"619.296178ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:53.047635Z","caller":"traceutil/trace.go:171","msg":"trace[301503717] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"610.367761ms","start":"2026-09-22T01:51:52.437254Z","end":"2026-09-22T01:51:53.047621Z","steps":["trace[301503717] 'process raft request' (duration: 609.820791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.047733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:52.437231Z","time spent":"610.433644ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:53.047775Z","caller":"traceutil/trace.go:171","msg":"trace[1742314334] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"586.826225ms","start":"2026-09-22T01:51:52.460931Z","end":"2026-09-22T01:51:53.047757Z","steps":["trace[1742314334] 'process raft request' (duration: 586.371457ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:53.047922Z","caller":"traceutil/trace.go:171","msg":"trace[1961352229] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"452.116252ms","start":"2026-09-22T01:51:52.595763Z","end":"2026-09-22T01:51:53.047879Z","steps":["trace[1961352229] 'process raft request' (duration: 451.940584ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.047973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:52.460912Z","time spent":"586.971231ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:51:53.048076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:52.595728Z","time spent":"452.242626ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:53.048049Z","caller":"traceutil/trace.go:171","msg":"trace[1199796736] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:551; }","duration":"456.645892ms","start":"2026-09-22T01:51:52.59125Z","end":"2026-09-22T01:51:53.047896Z","steps":["trace[1199796736] 'read index received' (duration: 292.128562ms)","trace[1199796736] 'applied index is now lower than readState.Index' (duration: 164.51554ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:51:53.048463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.198603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-22T01:51:53.048613Z","caller":"traceutil/trace.go:171","msg":"trace[1867628886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"457.353589ms","start":"2026-09-22T01:51:52.591194Z","end":"2026-09-22T01:51:53.048547Z","steps":["trace[1867628886] 'agreement among raft nodes before linearized reading' (duration: 456.969054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.048696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:52.591165Z","time spent":"457.483203ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-22T01:51:53.107049Z","caller":"traceutil/trace.go:171","msg":"trace[1591848121] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"215.415075ms","start":"2026-09-22T01:51:52.891603Z","end":"2026-09-22T01:51:53.107018Z","steps":["trace[1591848121] 'process raft request' (duration: 215.227378ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:53.107075Z","caller":"traceutil/trace.go:171","msg":"trace[1489022074] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"215.374104ms","start":"2026-09-22T01:51:52.891668Z","end":"2026-09-22T01:51:53.107042Z","steps":["trace[1489022074] 'process raft request' (duration: 215.27799ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:53.107138Z","caller":"traceutil/trace.go:171","msg":"trace[105416267] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"211.701055ms","start":"2026-09-22T01:51:52.895431Z","end":"2026-09-22T01:51:53.107132Z","steps":["trace[105416267] 'process raft request' (duration: 211.540589ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:53.10709Z","caller":"traceutil/trace.go:171","msg":"trace[1541048415] transaction","detail":"{read_only:false; number_of_response:1; response_revision:539; }","duration":"211.614371ms","start":"2026-09-22T01:51:52.895453Z","end":"2026-09-22T01:51:53.107068Z","steps":["trace[1541048415] 'process raft request' (duration: 211.57263ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.618163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.268642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:51:53.618508Z","caller":"traceutil/trace.go:171","msg":"trace[505104463] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"418.184656ms","start":"2026-09-22T01:51:53.200297Z","end":"2026-09-22T01:51:53.618481Z","steps":["trace[505104463] 'process raft request' (duration: 186.153097ms)","trace[505104463] 'compare' (duration: 230.974429ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:51:53.618736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:53.200275Z","time spent":"418.342322ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:51:53.627081Z","caller":"traceutil/trace.go:171","msg":"trace[1296002242] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"426.664738ms","start":"2026-09-22T01:51:53.200392Z","end":"2026-09-22T01:51:53.627057Z","steps":["trace[1296002242] 'process raft request' (duration: 419.742955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.627219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:53.200385Z","time spent":"426.778582ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:51:53.629138Z","caller":"traceutil/trace.go:171","msg":"trace[524437252] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"422.026303ms","start":"2026-09-22T01:51:53.207094Z","end":"2026-09-22T01:51:53.629121Z","steps":["trace[524437252] 'process raft request' (duration: 419.737565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.629225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:53.207087Z","time spent":"422.100916ms","remote":"127.0.0.1:39592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:53.697854Z","caller":"traceutil/trace.go:171","msg":"trace[1295738957] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"471.809722ms","start":"2026-09-22T01:51:53.226001Z","end":"2026-09-22T01:51:53.697811Z","steps":["trace[1295738957] 'process raft request' (duration: 471.522062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.698019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:53.225993Z","time spent":"471.932807ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:51:53.698048Z","caller":"traceutil/trace.go:171","msg":"trace[1458866595] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"471.964298ms","start":"2026-09-22T01:51:53.226051Z","end":"2026-09-22T01:51:53.698015Z","steps":["trace[1458866595] 'process raft request' (duration: 471.707098ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:53.698108Z","caller":"traceutil/trace.go:171","msg":"trace[703442061] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:585; }","duration":"264.769092ms","start":"2026-09-22T01:51:53.433317Z","end":"2026-09-22T01:51:53.698086Z","steps":["trace[703442061] 'read index received' (duration: 264.154679ms)","trace[703442061] 'applied index is now lower than readState.Index' (duration: 613.613µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:51:53.698211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:53.226047Z","time spent":"472.086392ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:51:53.698232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.893077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.112\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-22T01:51:53.698274Z","caller":"traceutil/trace.go:171","msg":"trace[503344444] range","detail":"{range_begin:/registry/masterleases/162.253.55.112; range_end:; response_count:1; response_revision:559; }","duration":"264.99264ms","start":"2026-09-22T01:51:53.433271Z","end":"2026-09-22T01:51:53.698264Z","steps":["trace[503344444] 'agreement among raft nodes before linearized reading' (duration: 264.898417ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:53.698331Z","caller":"traceutil/trace.go:171","msg":"trace[1591624729] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"452.914964ms","start":"2026-09-22T01:51:53.245402Z","end":"2026-09-22T01:51:53.698317Z","steps":["trace[1591624729] 'process raft request' (duration: 452.443847ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.698389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:53.245376Z","time spent":"452.980677ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:51:53.698445Z","caller":"traceutil/trace.go:171","msg":"trace[1071294927] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"449.257077ms","start":"2026-09-22T01:51:53.249178Z","end":"2026-09-22T01:51:53.698435Z","steps":["trace[1071294927] 'process raft request' (duration: 448.824701ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:53.698495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:53.24915Z","time spent":"449.321159ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:51:53.861287Z","caller":"traceutil/trace.go:171","msg":"trace[1952034285] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"136.957599ms","start":"2026-09-22T01:51:53.724312Z","end":"2026-09-22T01:51:53.861269Z","steps":["trace[1952034285] 'process raft request' (duration: 136.769391ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:54.123429Z","caller":"traceutil/trace.go:171","msg":"trace[1456922527] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:590; }","duration":"280.461554ms","start":"2026-09-22T01:51:53.84292Z","end":"2026-09-22T01:51:54.123382Z","steps":["trace[1456922527] 'read index received' (duration: 18.088397ms)","trace[1456922527] 'applied index is now lower than readState.Index' (duration: 262.372327ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:54.123662Z","caller":"traceutil/trace.go:171","msg":"trace[2005941009] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"394.93941ms","start":"2026-09-22T01:51:53.728696Z","end":"2026-09-22T01:51:54.123636Z","steps":["trace[2005941009] 'process raft request' (duration: 394.549795ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:51:54.123694Z","caller":"traceutil/trace.go:171","msg":"trace[152898372] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"398.428403ms","start":"2026-09-22T01:51:53.725239Z","end":"2026-09-22T01:51:54.123667Z","steps":["trace[152898372] 'process raft request' (duration: 352.451784ms)","trace[152898372] 'compare' (duration: 45.328854ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:54.123757Z","caller":"traceutil/trace.go:171","msg":"trace[102680977] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"345.713031ms","start":"2026-09-22T01:51:53.778022Z","end":"2026-09-22T01:51:54.123735Z","steps":["trace[102680977] 'process raft request' (duration: 345.313346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:54.123845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:53.725225Z","time spent":"398.545007ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:51:54.123844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:53.728683Z","time spent":"395.081226ms","remote":"127.0.0.1:39130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:51:54.123924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:53.777993Z","time spent":"345.838916ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:51:54.124026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.051016ms","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-09-22T01:51:54.12411Z","caller":"traceutil/trace.go:171","msg":"trace[1430661569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"281.199992ms","start":"2026-09-22T01:51:53.842888Z","end":"2026-09-22T01:51:54.124088Z","steps":["trace[1430661569] 'agreement among raft nodes before linearized reading' (duration: 280.87697ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:54.132794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.412078ms","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-09-22T01:51:54.132869Z","caller":"traceutil/trace.go:171","msg":"trace[2027093322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"278.527163ms","start":"2026-09-22T01:51:53.854326Z","end":"2026-09-22T01:51:54.132853Z","steps":["trace[2027093322] 'agreement among raft nodes before linearized reading' (duration: 278.393067ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:54.133166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.400654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-22T01:51:54.133218Z","caller":"traceutil/trace.go:171","msg":"trace[1049259863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:563; }","duration":"285.496048ms","start":"2026-09-22T01:51:53.84771Z","end":"2026-09-22T01:51:54.133206Z","steps":["trace[1049259863] 'agreement among raft nodes before linearized reading' (duration: 285.345072ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:58.677365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8916741087979030360,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-22T01:51:58.704653Z","caller":"traceutil/trace.go:171","msg":"trace[1554137567] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"527.799251ms","start":"2026-09-22T01:51:58.176775Z","end":"2026-09-22T01:51:58.704574Z","steps":["trace[1554137567] 'read index received' (duration: 520.388938ms)","trace[1554137567] 'applied index is now lower than readState.Index' (duration: 7.409013ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:58.704734Z","caller":"traceutil/trace.go:171","msg":"trace[743183260] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"581.332461ms","start":"2026-09-22T01:51:58.123338Z","end":"2026-09-22T01:51:58.70467Z","steps":["trace[743183260] 'process raft request' (duration: 579.620362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:58.704901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.104454ms","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-09-22T01:51:58.704939Z","caller":"traceutil/trace.go:171","msg":"trace[1942710264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"528.205677ms","start":"2026-09-22T01:51:58.176722Z","end":"2026-09-22T01:51:58.704927Z","steps":["trace[1942710264] 'agreement among raft nodes before linearized reading' (duration: 528.054001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:58.704974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:58.176697Z","time spent":"528.268799ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-22T01:51:58.704999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:58.12328Z","time spent":"581.575241ms","remote":"127.0.0.1:39196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:51:58.704987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.471124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-22T01:51:58.705075Z","caller":"traceutil/trace.go:171","msg":"trace[1161673469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"485.61606ms","start":"2026-09-22T01:51:58.219435Z","end":"2026-09-22T01:51:58.705051Z","steps":["trace[1161673469] 'agreement among raft nodes before linearized reading' (duration: 485.390631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:58.704987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.317908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-22T01:51:58.705118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:58.219418Z","time spent":"485.691772ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-22T01:51:58.705152Z","caller":"traceutil/trace.go:171","msg":"trace[1255690616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"484.539727ms","start":"2026-09-22T01:51:58.220595Z","end":"2026-09-22T01:51:58.705135Z","steps":["trace[1255690616] 'agreement among raft nodes before linearized reading' (duration: 484.241195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:58.7052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:58.220585Z","time spent":"484.603769ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-22T01:51:59.845934Z","caller":"traceutil/trace.go:171","msg":"trace[1131533874] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"608.927637ms","start":"2026-09-22T01:51:59.236982Z","end":"2026-09-22T01:51:59.84591Z","steps":["trace[1131533874] 'read index received' (duration: 29.136016ms)","trace[1131533874] 'applied index is now lower than readState.Index' (duration: 579.790301ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:51:59.846044Z","caller":"traceutil/trace.go:171","msg":"trace[1501113084] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"639.539482ms","start":"2026-09-22T01:51:59.206492Z","end":"2026-09-22T01:51:59.846032Z","steps":["trace[1501113084] 'process raft request' (duration: 638.115475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:59.846165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:59.206461Z","time spent":"639.613625ms","remote":"127.0.0.1:39196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:51:59.846513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.786543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-22T01:51:59.846572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.548241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:51:59.846694Z","caller":"traceutil/trace.go:171","msg":"trace[1788649359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"609.713587ms","start":"2026-09-22T01:51:59.236959Z","end":"2026-09-22T01:51:59.846672Z","steps":["trace[1788649359] 'agreement among raft nodes before linearized reading' (duration: 609.486829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:59.846744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:59.236949Z","time spent":"609.787ms","remote":"127.0.0.1:39084","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-09-22T01:51:59.846762Z","caller":"traceutil/trace.go:171","msg":"trace[1637305616] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:626; }","duration":"156.96928ms","start":"2026-09-22T01:51:59.689664Z","end":"2026-09-22T01:51:59.846633Z","steps":["trace[1637305616] 'agreement among raft nodes before linearized reading' (duration: 156.804554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:59.847177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.388867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4wq7v\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-22T01:51:59.84742Z","caller":"traceutil/trace.go:171","msg":"trace[969243119] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4wq7v; range_end:; response_count:1; response_revision:626; }","duration":"572.655467ms","start":"2026-09-22T01:51:59.274747Z","end":"2026-09-22T01:51:59.847403Z","steps":["trace[969243119] 'agreement among raft nodes before linearized reading' (duration: 571.371236ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:51:59.847592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:59.274733Z","time spent":"572.846225ms","remote":"127.0.0.1:39274","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-4wq7v\" "} {"level":"warn","ts":"2026-09-22T01:52:00.566946Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8916741087979030379,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-22T01:52:00.740444Z","caller":"traceutil/trace.go:171","msg":"trace[1289188532] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"851.502174ms","start":"2026-09-22T01:51:59.888921Z","end":"2026-09-22T01:52:00.740423Z","steps":["trace[1289188532] 'process raft request' (duration: 851.315457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:00.740576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:59.888894Z","time spent":"851.608279ms","remote":"127.0.0.1:39274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:52:01.470066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.477984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:52:01.470171Z","caller":"traceutil/trace.go:171","msg":"trace[333422876] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:658; }","duration":"1.403286858s","start":"2026-09-22T01:52:00.066863Z","end":"2026-09-22T01:52:01.47015Z","steps":["trace[333422876] 'read index received' (duration: 673.527294ms)","trace[333422876] 'applied index is now lower than readState.Index' (duration: 729.757974ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:52:01.47038Z","caller":"traceutil/trace.go:171","msg":"trace[504547126] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.545318243s","start":"2026-09-22T01:51:59.925043Z","end":"2026-09-22T01:52:01.470361Z","steps":["trace[504547126] 'process raft request' (duration: 1.434475244s)","trace[504547126] 'compare' (duration: 110.342199ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:52:01.470465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:51:59.92503Z","time spent":"1.545398167s","remote":"127.0.0.1:39410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:52:01.470556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.454252ms","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-09-22T01:52:01.470619Z","caller":"traceutil/trace.go:171","msg":"trace[1210337347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"754.569796ms","start":"2026-09-22T01:52:00.716032Z","end":"2026-09-22T01:52:01.470602Z","steps":["trace[1210337347] 'agreement among raft nodes before linearized reading' (duration: 754.350578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:01.470718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:00.715969Z","time spent":"754.688052ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-22T01:52:01.470905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404041328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:52:01.470962Z","caller":"traceutil/trace.go:171","msg":"trace[1474697749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.404142102s","start":"2026-09-22T01:52:00.066803Z","end":"2026-09-22T01:52:01.470945Z","steps":["trace[1474697749] 'agreement among raft nodes before linearized reading' (duration: 1.404060159s)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:01.470945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.626188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-22T01:52:01.471055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.127119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-22T01:52:01.47107Z","caller":"traceutil/trace.go:171","msg":"trace[155574514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"745.806636ms","start":"2026-09-22T01:52:00.725236Z","end":"2026-09-22T01:52:01.471043Z","steps":["trace[155574514] 'agreement among raft nodes before linearized reading' (duration: 745.426041ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:01.471132Z","caller":"traceutil/trace.go:171","msg":"trace[1901170345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"752.278306ms","start":"2026-09-22T01:52:00.718835Z","end":"2026-09-22T01:52:01.471113Z","steps":["trace[1901170345] 'agreement among raft nodes before linearized reading' (duration: 752.040685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:01.471174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:00.725226Z","time spent":"745.935142ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-22T01:52:01.471179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:00.718814Z","time spent":"752.357429ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T01:52:01.471007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:00.066783Z","time spent":"1.404213095s","remote":"127.0.0.1:39100","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-09-22T01:52:01.471613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.145522ms","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-09-22T01:52:01.4717Z","caller":"traceutil/trace.go:171","msg":"trace[1373284557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"201.249306ms","start":"2026-09-22T01:52:01.270411Z","end":"2026-09-22T01:52:01.471661Z","steps":["trace[1373284557] 'agreement among raft nodes before linearized reading' (duration: 199.991235ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:02.639301Z","caller":"traceutil/trace.go:171","msg":"trace[1781159752] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"146.599716ms","start":"2026-09-22T01:52:02.49268Z","end":"2026-09-22T01:52:02.63928Z","steps":["trace[1781159752] 'process raft request' (duration: 146.078344ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:03.603422Z","caller":"traceutil/trace.go:171","msg":"trace[2134845880] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"100.481323ms","start":"2026-09-22T01:52:03.502921Z","end":"2026-09-22T01:52:03.603402Z","steps":["trace[2134845880] 'read index received' (duration: 89.485756ms)","trace[2134845880] 'applied index is now lower than readState.Index' (duration: 10.994717ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:52:03.603494Z","caller":"traceutil/trace.go:171","msg":"trace[540685402] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"102.287428ms","start":"2026-09-22T01:52:03.501181Z","end":"2026-09-22T01:52:03.603468Z","steps":["trace[540685402] 'process raft request' (duration: 91.147134ms)","trace[540685402] 'compare' (duration: 10.983178ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:52:03.603674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.731305ms","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-09-22T01:52:03.603739Z","caller":"traceutil/trace.go:171","msg":"trace[593280643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:668; }","duration":"100.808947ms","start":"2026-09-22T01:52:03.502897Z","end":"2026-09-22T01:52:03.603706Z","steps":["trace[593280643] 'agreement among raft nodes before linearized reading' (duration: 100.625479ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:03.610796Z","caller":"traceutil/trace.go:171","msg":"trace[1498202890] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"106.370014ms","start":"2026-09-22T01:52:03.504399Z","end":"2026-09-22T01:52:03.610769Z","steps":["trace[1498202890] 'process raft request' (duration: 106.244979ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:03.610862Z","caller":"traceutil/trace.go:171","msg":"trace[1518932129] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"106.657865ms","start":"2026-09-22T01:52:03.504173Z","end":"2026-09-22T01:52:03.610831Z","steps":["trace[1518932129] 'process raft request' (duration: 106.372614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:18.454978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.375382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-22T01:52:18.455068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.721594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:52:18.455081Z","caller":"traceutil/trace.go:171","msg":"trace[770009745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"773.524739ms","start":"2026-09-22T01:52:17.681539Z","end":"2026-09-22T01:52:18.455063Z","steps":["trace[770009745] 'range keys from in-memory index tree' (duration: 773.222255ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:18.455116Z","caller":"traceutil/trace.go:171","msg":"trace[205449475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"388.811908ms","start":"2026-09-22T01:52:18.06629Z","end":"2026-09-22T01:52:18.455102Z","steps":["trace[205449475] 'range keys from in-memory index tree' (duration: 388.64105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:18.455122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:17.681522Z","time spent":"773.591523ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T01:52:18.455145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:18.066263Z","time spent":"388.875811ms","remote":"127.0.0.1:39084","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-09-22T01:52:18.454978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.094108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-22T01:52:18.455721Z","caller":"traceutil/trace.go:171","msg":"trace[1718507911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"725.821891ms","start":"2026-09-22T01:52:17.72984Z","end":"2026-09-22T01:52:18.455662Z","steps":["trace[1718507911] 'range keys from in-memory index tree' (duration: 724.924982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:18.45574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.181241ms","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-09-22T01:52:18.455475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.236165ms","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-09-22T01:52:18.45586Z","caller":"traceutil/trace.go:171","msg":"trace[2134627790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"757.321307ms","start":"2026-09-22T01:52:17.698517Z","end":"2026-09-22T01:52:18.455839Z","steps":["trace[2134627790] 'range keys from in-memory index tree' (duration: 756.523382ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:18.455951Z","caller":"traceutil/trace.go:171","msg":"trace[1233761587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"775.762808ms","start":"2026-09-22T01:52:17.68017Z","end":"2026-09-22T01:52:18.455933Z","steps":["trace[1233761587] 'range keys from in-memory index tree' (duration: 775.100159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:18.456058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:17.680146Z","time spent":"775.876683ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-22T01:52:18.456089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:17.698494Z","time spent":"757.450762ms","remote":"127.0.0.1:39360","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-09-22T01:52:18.456138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:52:17.729829Z","time spent":"725.994269ms","remote":"127.0.0.1:39360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-22T01:52:18.619249Z","caller":"traceutil/trace.go:171","msg":"trace[278168767] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"198.819744ms","start":"2026-09-22T01:52:18.420411Z","end":"2026-09-22T01:52:18.619231Z","steps":["trace[278168767] 'process raft request' (duration: 198.697088ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:18.630763Z","caller":"traceutil/trace.go:171","msg":"trace[259308133] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:840; }","duration":"173.117688ms","start":"2026-09-22T01:52:18.45763Z","end":"2026-09-22T01:52:18.630747Z","steps":["trace[259308133] 'read index received' (duration: 161.417442ms)","trace[259308133] 'applied index is now lower than readState.Index' (duration: 11.698966ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:52:18.630887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.226154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:52:18.630953Z","caller":"traceutil/trace.go:171","msg":"trace[2084249584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"173.332708ms","start":"2026-09-22T01:52:18.457602Z","end":"2026-09-22T01:52:18.630934Z","steps":["trace[2084249584] 'agreement among raft nodes before linearized reading' (duration: 173.209153ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:18.631261Z","caller":"traceutil/trace.go:171","msg":"trace[1105926327] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"169.30378ms","start":"2026-09-22T01:52:18.461945Z","end":"2026-09-22T01:52:18.631249Z","steps":["trace[1105926327] 'process raft request' (duration: 168.735565ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:18.631339Z","caller":"traceutil/trace.go:171","msg":"trace[857930637] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"170.724563ms","start":"2026-09-22T01:52:18.460538Z","end":"2026-09-22T01:52:18.631262Z","steps":["trace[857930637] 'process raft request' (duration: 170.109786ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:18.631791Z","caller":"traceutil/trace.go:171","msg":"trace[508880237] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"171.373802ms","start":"2026-09-22T01:52:18.460408Z","end":"2026-09-22T01:52:18.631782Z","steps":["trace[508880237] 'process raft request' (duration: 170.155798ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:18.631401Z","caller":"traceutil/trace.go:171","msg":"trace[1039938937] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"168.735854ms","start":"2026-09-22T01:52:18.462655Z","end":"2026-09-22T01:52:18.631391Z","steps":["trace[1039938937] 'process raft request' (duration: 168.053465ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:21.562251Z","caller":"traceutil/trace.go:171","msg":"trace[886289638] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"139.187333ms","start":"2026-09-22T01:52:21.423041Z","end":"2026-09-22T01:52:21.562228Z","steps":["trace[886289638] 'process raft request' (duration: 139.031415ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:23.941898Z","caller":"traceutil/trace.go:171","msg":"trace[786067383] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"146.643412ms","start":"2026-09-22T01:52:23.795226Z","end":"2026-09-22T01:52:23.94187Z","steps":["trace[786067383] 'process raft request' (duration: 146.458014ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:27.108864Z","caller":"traceutil/trace.go:171","msg":"trace[1717432170] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"115.004044ms","start":"2026-09-22T01:52:26.993838Z","end":"2026-09-22T01:52:27.108842Z","steps":["trace[1717432170] 'process raft request' (duration: 110.664345ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:34.736474Z","caller":"traceutil/trace.go:171","msg":"trace[94276245] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"254.101036ms","start":"2026-09-22T01:52:34.482349Z","end":"2026-09-22T01:52:34.73645Z","steps":["trace[94276245] 'process raft request' (duration: 253.896405ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:34.968707Z","caller":"traceutil/trace.go:171","msg":"trace[1091088027] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"173.290573ms","start":"2026-09-22T01:52:34.795397Z","end":"2026-09-22T01:52:34.968688Z","steps":["trace[1091088027] 'process raft request' (duration: 173.23179ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:34.968928Z","caller":"traceutil/trace.go:171","msg":"trace[1813933684] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"174.522541ms","start":"2026-09-22T01:52:34.794367Z","end":"2026-09-22T01:52:34.968889Z","steps":["trace[1813933684] 'process raft request' (duration: 164.008346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:53:01.426858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.230392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:53:01.427349Z","caller":"traceutil/trace.go:171","msg":"trace[1738909200] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"278.459388ms","start":"2026-09-22T01:53:01.148817Z","end":"2026-09-22T01:53:01.427277Z","steps":["trace[1738909200] 'process raft request' (duration: 163.715351ms)","trace[1738909200] 'compare' (duration: 114.023391ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:53:01.427473Z","caller":"traceutil/trace.go:171","msg":"trace[524505118] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"192.270013ms","start":"2026-09-22T01:53:01.235182Z","end":"2026-09-22T01:53:01.427452Z","steps":["trace[524505118] 'process raft request' (duration: 191.81484ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:01.427491Z","caller":"traceutil/trace.go:171","msg":"trace[1252653486] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"242.614546ms","start":"2026-09-22T01:53:01.184848Z","end":"2026-09-22T01:53:01.427463Z","steps":["trace[1252653486] 'read index received' (duration: 127.645358ms)","trace[1252653486] 'applied index is now lower than readState.Index' (duration: 114.965548ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:53:01.427792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.914261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T01:53:01.428012Z","caller":"traceutil/trace.go:171","msg":"trace[1244693481] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1081; }","duration":"243.194486ms","start":"2026-09-22T01:53:01.184777Z","end":"2026-09-22T01:53:01.427972Z","steps":["trace[1244693481] 'agreement among raft nodes before linearized reading' (duration: 242.844297ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:03.24858Z","caller":"traceutil/trace.go:171","msg":"trace[1657577562] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"170.095308ms","start":"2026-09-22T01:53:03.078452Z","end":"2026-09-22T01:53:03.248547Z","steps":["trace[1657577562] 'process raft request' (duration: 169.916909ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:29.671985Z","caller":"traceutil/trace.go:171","msg":"trace[605261659] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"115.98662ms","start":"2026-09-22T01:53:29.555974Z","end":"2026-09-22T01:53:29.67196Z","steps":["trace[605261659] 'process raft request' (duration: 115.846213ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:29.679706Z","caller":"traceutil/trace.go:171","msg":"trace[1906726302] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"104.81141ms","start":"2026-09-22T01:53:29.574878Z","end":"2026-09-22T01:53:29.679689Z","steps":["trace[1906726302] 'process raft request' (duration: 104.564417ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:33.590138Z","caller":"traceutil/trace.go:171","msg":"trace[1367168344] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1235; }","duration":"146.87005ms","start":"2026-09-22T01:53:33.443237Z","end":"2026-09-22T01:53:33.590107Z","steps":["trace[1367168344] 'read index received' (duration: 146.605386ms)","trace[1367168344] 'applied index is now lower than readState.Index' (duration: 245.313µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:53:33.590189Z","caller":"traceutil/trace.go:171","msg":"trace[8683085] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"156.285111ms","start":"2026-09-22T01:53:33.433881Z","end":"2026-09-22T01:53:33.590166Z","steps":["trace[8683085] 'process raft request' (duration: 156.058378ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:53:33.590387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.127434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.112\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-22T01:53:33.590422Z","caller":"traceutil/trace.go:171","msg":"trace[1632144863] range","detail":"{range_begin:/registry/masterleases/162.253.55.112; range_end:; response_count:1; response_revision:1182; }","duration":"147.219378ms","start":"2026-09-22T01:53:33.443192Z","end":"2026-09-22T01:53:33.590412Z","steps":["trace[1632144863] 'agreement among raft nodes before linearized reading' (duration: 147.06358ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:53:33.590435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.319899ms","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-09-22T01:53:33.590471Z","caller":"traceutil/trace.go:171","msg":"trace[783825788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1182; }","duration":"100.405863ms","start":"2026-09-22T01:53:33.490053Z","end":"2026-09-22T01:53:33.590459Z","steps":["trace[783825788] 'agreement among raft nodes before linearized reading' (duration: 100.295527ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:33.82027Z","caller":"traceutil/trace.go:171","msg":"trace[1760459251] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"224.545745ms","start":"2026-09-22T01:53:33.595685Z","end":"2026-09-22T01:53:33.820231Z","steps":["trace[1760459251] 'process raft request' (duration: 224.185216ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:53:33.820541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.685456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-22T01:53:33.820628Z","caller":"traceutil/trace.go:171","msg":"trace[877825126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1183; }","duration":"125.848904ms","start":"2026-09-22T01:53:33.694764Z","end":"2026-09-22T01:53:33.820613Z","steps":["trace[877825126] 'agreement among raft nodes before linearized reading' (duration: 125.554038ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:33.820262Z","caller":"traceutil/trace.go:171","msg":"trace[102245576] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"125.38835ms","start":"2026-09-22T01:53:33.694797Z","end":"2026-09-22T01:53:33.820185Z","steps":["trace[102245576] 'read index received' (duration: 125.100154ms)","trace[102245576] 'applied index is now lower than readState.Index' (duration: 286.495µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:53:33.957572Z","caller":"traceutil/trace.go:171","msg":"trace[1277784765] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"125.203189ms","start":"2026-09-22T01:53:33.832341Z","end":"2026-09-22T01:53:33.957544Z","steps":["trace[1277784765] 'process raft request' (duration: 125.154557ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:33.957644Z","caller":"traceutil/trace.go:171","msg":"trace[1192144651] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"260.829102ms","start":"2026-09-22T01:53:33.696768Z","end":"2026-09-22T01:53:33.957598Z","steps":["trace[1192144651] 'process raft request' (duration: 163.43084ms)","trace[1192144651] 'compare' (duration: 97.059254ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:53:33.958008Z","caller":"traceutil/trace.go:171","msg":"trace[1797558619] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"132.7586ms","start":"2026-09-22T01:53:33.825235Z","end":"2026-09-22T01:53:33.957993Z","steps":["trace[1797558619] 'process raft request' (duration: 132.220992ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:33.958071Z","caller":"traceutil/trace.go:171","msg":"trace[837525210] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"215.827052ms","start":"2026-09-22T01:53:33.742225Z","end":"2026-09-22T01:53:33.958052Z","steps":["trace[837525210] 'process raft request' (duration: 215.166837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:53:33.958003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.394967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-22T01:53:33.95835Z","caller":"traceutil/trace.go:171","msg":"trace[108785914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1187; }","duration":"250.789481ms","start":"2026-09-22T01:53:33.707546Z","end":"2026-09-22T01:53:33.958336Z","steps":["trace[108785914] 'agreement among raft nodes before linearized reading' (duration: 250.204799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:53:33.958468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.415988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-22T01:53:33.958524Z","caller":"traceutil/trace.go:171","msg":"trace[1196354728] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1187; }","duration":"201.499492ms","start":"2026-09-22T01:53:33.75701Z","end":"2026-09-22T01:53:33.95851Z","steps":["trace[1196354728] 'agreement among raft nodes before linearized reading' (duration: 201.354304ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:33.957684Z","caller":"traceutil/trace.go:171","msg":"trace[1325469043] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1238; }","duration":"136.782335ms","start":"2026-09-22T01:53:33.820868Z","end":"2026-09-22T01:53:33.95765Z","steps":["trace[1325469043] 'read index received' (duration: 39.34129ms)","trace[1325469043] 'applied index is now lower than readState.Index' (duration: 97.437225ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:53:34.114269Z","caller":"traceutil/trace.go:171","msg":"trace[100719457] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"148.303073ms","start":"2026-09-22T01:53:33.965932Z","end":"2026-09-22T01:53:34.114235Z","steps":["trace[100719457] 'process raft request' (duration: 147.452338ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:38.177603Z","caller":"traceutil/trace.go:171","msg":"trace[1317862009] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1255; }","duration":"110.107472ms","start":"2026-09-22T01:53:38.06747Z","end":"2026-09-22T01:53:38.177578Z","steps":["trace[1317862009] 'read index received' (duration: 108.03064ms)","trace[1317862009] 'applied index is now lower than readState.Index' (duration: 2.075671ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:53:38.177791Z","caller":"traceutil/trace.go:171","msg":"trace[139014075] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"124.478918ms","start":"2026-09-22T01:53:38.053282Z","end":"2026-09-22T01:53:38.17776Z","steps":["trace[139014075] 'process raft request' (duration: 122.205176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:53:38.178043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.542644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:53:38.178157Z","caller":"traceutil/trace.go:171","msg":"trace[1057292548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"110.701812ms","start":"2026-09-22T01:53:38.067424Z","end":"2026-09-22T01:53:38.178126Z","steps":["trace[1057292548] 'agreement among raft nodes before linearized reading' (duration: 110.534104ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:53:40.058646Z","caller":"traceutil/trace.go:171","msg":"trace[1057876519] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"151.215038ms","start":"2026-09-22T01:53:39.90741Z","end":"2026-09-22T01:53:40.058625Z","steps":["trace[1057876519] 'process raft request' (duration: 151.080551ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:54:15.989882Z","caller":"traceutil/trace.go:171","msg":"trace[554197171] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"101.06159ms","start":"2026-09-22T01:54:15.888775Z","end":"2026-09-22T01:54:15.989836Z","steps":["trace[554197171] 'process raft request' (duration: 100.931253ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:54:24.285681Z","caller":"traceutil/trace.go:171","msg":"trace[1086856655] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"253.812174ms","start":"2026-09-22T01:54:24.031842Z","end":"2026-09-22T01:54:24.285654Z","steps":["trace[1086856655] 'process raft request' (duration: 253.641254ms)"],"step_count":1}