{"level":"warn","ts":"2026-01-30T18:12:42.455753Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T18:12:42.455853Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207: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.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207: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-01-30T18:12:42.455925Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T18:12:42.455935Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-01-30T18:12:42.455953Z","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-01-30T18:12:42.457562Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-01-30T18:12:42.457764Z","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":2,"max-cpu-available":2,"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.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207: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-01-30T18:12:42.467366Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.951162ms"} {"level":"info","ts":"2026-01-30T18:12:42.50516Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-01-30T18:12:42.505507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-01-30T18:12:42.5056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-01-30T18:12:42.505654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-30T18:12:42.50567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-01-30T18:12:42.505754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-01-30T18:12:42.520092Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-30T18:12:42.531088Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-30T18:12:42.534829Z","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-01-30T18:12:42.537409Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-30T18:12:42.540108Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-30T18:12:42.54114Z","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-01-30T18:12:42.541908Z","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-01-30T18:12:42.542022Z","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-01-30T18:12:42.550888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-01-30T18:12:42.550985Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-01-30T18:12:42.551419Z","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-01-30T18:12:42.554162Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-30T18:12:42.554202Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-01-30T18:12:42.554911Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-01-30T18:12:42.555522Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-30T18:12:42.606756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-01-30T18:12:42.60706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-01-30T18:12:42.607072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-01-30T18:12:42.607083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-01-30T18:12:42.607103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-01-30T18:12:42.607115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-01-30T18:12:42.607127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-01-30T18:12:42.60823Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T18:12:42.614073Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-01-30T18:12:42.614437Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T18:12:42.614583Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T18:12:42.614714Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T18:12:42.614768Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T18:12:42.615148Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T18:12:42.616111Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-30T18:12:42.616184Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-30T18:12:42.620728Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-30T18:12:42.62469Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"info","ts":"2026-01-30T18:12:59.236121Z","caller":"traceutil/trace.go:171","msg":"trace[334973756] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"391.936268ms","start":"2026-01-30T18:12:58.844158Z","end":"2026-01-30T18:12:59.236094Z","steps":["trace[334973756] 'process raft request' (duration: 391.713203ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:12:59.236639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:12:58.844138Z","time spent":"392.066791ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:12:59.368119Z","caller":"traceutil/trace.go:171","msg":"trace[590319104] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:420; }","duration":"265.992968ms","start":"2026-01-30T18:12:59.102095Z","end":"2026-01-30T18:12:59.368088Z","steps":["trace[590319104] 'read index received' (duration: 133.825733ms)","trace[590319104] 'applied index is now lower than readState.Index' (duration: 132.166415ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:12:59.368322Z","caller":"traceutil/trace.go:171","msg":"trace[1616522732] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"445.28526ms","start":"2026-01-30T18:12:58.923028Z","end":"2026-01-30T18:12:59.368313Z","steps":["trace[1616522732] 'process raft request' (duration: 444.922012ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:12:59.36842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:12:58.922997Z","time spent":"445.348171ms","remote":"127.0.0.1:43422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4042,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:12:59.368686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.586922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-30T18:12:59.368719Z","caller":"traceutil/trace.go:171","msg":"trace[370884039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"266.662313ms","start":"2026-01-30T18:12:59.10205Z","end":"2026-01-30T18:12:59.368713Z","steps":["trace[370884039] 'agreement among raft nodes before linearized reading' (duration: 266.602722ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:12:59.368864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.105719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-30T18:12:59.368882Z","caller":"traceutil/trace.go:171","msg":"trace[1308799005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"259.136059ms","start":"2026-01-30T18:12:59.109742Z","end":"2026-01-30T18:12:59.368878Z","steps":["trace[1308799005] 'agreement among raft nodes before linearized reading' (duration: 259.103758ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:12:59.369303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.430653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-30T18:12:59.369348Z","caller":"traceutil/trace.go:171","msg":"trace[1237885213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:412; }","duration":"252.483604ms","start":"2026-01-30T18:12:59.116851Z","end":"2026-01-30T18:12:59.369335Z","steps":["trace[1237885213] 'agreement among raft nodes before linearized reading' (duration: 252.409582ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:12:59.710394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.428813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T18:12:59.710605Z","caller":"traceutil/trace.go:171","msg":"trace[679584729] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:422; }","duration":"115.135929ms","start":"2026-01-30T18:12:59.595455Z","end":"2026-01-30T18:12:59.710591Z","steps":["trace[679584729] 'read index received' (duration: 4.916004ms)","trace[679584729] 'applied index is now lower than readState.Index' (duration: 110.219215ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:12:59.710789Z","caller":"traceutil/trace.go:171","msg":"trace[1040440497] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"325.015671ms","start":"2026-01-30T18:12:59.385764Z","end":"2026-01-30T18:12:59.710779Z","steps":["trace[1040440497] 'process raft request' (duration: 172.943494ms)","trace[1040440497] 'compare' (duration: 150.959751ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:12:59.710824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:12:59.385738Z","time spent":"325.069782ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:12:59.71094Z","caller":"traceutil/trace.go:171","msg":"trace[1860069438] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"322.541923ms","start":"2026-01-30T18:12:59.388392Z","end":"2026-01-30T18:12:59.710934Z","steps":["trace[1860069438] 'process raft request' (duration: 322.104943ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:12:59.710964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:12:59.388383Z","time spent":"322.567224ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:12:59.71102Z","caller":"traceutil/trace.go:171","msg":"trace[118631214] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"322.575744ms","start":"2026-01-30T18:12:59.388441Z","end":"2026-01-30T18:12:59.711016Z","steps":["trace[118631214] 'process raft request' (duration: 322.129253ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:12:59.711042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:12:59.388438Z","time spent":"322.591674ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:12:59.711108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.657581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:12:59.711125Z","caller":"traceutil/trace.go:171","msg":"trace[222677581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"115.707001ms","start":"2026-01-30T18:12:59.595413Z","end":"2026-01-30T18:12:59.71112Z","steps":["trace[222677581] 'agreement among raft nodes before linearized reading' (duration: 115.67735ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:19.852015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.721567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T18:13:19.852087Z","caller":"traceutil/trace.go:171","msg":"trace[1237972901] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"391.718893ms","start":"2026-01-30T18:13:19.460356Z","end":"2026-01-30T18:13:19.852075Z","steps":["trace[1237972901] 'process raft request' (duration: 206.872013ms)","trace[1237972901] 'compare' (duration: 184.545743ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:13:19.852126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:19.460341Z","time spent":"391.764774ms","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:13:20.285369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057751939747492,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T18:13:20.786109Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057751939747492,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T18:13:20.878966Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.000108018s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-30T18:13:21.166565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.371656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-30T18:13:21.166626Z","caller":"traceutil/trace.go:171","msg":"trace[1482294827] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.544834911s","start":"2026-01-30T18:13:19.621781Z","end":"2026-01-30T18:13:21.166616Z","steps":["trace[1482294827] 'process raft request' (duration: 1.257346872s)","trace[1482294827] 'compare' (duration: 287.08857ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:13:21.166652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:19.62176Z","time spent":"1.544881662s","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T18:13:21.180163Z","caller":"traceutil/trace.go:171","msg":"trace[1436664611] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:578; }","duration":"1.395208319s","start":"2026-01-30T18:13:19.784944Z","end":"2026-01-30T18:13:21.180153Z","steps":["trace[1436664611] 'read index received' (duration: 1.094879521s)","trace[1436664611] 'applied index is now lower than readState.Index' (duration: 300.328058ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:13:21.180321Z","caller":"traceutil/trace.go:171","msg":"trace[1121664599] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.396854737s","start":"2026-01-30T18:13:19.78346Z","end":"2026-01-30T18:13:21.180315Z","steps":["trace[1121664599] 'process raft request' (duration: 1.39655627s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.180386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:19.783444Z","time spent":"1.396891949s","remote":"127.0.0.1:43422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4408,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:13:21.180516Z","caller":"traceutil/trace.go:171","msg":"trace[177539997] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.301008829s","start":"2026-01-30T18:13:19.879503Z","end":"2026-01-30T18:13:21.180512Z","steps":["trace[177539997] 'process raft request' (duration: 1.300602449s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.180549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:19.879489Z","time spent":"1.301040929s","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:13:21.180651Z","caller":"traceutil/trace.go:171","msg":"trace[298516901] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.177413522s","start":"2026-01-30T18:13:20.003234Z","end":"2026-01-30T18:13:21.180647Z","steps":["trace[298516901] 'process raft request' (duration: 1.17689812s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.180677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:20.003216Z","time spent":"1.177448832s","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:13:21.180752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395805022s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:13:21.180771Z","caller":"traceutil/trace.go:171","msg":"trace[211569553] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"1.395841824s","start":"2026-01-30T18:13:19.784925Z","end":"2026-01-30T18:13:21.180767Z","steps":["trace[211569553] 'agreement among raft nodes before linearized reading' (duration: 1.395812343s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.180787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:19.784918Z","time spent":"1.395866324s","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-01-30T18:13:21.598635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.815838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T18:13:21.598695Z","caller":"traceutil/trace.go:171","msg":"trace[126258672] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"418.486685ms","start":"2026-01-30T18:13:21.180198Z","end":"2026-01-30T18:13:21.598685Z","steps":["trace[126258672] 'read index received' (duration: 307.517804ms)","trace[126258672] 'applied index is now lower than readState.Index' (duration: 110.968271ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:13:21.59883Z","caller":"traceutil/trace.go:171","msg":"trace[2027754642] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"420.165385ms","start":"2026-01-30T18:13:21.178659Z","end":"2026-01-30T18:13:21.598824Z","steps":["trace[2027754642] 'process raft request' (duration: 309.112152ms)","trace[2027754642] 'compare' (duration: 110.722235ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:13:21.598862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:21.178643Z","time spent":"420.203246ms","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:13:21.598999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.559475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-01-30T18:13:21.599015Z","caller":"traceutil/trace.go:171","msg":"trace[1397543615] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:567; }","duration":"613.609025ms","start":"2026-01-30T18:13:20.985401Z","end":"2026-01-30T18:13:21.59901Z","steps":["trace[1397543615] 'agreement among raft nodes before linearized reading' (duration: 613.549754ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.599031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:20.985379Z","time spent":"613.647017ms","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-01-30T18:13:21.599108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.666247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-01-30T18:13:21.59912Z","caller":"traceutil/trace.go:171","msg":"trace[1560830706] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:567; }","duration":"812.702408ms","start":"2026-01-30T18:13:20.786414Z","end":"2026-01-30T18:13:21.599117Z","steps":["trace[1560830706] 'agreement among raft nodes before linearized reading' (duration: 812.664517ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.599134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:20.786399Z","time spent":"812.731628ms","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-01-30T18:13:21.599209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758549652s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-30T18:13:21.599221Z","caller":"traceutil/trace.go:171","msg":"trace[1439384069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"1.758561232s","start":"2026-01-30T18:13:19.840656Z","end":"2026-01-30T18:13:21.599218Z","steps":["trace[1439384069] 'agreement among raft nodes before linearized reading' (duration: 1.758526061s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.599233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:19.840641Z","time spent":"1.758589552s","remote":"127.0.0.1:43536","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-01-30T18:13:21.599252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758569492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-30T18:13:21.59928Z","caller":"traceutil/trace.go:171","msg":"trace[1425778201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"1.758604792s","start":"2026-01-30T18:13:19.840667Z","end":"2026-01-30T18:13:21.599271Z","steps":["trace[1425778201] 'agreement among raft nodes before linearized reading' (duration: 1.75850646s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.599317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.783301017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-01-30T18:13:21.599319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:19.840664Z","time spent":"1.758645523s","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-30T18:13:21.599331Z","caller":"traceutil/trace.go:171","msg":"trace[640463928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"1.783348148s","start":"2026-01-30T18:13:19.815979Z","end":"2026-01-30T18:13:21.599328Z","steps":["trace[640463928] 'agreement among raft nodes before linearized reading' (duration: 1.783300867s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.599344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:19.81597Z","time spent":"1.783371619s","remote":"127.0.0.1:43536","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-01-30T18:13:21.599382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.80962438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:13:21.599392Z","caller":"traceutil/trace.go:171","msg":"trace[735165331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"1.8096443s","start":"2026-01-30T18:13:19.789744Z","end":"2026-01-30T18:13:21.599388Z","steps":["trace[735165331] 'agreement among raft nodes before linearized reading' (duration: 1.80962835s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.599402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:19.789738Z","time spent":"1.809662011s","remote":"127.0.0.1:43262","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-01-30T18:13:21.599448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012903644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-01-30T18:13:21.599467Z","caller":"traceutil/trace.go:171","msg":"trace[1005652320] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:567; }","duration":"1.012956236s","start":"2026-01-30T18:13:20.586505Z","end":"2026-01-30T18:13:21.599461Z","steps":["trace[1005652320] 'agreement among raft nodes before linearized reading' (duration: 1.012899874s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.599485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:20.586488Z","time spent":"1.012992196s","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-01-30T18:13:21.599561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214597697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-01-30T18:13:21.599577Z","caller":"traceutil/trace.go:171","msg":"trace[389889664] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:567; }","duration":"1.214640837s","start":"2026-01-30T18:13:20.38493Z","end":"2026-01-30T18:13:21.599571Z","steps":["trace[389889664] 'agreement among raft nodes before linearized reading' (duration: 1.214596026s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.599594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:20.384916Z","time spent":"1.214673489s","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-01-30T18:13:21.599701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413908044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-01-30T18:13:21.599718Z","caller":"traceutil/trace.go:171","msg":"trace[1485863084] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:567; }","duration":"1.413947526s","start":"2026-01-30T18:13:20.185765Z","end":"2026-01-30T18:13:21.599713Z","steps":["trace[1485863084] 'agreement among raft nodes before linearized reading' (duration: 1.413897854s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.599734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:20.185753Z","time spent":"1.413977356s","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-01-30T18:13:21.599804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.491682525s","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-01-30T18:13:21.599819Z","caller":"traceutil/trace.go:171","msg":"trace[547818978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:567; }","duration":"1.491734826s","start":"2026-01-30T18:13:20.108079Z","end":"2026-01-30T18:13:21.599814Z","steps":["trace[547818978] 'agreement among raft nodes before linearized reading' (duration: 1.491689605s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.599872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:20.108061Z","time spent":"1.491806737s","remote":"127.0.0.1:43536","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-01-30T18:13:21.599888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.557250669s","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-01-30T18:13:21.599903Z","caller":"traceutil/trace.go:171","msg":"trace[1229541535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:567; }","duration":"1.55728658s","start":"2026-01-30T18:13:20.042612Z","end":"2026-01-30T18:13:21.599899Z","steps":["trace[1229541535] 'agreement among raft nodes before linearized reading' (duration: 1.557223178s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.599927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:20.042598Z","time spent":"1.55731432s","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-30T18:13:21.599964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614737797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:13:21.599975Z","caller":"traceutil/trace.go:171","msg":"trace[469513365] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:567; }","duration":"1.614775267s","start":"2026-01-30T18:13:19.985196Z","end":"2026-01-30T18:13:21.599971Z","steps":["trace[469513365] 'agreement among raft nodes before linearized reading' (duration: 1.614758837s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.599986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:19.98518Z","time spent":"1.614802999s","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-01-30T18:13:21.649893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.164426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-01-30T18:13:21.649953Z","caller":"traceutil/trace.go:171","msg":"trace[1755154600] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:567; }","duration":"264.38381ms","start":"2026-01-30T18:13:21.385554Z","end":"2026-01-30T18:13:21.649937Z","steps":["trace[1755154600] 'agreement among raft nodes before linearized reading' (duration: 264.155595ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.650205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.472146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-01-30T18:13:21.650254Z","caller":"traceutil/trace.go:171","msg":"trace[1640445720] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:567; }","duration":"461.541768ms","start":"2026-01-30T18:13:21.1887Z","end":"2026-01-30T18:13:21.650242Z","steps":["trace[1640445720] 'agreement among raft nodes before linearized reading' (duration: 461.441026ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:21.650433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:21.188688Z","time spent":"461.732362ms","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-01-30T18:13:22.382048Z","caller":"traceutil/trace.go:171","msg":"trace[1093425397] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"100.11921ms","start":"2026-01-30T18:13:22.281918Z","end":"2026-01-30T18:13:22.382037Z","steps":["trace[1093425397] 'process raft request' (duration: 99.962287ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:13:23.796061Z","caller":"traceutil/trace.go:171","msg":"trace[1939691000] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"127.373244ms","start":"2026-01-30T18:13:23.668664Z","end":"2026-01-30T18:13:23.796038Z","steps":["trace[1939691000] 'process raft request' (duration: 127.075846ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:13:23.796862Z","caller":"traceutil/trace.go:171","msg":"trace[579589082] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"124.567689ms","start":"2026-01-30T18:13:23.672285Z","end":"2026-01-30T18:13:23.796853Z","steps":["trace[579589082] 'read index received' (duration: 123.39023ms)","trace[579589082] 'applied index is now lower than readState.Index' (duration: 1.176339ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:13:23.796993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.693081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-30T18:13:23.797019Z","caller":"traceutil/trace.go:171","msg":"trace[431209242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"124.747363ms","start":"2026-01-30T18:13:23.672266Z","end":"2026-01-30T18:13:23.797013Z","steps":["trace[431209242] 'agreement among raft nodes before linearized reading' (duration: 124.646821ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:23.797535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.955894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-30T18:13:23.797573Z","caller":"traceutil/trace.go:171","msg":"trace[2133572245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"115.013056ms","start":"2026-01-30T18:13:23.682554Z","end":"2026-01-30T18:13:23.797567Z","steps":["trace[2133572245] 'agreement among raft nodes before linearized reading' (duration: 114.927014ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:23.798091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.149996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-30T18:13:23.798118Z","caller":"traceutil/trace.go:171","msg":"trace[176564633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:581; }","duration":"123.188897ms","start":"2026-01-30T18:13:23.674923Z","end":"2026-01-30T18:13:23.798112Z","steps":["trace[176564633] 'agreement among raft nodes before linearized reading' (duration: 123.109015ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:23.798464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.242973ms","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-01-30T18:13:23.798487Z","caller":"traceutil/trace.go:171","msg":"trace[856177171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"125.312695ms","start":"2026-01-30T18:13:23.673168Z","end":"2026-01-30T18:13:23.79848Z","steps":["trace[856177171] 'agreement among raft nodes before linearized reading' (duration: 124.288881ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:13:29.180136Z","caller":"traceutil/trace.go:171","msg":"trace[2014779998] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"320.632839ms","start":"2026-01-30T18:13:28.859488Z","end":"2026-01-30T18:13:29.180121Z","steps":["trace[2014779998] 'process raft request' (duration: 320.522867ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:29.180227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:28.859475Z","time spent":"320.704391ms","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:13:41.129484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.580416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T18:13:41.129546Z","caller":"traceutil/trace.go:171","msg":"trace[1773925142] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.072935591s","start":"2026-01-30T18:13:40.056601Z","end":"2026-01-30T18:13:41.129536Z","steps":["trace[1773925142] 'process raft request' (duration: 495.255412ms)","trace[1773925142] 'compare' (duration: 577.425033ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:13:41.129577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:40.056585Z","time spent":"1.072978631s","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:13:41.209605Z","caller":"traceutil/trace.go:171","msg":"trace[1057725629] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:685; }","duration":"951.498097ms","start":"2026-01-30T18:13:40.258092Z","end":"2026-01-30T18:13:41.20959Z","steps":["trace[1057725629] 'read index received' (duration: 293.773205ms)","trace[1057725629] 'applied index is now lower than readState.Index' (duration: 657.724432ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:13:41.209753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.64618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wkngk\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-01-30T18:13:41.20977Z","caller":"traceutil/trace.go:171","msg":"trace[1609454976] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wkngk; range_end:; response_count:1; response_revision:667; }","duration":"951.676301ms","start":"2026-01-30T18:13:40.258088Z","end":"2026-01-30T18:13:41.209764Z","steps":["trace[1609454976] 'agreement among raft nodes before linearized reading' (duration: 951.558908ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:41.209788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:40.258044Z","time spent":"951.739792ms","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18273,"request content":"key:\"/registry/pods/kube-system/cilium-wkngk\" "} {"level":"info","ts":"2026-01-30T18:13:41.210156Z","caller":"traceutil/trace.go:171","msg":"trace[858326757] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"1.148162622s","start":"2026-01-30T18:13:40.061985Z","end":"2026-01-30T18:13:41.210148Z","steps":["trace[858326757] 'process raft request' (duration: 1.147583268s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:41.21023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:40.061979Z","time spent":"1.148216943s","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:13:41.210274Z","caller":"traceutil/trace.go:171","msg":"trace[1451786294] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"1.151213962s","start":"2026-01-30T18:13:40.059055Z","end":"2026-01-30T18:13:41.210269Z","steps":["trace[1451786294] 'process raft request' (duration: 1.150439145s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:41.210294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:40.059049Z","time spent":"1.151232963s","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:13:41.891747Z","caller":"traceutil/trace.go:171","msg":"trace[505621375] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"666.366723ms","start":"2026-01-30T18:13:41.225364Z","end":"2026-01-30T18:13:41.891731Z","steps":["trace[505621375] 'process raft request' (duration: 666.286811ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:41.891836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:13:41.225349Z","time spent":"666.439354ms","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:13:41.897942Z","caller":"traceutil/trace.go:171","msg":"trace[1031220821] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"108.181586ms","start":"2026-01-30T18:13:41.789746Z","end":"2026-01-30T18:13:41.897927Z","steps":["trace[1031220821] 'read index received' (duration: 102.564695ms)","trace[1031220821] 'applied index is now lower than readState.Index' (duration: 5.616011ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:13:41.89801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.251578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:13:41.89804Z","caller":"traceutil/trace.go:171","msg":"trace[70845115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"108.311199ms","start":"2026-01-30T18:13:41.789718Z","end":"2026-01-30T18:13:41.898029Z","steps":["trace[70845115] 'agreement among raft nodes before linearized reading' (duration: 108.258898ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:13:47.417614Z","caller":"traceutil/trace.go:171","msg":"trace[1947618608] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"177.208926ms","start":"2026-01-30T18:13:47.240387Z","end":"2026-01-30T18:13:47.417596Z","steps":["trace[1947618608] 'read index received' (duration: 177.082293ms)","trace[1947618608] 'applied index is now lower than readState.Index' (duration: 125.973µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:13:47.417693Z","caller":"traceutil/trace.go:171","msg":"trace[1548661560] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"211.763463ms","start":"2026-01-30T18:13:47.205922Z","end":"2026-01-30T18:13:47.417686Z","steps":["trace[1548661560] 'process raft request' (duration: 211.582349ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:13:47.417874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.941673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-01-30T18:13:47.417935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.535363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-30T18:13:47.417959Z","caller":"traceutil/trace.go:171","msg":"trace[465220907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:738; }","duration":"177.569173ms","start":"2026-01-30T18:13:47.240382Z","end":"2026-01-30T18:13:47.417951Z","steps":["trace[465220907] 'agreement among raft nodes before linearized reading' (duration: 177.483991ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:13:47.418178Z","caller":"traceutil/trace.go:171","msg":"trace[1103568438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:738; }","duration":"174.043625ms","start":"2026-01-30T18:13:47.243878Z","end":"2026-01-30T18:13:47.417921Z","steps":["trace[1103568438] 'agreement among raft nodes before linearized reading' (duration: 173.881741ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:13:47.57465Z","caller":"traceutil/trace.go:171","msg":"trace[1421820065] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"151.626174ms","start":"2026-01-30T18:13:47.423001Z","end":"2026-01-30T18:13:47.574627Z","steps":["trace[1421820065] 'process raft request' (duration: 151.581973ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:13:47.575004Z","caller":"traceutil/trace.go:171","msg":"trace[1184686315] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"152.003502ms","start":"2026-01-30T18:13:47.422989Z","end":"2026-01-30T18:13:47.574992Z","steps":["trace[1184686315] 'process raft request' (duration: 151.47491ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:13:50.344298Z","caller":"traceutil/trace.go:171","msg":"trace[878535025] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"257.524394ms","start":"2026-01-30T18:13:50.086767Z","end":"2026-01-30T18:13:50.344292Z","steps":["trace[878535025] 'process raft request' (duration: 257.240778ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:13:50.344251Z","caller":"traceutil/trace.go:171","msg":"trace[833687646] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"246.852351ms","start":"2026-01-30T18:13:50.097385Z","end":"2026-01-30T18:13:50.344238Z","steps":["trace[833687646] 'read index received' (duration: 246.565125ms)","trace[833687646] 'applied index is now lower than readState.Index' (duration: 286.576µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:13:50.344555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.159349ms","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-01-30T18:13:50.344575Z","caller":"traceutil/trace.go:171","msg":"trace[1719170441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:746; }","duration":"247.202109ms","start":"2026-01-30T18:13:50.097368Z","end":"2026-01-30T18:13:50.34457Z","steps":["trace[1719170441] 'agreement among raft nodes before linearized reading' (duration: 247.118247ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:03.753041Z","caller":"traceutil/trace.go:171","msg":"trace[105186801] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"266.648014ms","start":"2026-01-30T18:14:03.486371Z","end":"2026-01-30T18:14:03.753019Z","steps":["trace[105186801] 'process raft request' (duration: 266.45192ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:03.753267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.064523ms","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-01-30T18:14:03.753308Z","caller":"traceutil/trace.go:171","msg":"trace[686216697] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"100.135436ms","start":"2026-01-30T18:14:03.653163Z","end":"2026-01-30T18:14:03.753298Z","steps":["trace[686216697] 'agreement among raft nodes before linearized reading' (duration: 100.048424ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:06.657882Z","caller":"traceutil/trace.go:171","msg":"trace[1086818400] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"247.063676ms","start":"2026-01-30T18:14:06.4108Z","end":"2026-01-30T18:14:06.657864Z","steps":["trace[1086818400] 'read index received' (duration: 246.907742ms)","trace[1086818400] 'applied index is now lower than readState.Index' (duration: 155.334µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:14:06.658136Z","caller":"traceutil/trace.go:171","msg":"trace[1336627903] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"259.496999ms","start":"2026-01-30T18:14:06.398631Z","end":"2026-01-30T18:14:06.658128Z","steps":["trace[1336627903] 'process raft request' (duration: 259.131841ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:06.658371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.479254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:14:06.658397Z","caller":"traceutil/trace.go:171","msg":"trace[1204815111] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:797; }","duration":"247.601167ms","start":"2026-01-30T18:14:06.410788Z","end":"2026-01-30T18:14:06.658389Z","steps":["trace[1204815111] 'agreement among raft nodes before linearized reading' (duration: 247.473334ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:06.658565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.201148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-30T18:14:06.658583Z","caller":"traceutil/trace.go:171","msg":"trace[1474502322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"221.22978ms","start":"2026-01-30T18:14:06.437348Z","end":"2026-01-30T18:14:06.658578Z","steps":["trace[1474502322] 'agreement among raft nodes before linearized reading' (duration: 221.154218ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:06.658763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.721935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-30T18:14:06.658783Z","caller":"traceutil/trace.go:171","msg":"trace[1447970336] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:797; }","duration":"187.856918ms","start":"2026-01-30T18:14:06.47092Z","end":"2026-01-30T18:14:06.658777Z","steps":["trace[1447970336] 'agreement among raft nodes before linearized reading' (duration: 187.797477ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:07.111095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.92197ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-01-30T18:14:07.111174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:06.66514Z","time spent":"446.030478ms","remote":"127.0.0.1:43286","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-30T18:14:07.13408Z","caller":"traceutil/trace.go:171","msg":"trace[1787233270] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:823; }","duration":"339.526713ms","start":"2026-01-30T18:14:06.794483Z","end":"2026-01-30T18:14:07.13401Z","steps":["trace[1787233270] 'read index received' (duration: 159.724651ms)","trace[1787233270] 'applied index is now lower than readState.Index' (duration: 179.801132ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:07.134222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.721307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:14:07.134257Z","caller":"traceutil/trace.go:171","msg":"trace[1174961183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"339.816349ms","start":"2026-01-30T18:14:06.794429Z","end":"2026-01-30T18:14:07.134246Z","steps":["trace[1174961183] 'agreement among raft nodes before linearized reading' (duration: 339.705377ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:07.134289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:06.794354Z","time spent":"339.927931ms","remote":"127.0.0.1:43262","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-01-30T18:14:07.134722Z","caller":"traceutil/trace.go:171","msg":"trace[1169572420] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"469.199125ms","start":"2026-01-30T18:14:06.665513Z","end":"2026-01-30T18:14:07.134712Z","steps":["trace[1169572420] 'process raft request' (duration: 468.373987ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:07.134789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:06.665501Z","time spent":"469.243646ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:14:08.01357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.723159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-30T18:14:08.013647Z","caller":"traceutil/trace.go:171","msg":"trace[1289376854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"226.829282ms","start":"2026-01-30T18:14:07.786799Z","end":"2026-01-30T18:14:08.013628Z","steps":["trace[1289376854] 'range keys from in-memory index tree' (duration: 226.590586ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:08.013956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.173558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-30T18:14:08.01399Z","caller":"traceutil/trace.go:171","msg":"trace[1132143575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"195.23102ms","start":"2026-01-30T18:14:07.818748Z","end":"2026-01-30T18:14:08.013979Z","steps":["trace[1132143575] 'range keys from in-memory index tree' (duration: 195.099107ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:08.014398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.33633ms","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-01-30T18:14:08.014433Z","caller":"traceutil/trace.go:171","msg":"trace[708398728] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"163.470343ms","start":"2026-01-30T18:14:07.850953Z","end":"2026-01-30T18:14:08.014423Z","steps":["trace[708398728] 'range keys from in-memory index tree' (duration: 163.255628ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:09.025481Z","caller":"traceutil/trace.go:171","msg":"trace[947331367] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:827; }","duration":"420.875755ms","start":"2026-01-30T18:14:08.604504Z","end":"2026-01-30T18:14:09.025379Z","steps":["trace[947331367] 'read index received' (duration: 366.643977ms)","trace[947331367] 'applied index is now lower than readState.Index' (duration: 54.230618ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:14:09.025784Z","caller":"traceutil/trace.go:171","msg":"trace[1571879697] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"994.105811ms","start":"2026-01-30T18:14:08.031662Z","end":"2026-01-30T18:14:09.025768Z","steps":["trace[1571879697] 'process raft request' (duration: 939.550445ms)","trace[1571879697] 'compare' (duration: 53.468222ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:09.025892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:08.03165Z","time spent":"994.177172ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:14:09.026112Z","caller":"traceutil/trace.go:171","msg":"trace[179784600] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"990.974162ms","start":"2026-01-30T18:14:08.035128Z","end":"2026-01-30T18:14:09.026103Z","steps":["trace[179784600] 'process raft request' (duration: 990.165724ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:09.026183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:08.035119Z","time spent":"991.016653ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:14:09.02632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.795705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:14:09.026554Z","caller":"traceutil/trace.go:171","msg":"trace[1801783470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"421.968289ms","start":"2026-01-30T18:14:08.604477Z","end":"2026-01-30T18:14:09.026445Z","steps":["trace[1801783470] 'agreement among raft nodes before linearized reading' (duration: 421.801146ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:09.026591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:08.604464Z","time spent":"422.117322ms","remote":"127.0.0.1:43262","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-01-30T18:14:09.026912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.785782ms","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-01-30T18:14:09.02696Z","caller":"traceutil/trace.go:171","msg":"trace[603070241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"362.863504ms","start":"2026-01-30T18:14:08.664087Z","end":"2026-01-30T18:14:09.02695Z","steps":["trace[603070241] 'agreement among raft nodes before linearized reading' (duration: 362.669299ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:09.026987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:08.664073Z","time spent":"362.907945ms","remote":"127.0.0.1:43536","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-01-30T18:14:09.027381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.817565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:14:09.027407Z","caller":"traceutil/trace.go:171","msg":"trace[2010609191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"233.874467ms","start":"2026-01-30T18:14:08.793525Z","end":"2026-01-30T18:14:09.027399Z","steps":["trace[2010609191] 'agreement among raft nodes before linearized reading' (duration: 233.835196ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:09.292659Z","caller":"traceutil/trace.go:171","msg":"trace[1540063086] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"152.185805ms","start":"2026-01-30T18:14:09.140452Z","end":"2026-01-30T18:14:09.292637Z","steps":["trace[1540063086] 'read index received' (duration: 64.766939ms)","trace[1540063086] 'applied index is now lower than readState.Index' (duration: 87.417926ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:09.292863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.403421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-30T18:14:09.292893Z","caller":"traceutil/trace.go:171","msg":"trace[1960328097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:803; }","duration":"152.477482ms","start":"2026-01-30T18:14:09.140407Z","end":"2026-01-30T18:14:09.292884Z","steps":["trace[1960328097] 'agreement among raft nodes before linearized reading' (duration: 152.329489ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:09.293141Z","caller":"traceutil/trace.go:171","msg":"trace[2090923206] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"259.414227ms","start":"2026-01-30T18:14:09.033715Z","end":"2026-01-30T18:14:09.29313Z","steps":["trace[2090923206] 'process raft request' (duration: 171.575401ms)","trace[2090923206] 'compare' (duration: 87.166991ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:14:20.256271Z","caller":"traceutil/trace.go:171","msg":"trace[1780394926] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"112.67249ms","start":"2026-01-30T18:14:20.14358Z","end":"2026-01-30T18:14:20.256253Z","steps":["trace[1780394926] 'process raft request' (duration: 112.560877ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:21.461158Z","caller":"traceutil/trace.go:171","msg":"trace[84421165] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"365.531822ms","start":"2026-01-30T18:14:21.095611Z","end":"2026-01-30T18:14:21.461143Z","steps":["trace[84421165] 'process raft request' (duration: 365.4424ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:21.46126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:21.09559Z","time spent":"365.617674ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:14:22.209744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.888507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T18:14:22.209827Z","caller":"traceutil/trace.go:171","msg":"trace[2111472507] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"782.778288ms","start":"2026-01-30T18:14:21.427039Z","end":"2026-01-30T18:14:22.209817Z","steps":["trace[2111472507] 'read index received' (duration: 34.123358ms)","trace[2111472507] 'applied index is now lower than readState.Index' (duration: 748.65421ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:14:22.209933Z","caller":"traceutil/trace.go:171","msg":"trace[980608272] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"1.113816544s","start":"2026-01-30T18:14:21.09611Z","end":"2026-01-30T18:14:22.209927Z","steps":["trace[980608272] 'process raft request' (duration: 790.683812ms)","trace[980608272] 'compare' (duration: 322.778334ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:22.209965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:21.096103Z","time spent":"1.113844774s","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:14:22.214764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.709746ms","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-01-30T18:14:22.214814Z","caller":"traceutil/trace.go:171","msg":"trace[79432846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:887; }","duration":"787.767057ms","start":"2026-01-30T18:14:21.427034Z","end":"2026-01-30T18:14:22.214801Z","steps":["trace[79432846] 'agreement among raft nodes before linearized reading' (duration: 783.013483ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:22.214838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:21.426963Z","time spent":"787.870869ms","remote":"127.0.0.1:43536","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-01-30T18:14:22.448589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.603831ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-30T18:14:22.448714Z","caller":"traceutil/trace.go:171","msg":"trace[1972405986] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"238.865807ms","start":"2026-01-30T18:14:22.209839Z","end":"2026-01-30T18:14:22.448704Z","steps":["trace[1972405986] 'read index received' (duration: 5.112012ms)","trace[1972405986] 'applied index is now lower than readState.Index' (duration: 233.753125ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:22.448934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.868705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T18:14:22.448956Z","caller":"traceutil/trace.go:171","msg":"trace[930031749] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:887; }","duration":"581.914465ms","start":"2026-01-30T18:14:21.867035Z","end":"2026-01-30T18:14:22.44895Z","steps":["trace[930031749] 'agreement among raft nodes before linearized reading' (duration: 581.857164ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:22.448972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:21.867023Z","time spent":"581.945096ms","remote":"127.0.0.1:43458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-01-30T18:14:22.449072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.829911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:14:22.449084Z","caller":"traceutil/trace.go:171","msg":"trace[1140326748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"658.906383ms","start":"2026-01-30T18:14:21.790174Z","end":"2026-01-30T18:14:22.44908Z","steps":["trace[1140326748] 'agreement among raft nodes before linearized reading' (duration: 658.887633ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:22.449095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:21.790141Z","time spent":"658.950824ms","remote":"127.0.0.1:43262","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-01-30T18:14:22.449203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011805839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-30T18:14:22.449215Z","caller":"traceutil/trace.go:171","msg":"trace[1783681012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:887; }","duration":"1.011855369s","start":"2026-01-30T18:14:21.437356Z","end":"2026-01-30T18:14:22.449212Z","steps":["trace[1783681012] 'agreement among raft nodes before linearized reading' (duration: 1.011801528s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:22.449228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:21.437348Z","time spent":"1.01187571s","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-30T18:14:22.449533Z","caller":"traceutil/trace.go:171","msg":"trace[1353248841] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"226.769531ms","start":"2026-01-30T18:14:22.222757Z","end":"2026-01-30T18:14:22.449527Z","steps":["trace[1353248841] 'process raft request' (duration: 225.894582ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:22.588957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.12253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:14:22.589006Z","caller":"traceutil/trace.go:171","msg":"trace[2048949075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"139.188331ms","start":"2026-01-30T18:14:22.449806Z","end":"2026-01-30T18:14:22.588994Z","steps":["trace[2048949075] 'range keys from in-memory index tree' (duration: 139.09802ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:22.589163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.130866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-30T18:14:22.589208Z","caller":"traceutil/trace.go:171","msg":"trace[465942385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:888; }","duration":"330.209299ms","start":"2026-01-30T18:14:22.258986Z","end":"2026-01-30T18:14:22.589196Z","steps":["trace[465942385] 'agreement among raft nodes before linearized reading' (duration: 190.530277ms)","trace[465942385] 'range keys from in-memory index tree' (duration: 139.561649ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:22.589231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:22.258974Z","time spent":"330.252449ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-30T18:14:22.589383Z","caller":"traceutil/trace.go:171","msg":"trace[1565157196] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"136.054433ms","start":"2026-01-30T18:14:22.453322Z","end":"2026-01-30T18:14:22.589376Z","steps":["trace[1565157196] 'process raft request' (duration: 130.43898ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:24.854847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.950935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-30T18:14:24.854926Z","caller":"traceutil/trace.go:171","msg":"trace[1752355558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"175.062798ms","start":"2026-01-30T18:14:24.679844Z","end":"2026-01-30T18:14:24.854906Z","steps":["trace[1752355558] 'range keys from in-memory index tree' (duration: 174.778782ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:25.028634Z","caller":"traceutil/trace.go:171","msg":"trace[761207126] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"139.683222ms","start":"2026-01-30T18:14:24.888933Z","end":"2026-01-30T18:14:25.028616Z","steps":["trace[761207126] 'read index received' (duration: 139.554069ms)","trace[761207126] 'applied index is now lower than readState.Index' (duration: 128.163µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:25.028763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.814124ms","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-01-30T18:14:25.028785Z","caller":"traceutil/trace.go:171","msg":"trace[1662931240] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:896; }","duration":"139.887746ms","start":"2026-01-30T18:14:24.88889Z","end":"2026-01-30T18:14:25.028778Z","steps":["trace[1662931240] 'agreement among raft nodes before linearized reading' (duration: 139.793964ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:25.028828Z","caller":"traceutil/trace.go:171","msg":"trace[874508527] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"171.08569ms","start":"2026-01-30T18:14:24.857723Z","end":"2026-01-30T18:14:25.028809Z","steps":["trace[874508527] 'process raft request' (duration: 170.779433ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:26.58826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.495009ms","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-01-30T18:14:26.588311Z","caller":"traceutil/trace.go:171","msg":"trace[1342816309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:899; }","duration":"125.605452ms","start":"2026-01-30T18:14:26.462695Z","end":"2026-01-30T18:14:26.5883Z","steps":["trace[1342816309] 'range keys from in-memory index tree' (duration: 125.396418ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:26.588311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.787449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-30T18:14:26.588337Z","caller":"traceutil/trace.go:171","msg":"trace[882309622] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:899; }","duration":"116.82273ms","start":"2026-01-30T18:14:26.471508Z","end":"2026-01-30T18:14:26.58833Z","steps":["trace[882309622] 'range keys from in-memory index tree' (duration: 116.731059ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:26.909414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.827408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T18:14:26.909526Z","caller":"traceutil/trace.go:171","msg":"trace[1949810829] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"158.155506ms","start":"2026-01-30T18:14:26.751359Z","end":"2026-01-30T18:14:26.909515Z","steps":["trace[1949810829] 'process raft request' (duration: 158.107025ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:26.909689Z","caller":"traceutil/trace.go:171","msg":"trace[1248283612] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"318.268037ms","start":"2026-01-30T18:14:26.591414Z","end":"2026-01-30T18:14:26.909682Z","steps":["trace[1248283612] 'process raft request' (duration: 202.117812ms)","trace[1248283612] 'compare' (duration: 115.602193ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:26.909715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:26.591404Z","time spent":"318.295948ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:14:26.909773Z","caller":"traceutil/trace.go:171","msg":"trace[1649051910] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:932; }","duration":"295.300633ms","start":"2026-01-30T18:14:26.614468Z","end":"2026-01-30T18:14:26.909768Z","steps":["trace[1649051910] 'read index received' (duration: 136.184355ms)","trace[1649051910] 'applied index is now lower than readState.Index' (duration: 159.115698ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:26.909881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.413965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-30T18:14:26.909894Z","caller":"traceutil/trace.go:171","msg":"trace[1249712036] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"295.460866ms","start":"2026-01-30T18:14:26.614429Z","end":"2026-01-30T18:14:26.90989Z","steps":["trace[1249712036] 'agreement among raft nodes before linearized reading' (duration: 295.401324ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:26.909962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.63207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:14:26.909973Z","caller":"traceutil/trace.go:171","msg":"trace[188872968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"113.666201ms","start":"2026-01-30T18:14:26.796303Z","end":"2026-01-30T18:14:26.90997Z","steps":["trace[188872968] 'agreement among raft nodes before linearized reading' (duration: 113.647141ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:33.671031Z","caller":"traceutil/trace.go:171","msg":"trace[993876481] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"258.523828ms","start":"2026-01-30T18:14:33.412492Z","end":"2026-01-30T18:14:33.671016Z","steps":["trace[993876481] 'process raft request' (duration: 258.285473ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:34.481373Z","caller":"traceutil/trace.go:171","msg":"trace[1319629923] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"132.065414ms","start":"2026-01-30T18:14:34.349292Z","end":"2026-01-30T18:14:34.481357Z","steps":["trace[1319629923] 'process raft request' (duration: 131.978072ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:36.683763Z","caller":"traceutil/trace.go:171","msg":"trace[409435622] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"211.951096ms","start":"2026-01-30T18:14:36.471797Z","end":"2026-01-30T18:14:36.683748Z","steps":["trace[409435622] 'read index received' (duration: 211.850864ms)","trace[409435622] 'applied index is now lower than readState.Index' (duration: 99.582µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:14:36.683836Z","caller":"traceutil/trace.go:171","msg":"trace[66487605] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"316.211861ms","start":"2026-01-30T18:14:36.367619Z","end":"2026-01-30T18:14:36.683831Z","steps":["trace[66487605] 'process raft request' (duration: 316.031427ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:36.683898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:36.3676Z","time spent":"316.249732ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:14:36.684029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.230082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-30T18:14:36.684044Z","caller":"traceutil/trace.go:171","msg":"trace[769521385] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:926; }","duration":"212.269842ms","start":"2026-01-30T18:14:36.47177Z","end":"2026-01-30T18:14:36.68404Z","steps":["trace[769521385] 'agreement among raft nodes before linearized reading' (duration: 212.202841ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:36.684219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.038735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-30T18:14:36.68426Z","caller":"traceutil/trace.go:171","msg":"trace[553164920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"200.097196ms","start":"2026-01-30T18:14:36.484152Z","end":"2026-01-30T18:14:36.684249Z","steps":["trace[553164920] 'agreement among raft nodes before linearized reading' (duration: 199.979343ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:37.056579Z","caller":"traceutil/trace.go:171","msg":"trace[1576119384] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"115.570473ms","start":"2026-01-30T18:14:36.940985Z","end":"2026-01-30T18:14:37.056556Z","steps":["trace[1576119384] 'process raft request' (duration: 115.236786ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:45.248165Z","caller":"traceutil/trace.go:171","msg":"trace[1742220859] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"106.864422ms","start":"2026-01-30T18:14:45.141278Z","end":"2026-01-30T18:14:45.248143Z","steps":["trace[1742220859] 'process raft request' (duration: 106.367601ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:46.742281Z","caller":"traceutil/trace.go:171","msg":"trace[2083708708] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"193.45773ms","start":"2026-01-30T18:14:46.548807Z","end":"2026-01-30T18:14:46.742265Z","steps":["trace[2083708708] 'process raft request' (duration: 189.728348ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:52.304091Z","caller":"traceutil/trace.go:171","msg":"trace[1210449488] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"469.465255ms","start":"2026-01-30T18:14:51.834601Z","end":"2026-01-30T18:14:52.304066Z","steps":["trace[1210449488] 'process raft request' (duration: 469.323352ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:52.304305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:51.834588Z","time spent":"469.623909ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:14:53.263965Z","caller":"traceutil/trace.go:171","msg":"trace[631931677] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"379.220686ms","start":"2026-01-30T18:14:52.884729Z","end":"2026-01-30T18:14:53.263949Z","steps":["trace[631931677] 'read index received' (duration: 343.001399ms)","trace[631931677] 'applied index is now lower than readState.Index' (duration: 36.218757ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:14:53.264188Z","caller":"traceutil/trace.go:171","msg":"trace[520628710] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"411.085048ms","start":"2026-01-30T18:14:52.85309Z","end":"2026-01-30T18:14:53.264176Z","steps":["trace[520628710] 'process raft request' (duration: 374.473342ms)","trace[520628710] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:415; } (duration: 36.308389ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:53.264251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:52.853079Z","time spent":"411.127898ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:14:53.950424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.826277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-30T18:14:53.950492Z","caller":"traceutil/trace.go:171","msg":"trace[203496316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1013; }","duration":"820.95834ms","start":"2026-01-30T18:14:53.129515Z","end":"2026-01-30T18:14:53.950474Z","steps":["trace[203496316] 'agreement among raft nodes before linearized reading' (duration: 134.647076ms)","trace[203496316] 'range keys from in-memory index tree' (duration: 686.095479ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:53.950527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:53.129493Z","time spent":"821.027671ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T18:14:53.950802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066073201s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-01-30T18:14:53.95106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.416004ms","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-01-30T18:14:53.951084Z","caller":"traceutil/trace.go:171","msg":"trace[70998706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"675.462235ms","start":"2026-01-30T18:14:53.275615Z","end":"2026-01-30T18:14:53.951077Z","steps":["trace[70998706] 'range keys from in-memory index tree' (duration: 675.345832ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:53.951106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:53.275604Z","time spent":"675.497095ms","remote":"127.0.0.1:43536","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-01-30T18:14:53.951265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.815527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-30T18:14:53.951285Z","caller":"traceutil/trace.go:171","msg":"trace[691002013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"575.866258ms","start":"2026-01-30T18:14:53.375412Z","end":"2026-01-30T18:14:53.951278Z","steps":["trace[691002013] 'range keys from in-memory index tree' (duration: 575.736435ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:53.951305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:53.375391Z","time spent":"575.906528ms","remote":"127.0.0.1:43536","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-01-30T18:14:53.95143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.162309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:14:53.968632Z","caller":"traceutil/trace.go:171","msg":"trace[1079342943] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1013; }","duration":"1.066125983s","start":"2026-01-30T18:14:52.884689Z","end":"2026-01-30T18:14:53.950815Z","steps":["trace[1079342943] 'agreement among raft nodes before linearized reading' (duration: 379.690566ms)","trace[1079342943] 'count revisions from in-memory index tree' (duration: 686.404956ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:53.9687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:52.88467Z","time spent":"1.084012707s","remote":"127.0.0.1:43732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-01-30T18:14:53.971368Z","caller":"traceutil/trace.go:171","msg":"trace[1805677530] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1013; }","duration":"480.20699ms","start":"2026-01-30T18:14:53.471237Z","end":"2026-01-30T18:14:53.951444Z","steps":["trace[1805677530] 'count revisions from in-memory index tree' (duration: 480.125818ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:53.971429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:53.471223Z","time spent":"500.190521ms","remote":"127.0.0.1:43562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-01-30T18:14:53.976996Z","caller":"traceutil/trace.go:171","msg":"trace[600567353] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"269.014188ms","start":"2026-01-30T18:14:53.707934Z","end":"2026-01-30T18:14:53.976948Z","steps":["trace[600567353] 'process raft request' (duration: 168.131295ms)","trace[600567353] 'compare' (duration: 74.135014ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:53.967974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.592217ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:14:54.06429Z","caller":"traceutil/trace.go:171","msg":"trace[410538824] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1013; }","duration":"265.894457ms","start":"2026-01-30T18:14:53.798367Z","end":"2026-01-30T18:14:54.064262Z","steps":["trace[410538824] 'range keys from in-memory index tree' (duration: 169.581207ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:54.26358Z","caller":"traceutil/trace.go:171","msg":"trace[384077758] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"280.026261ms","start":"2026-01-30T18:14:53.983526Z","end":"2026-01-30T18:14:54.263553Z","steps":["trace[384077758] 'process raft request' (duration: 226.671695ms)","trace[384077758] 'compare' (duration: 52.002246ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:14:54.263626Z","caller":"traceutil/trace.go:171","msg":"trace[507686006] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"265.701395ms","start":"2026-01-30T18:14:53.997885Z","end":"2026-01-30T18:14:54.263586Z","steps":["trace[507686006] 'process raft request' (duration: 265.256464ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:54.263743Z","caller":"traceutil/trace.go:171","msg":"trace[2050924149] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"157.955522ms","start":"2026-01-30T18:14:54.105778Z","end":"2026-01-30T18:14:54.263733Z","steps":["trace[2050924149] 'process raft request' (duration: 157.393309ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:54.264074Z","caller":"traceutil/trace.go:171","msg":"trace[1815092971] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"266.614865ms","start":"2026-01-30T18:14:53.997447Z","end":"2026-01-30T18:14:54.264061Z","steps":["trace[1815092971] 'process raft request' (duration: 265.564231ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:54.264229Z","caller":"traceutil/trace.go:171","msg":"trace[1884833859] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"266.38273ms","start":"2026-01-30T18:14:53.997836Z","end":"2026-01-30T18:14:54.264219Z","steps":["trace[1884833859] 'process raft request' (duration: 265.265344ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:56.407254Z","caller":"traceutil/trace.go:171","msg":"trace[1564298813] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"132.520111ms","start":"2026-01-30T18:14:56.274719Z","end":"2026-01-30T18:14:56.407239Z","steps":["trace[1564298813] 'read index received' (duration: 132.346247ms)","trace[1564298813] 'applied index is now lower than readState.Index' (duration: 172.904µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:14:56.407379Z","caller":"traceutil/trace.go:171","msg":"trace[1860270014] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"132.748246ms","start":"2026-01-30T18:14:56.274622Z","end":"2026-01-30T18:14:56.40737Z","steps":["trace[1860270014] 'process raft request' (duration: 132.45584ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:56.407648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.91779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-30T18:14:56.407693Z","caller":"traceutil/trace.go:171","msg":"trace[357278736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1043; }","duration":"133.019512ms","start":"2026-01-30T18:14:56.27466Z","end":"2026-01-30T18:14:56.40768Z","steps":["trace[357278736] 'agreement among raft nodes before linearized reading' (duration: 132.94098ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:14:56.874518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:14:56.567283Z","time spent":"307.223311ms","remote":"127.0.0.1:43286","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-30T18:14:57.075845Z","caller":"traceutil/trace.go:171","msg":"trace[412246618] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"171.894458ms","start":"2026-01-30T18:14:56.903914Z","end":"2026-01-30T18:14:57.075808Z","steps":["trace[412246618] 'read index received' (duration: 162.297376ms)","trace[412246618] 'applied index is now lower than readState.Index' (duration: 9.595601ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:14:57.076677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.676475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:14:57.076751Z","caller":"traceutil/trace.go:171","msg":"trace[1506912130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"172.819479ms","start":"2026-01-30T18:14:56.903892Z","end":"2026-01-30T18:14:57.076711Z","steps":["trace[1506912130] 'agreement among raft nodes before linearized reading' (duration: 172.519533ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:57.076344Z","caller":"traceutil/trace.go:171","msg":"trace[714491407] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"172.661065ms","start":"2026-01-30T18:14:56.903655Z","end":"2026-01-30T18:14:57.076316Z","steps":["trace[714491407] 'process raft request' (duration: 162.544112ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:14:57.155843Z","caller":"traceutil/trace.go:171","msg":"trace[298071600] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"169.604987ms","start":"2026-01-30T18:14:56.986156Z","end":"2026-01-30T18:14:57.155761Z","steps":["trace[298071600] 'process raft request' (duration: 169.157658ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:17:16.345598Z","caller":"traceutil/trace.go:171","msg":"trace[1296570326] linearizableReadLoop","detail":"{readStateIndex:1682; appliedIndex:1681; }","duration":"112.470203ms","start":"2026-01-30T18:17:16.233097Z","end":"2026-01-30T18:17:16.345567Z","steps":["trace[1296570326] 'read index received' (duration: 91.640056ms)","trace[1296570326] 'applied index is now lower than readState.Index' (duration: 20.828917ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:17:16.345769Z","caller":"traceutil/trace.go:171","msg":"trace[1844274709] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"196.783068ms","start":"2026-01-30T18:17:16.148957Z","end":"2026-01-30T18:17:16.34574Z","steps":["trace[1844274709] 'process raft request' (duration: 175.833158ms)","trace[1844274709] 'compare' (duration: 20.642493ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:17:16.350934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.823672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-01-30T18:17:16.350962Z","caller":"traceutil/trace.go:171","msg":"trace[687305039] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1615; }","duration":"117.930544ms","start":"2026-01-30T18:17:16.233021Z","end":"2026-01-30T18:17:16.350952Z","steps":["trace[687305039] 'agreement among raft nodes before linearized reading' (duration: 117.75837ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:18:32.283634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.100997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T18:18:32.284113Z","caller":"traceutil/trace.go:171","msg":"trace[1039418710] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"396.910385ms","start":"2026-01-30T18:18:31.887163Z","end":"2026-01-30T18:18:32.284074Z","steps":["trace[1039418710] 'process raft request' (duration: 118.816707ms)","trace[1039418710] 'compare' (duration: 276.337798ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:18:32.285553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:18:31.887141Z","time spent":"397.226291ms","remote":"127.0.0.1:43536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:18:32.296088Z","caller":"traceutil/trace.go:171","msg":"trace[1219535493] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2037; }","duration":"172.038985ms","start":"2026-01-30T18:18:32.124018Z","end":"2026-01-30T18:18:32.296057Z","steps":["trace[1219535493] 'read index received' (duration: 156.998402ms)","trace[1219535493] 'applied index is now lower than readState.Index' (duration: 15.039103ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:18:32.296332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.294271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-30T18:18:32.296368Z","caller":"traceutil/trace.go:171","msg":"trace[1373382402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1955; }","duration":"172.394414ms","start":"2026-01-30T18:18:32.123965Z","end":"2026-01-30T18:18:32.29636Z","steps":["trace[1373382402] 'agreement among raft nodes before linearized reading' (duration: 172.202459ms)"],"step_count":1}