{"level":"warn","ts":"2026-10-26T00:55:59.010869Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T00:55:59.010966Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.152: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.152:2380","--initial-cluster=instance=https://162.253.55.152:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.152:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.152: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-10-26T00:55:59.011035Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T00:55:59.011045Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.152:2380"]} {"level":"info","ts":"2026-10-26T00:55:59.01107Z","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-10-26T00:55:59.012013Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.152:2379"]} {"level":"info","ts":"2026-10-26T00:55:59.012111Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.152:2380"],"listen-peer-urls":["https://162.253.55.152:2380"],"advertise-client-urls":["https://162.253.55.152:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.152:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.152: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-10-26T00:55:59.018432Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.056207ms"} {"level":"info","ts":"2026-10-26T00:55:59.025326Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3211802a8f385ce6","cluster-id":"25d088d9a9519f25"} {"level":"info","ts":"2026-10-26T00:55:59.025393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T00:55:59.025414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 became follower at term 0"} {"level":"info","ts":"2026-10-26T00:55:59.025429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3211802a8f385ce6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T00:55:59.025443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 became follower at term 1"} {"level":"info","ts":"2026-10-26T00:55:59.025491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 switched to configuration voters=(3607805696780295398)"} {"level":"warn","ts":"2026-10-26T00:55:59.034918Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T00:55:59.040012Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T00:55:59.041681Z","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-10-26T00:55:59.044158Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3211802a8f385ce6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T00:55:59.044288Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3211802a8f385ce6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T00:55:59.044518Z","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-10-26T00:55:59.044604Z","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-10-26T00:55:59.044613Z","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-10-26T00:55:59.047357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 switched to configuration voters=(3607805696780295398)"} {"level":"info","ts":"2026-10-26T00:55:59.047487Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25d088d9a9519f25","local-member-id":"3211802a8f385ce6","added-peer-id":"3211802a8f385ce6","added-peer-peer-urls":["https://162.253.55.152:2380"]} {"level":"info","ts":"2026-10-26T00:55:59.048555Z","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-10-26T00:55:59.048721Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.152:2380"} {"level":"info","ts":"2026-10-26T00:55:59.048778Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T00:55:59.048795Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.152:2380"} {"level":"info","ts":"2026-10-26T00:55:59.04874Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3211802a8f385ce6","initial-advertise-peer-urls":["https://162.253.55.152:2380"],"listen-peer-urls":["https://162.253.55.152:2380"],"advertise-client-urls":["https://162.253.55.152:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.152:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T00:55:59.826729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T00:55:59.826789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T00:55:59.826831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 received MsgPreVoteResp from 3211802a8f385ce6 at term 1"} {"level":"info","ts":"2026-10-26T00:55:59.826853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 became candidate at term 2"} {"level":"info","ts":"2026-10-26T00:55:59.826864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 received MsgVoteResp from 3211802a8f385ce6 at term 2"} {"level":"info","ts":"2026-10-26T00:55:59.826879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 became leader at term 2"} {"level":"info","ts":"2026-10-26T00:55:59.826897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3211802a8f385ce6 elected leader 3211802a8f385ce6 at term 2"} {"level":"info","ts":"2026-10-26T00:55:59.828022Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3211802a8f385ce6","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.152:2379]}","request-path":"/0/members/3211802a8f385ce6/attributes","cluster-id":"25d088d9a9519f25","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T00:55:59.828209Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T00:55:59.828346Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T00:55:59.828693Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:55:59.831333Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.152:2379"} {"level":"info","ts":"2026-10-26T00:55:59.832139Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T00:55:59.832201Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T00:55:59.832509Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T00:55:59.83252Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25d088d9a9519f25","local-member-id":"3211802a8f385ce6","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:55:59.832647Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:55:59.840293Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:56:17.714867Z","caller":"traceutil/trace.go:171","msg":"trace[923581044] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"158.78292ms","start":"2026-10-26T00:56:17.556057Z","end":"2026-10-26T00:56:17.71484Z","steps":["trace[923581044] 'process raft request' (duration: 61.608053ms)","trace[923581044] 'compare' (duration: 97.021073ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:18.000513Z","caller":"traceutil/trace.go:171","msg":"trace[628772853] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"266.953827ms","start":"2026-10-26T00:56:17.733536Z","end":"2026-10-26T00:56:18.00049Z","steps":["trace[628772853] 'process raft request' (duration: 266.803263ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:18.994699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.982908ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-26T00:56:19.671039Z","caller":"traceutil/trace.go:171","msg":"trace[1273342876] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"148.854175ms","start":"2026-10-26T00:56:19.522161Z","end":"2026-10-26T00:56:19.671016Z","steps":["trace[1273342876] 'process raft request' (duration: 148.725143ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:19.671074Z","caller":"traceutil/trace.go:171","msg":"trace[1199970758] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:438; }","duration":"113.455075ms","start":"2026-10-26T00:56:19.557577Z","end":"2026-10-26T00:56:19.671032Z","steps":["trace[1199970758] 'read index received' (duration: 113.444745ms)","trace[1199970758] 'applied index is now lower than readState.Index' (duration: 7.66µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:56:19.671225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.630829ms","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-10-26T00:56:19.671396Z","caller":"traceutil/trace.go:171","msg":"trace[1965437691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"113.804603ms","start":"2026-10-26T00:56:19.55754Z","end":"2026-10-26T00:56:19.671344Z","steps":["trace[1965437691] 'agreement among raft nodes before linearized reading' (duration: 113.593238ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:48.021893Z","caller":"traceutil/trace.go:171","msg":"trace[2111040878] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"416.460859ms","start":"2026-10-26T00:56:47.605376Z","end":"2026-10-26T00:56:48.021837Z","steps":["trace[2111040878] 'process raft request' (duration: 416.284135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:48.02427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:47.605351Z","time spent":"416.715134ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:56:48.030982Z","caller":"traceutil/trace.go:171","msg":"trace[783720116] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:572; }","duration":"131.419491ms","start":"2026-10-26T00:56:47.899536Z","end":"2026-10-26T00:56:48.030955Z","steps":["trace[783720116] 'read index received' (duration: 122.269245ms)","trace[783720116] 'applied index is now lower than readState.Index' (duration: 9.149236ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:48.031225Z","caller":"traceutil/trace.go:171","msg":"trace[170675044] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"378.391079ms","start":"2026-10-26T00:56:47.652822Z","end":"2026-10-26T00:56:48.031213Z","steps":["trace[170675044] 'process raft request' (duration: 377.810145ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:48.031212Z","caller":"traceutil/trace.go:171","msg":"trace[1094940645] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"393.537051ms","start":"2026-10-26T00:56:47.637646Z","end":"2026-10-26T00:56:48.031183Z","steps":["trace[1094940645] 'process raft request' (duration: 392.945207ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:48.031351Z","caller":"traceutil/trace.go:171","msg":"trace[2061091771] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"374.172283ms","start":"2026-10-26T00:56:47.657169Z","end":"2026-10-26T00:56:48.031342Z","steps":["trace[2061091771] 'process raft request' (duration: 373.536738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:48.031391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:47.657156Z","time spent":"374.219274ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:56:48.031497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:47.637636Z","time spent":"393.723085ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:56:48.031646Z","caller":"traceutil/trace.go:171","msg":"trace[1367792734] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"426.129578ms","start":"2026-10-26T00:56:47.605505Z","end":"2026-10-26T00:56:48.031634Z","steps":["trace[1367792734] 'process raft request' (duration: 424.757637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:48.031734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:47.605479Z","time spent":"426.22865ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:56:48.031864Z","caller":"traceutil/trace.go:171","msg":"trace[1727254166] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"405.261235ms","start":"2026-10-26T00:56:47.626591Z","end":"2026-10-26T00:56:48.031853Z","steps":["trace[1727254166] 'process raft request' (duration: 403.855333ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:48.031881Z","caller":"traceutil/trace.go:171","msg":"trace[1944278900] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"397.855437ms","start":"2026-10-26T00:56:47.634015Z","end":"2026-10-26T00:56:48.03187Z","steps":["trace[1944278900] 'process raft request' (duration: 396.500587ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:48.031911Z","caller":"traceutil/trace.go:171","msg":"trace[920825649] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"395.916414ms","start":"2026-10-26T00:56:47.635988Z","end":"2026-10-26T00:56:48.031904Z","steps":["trace[920825649] 'process raft request' (duration: 394.566243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:48.032618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:47.633997Z","time spent":"398.543303ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:56:48.032647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:47.635975Z","time spent":"396.636291ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:56:48.031317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:47.652796Z","time spent":"378.46142ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:56:48.031964Z","caller":"traceutil/trace.go:171","msg":"trace[542129755] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"405.447789ms","start":"2026-10-26T00:56:47.626509Z","end":"2026-10-26T00:56:48.031956Z","steps":["trace[542129755] 'process raft request' (duration: 403.899804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:48.032915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:47.626488Z","time spent":"406.400382ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:56:48.031985Z","caller":"traceutil/trace.go:171","msg":"trace[1872970263] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"344.369958ms","start":"2026-10-26T00:56:47.687584Z","end":"2026-10-26T00:56:48.031954Z","steps":["trace[1872970263] 'process raft request' (duration: 343.179301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:48.03313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:47.687565Z","time spent":"345.523705ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:56:48.032026Z","caller":"traceutil/trace.go:171","msg":"trace[23142083] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"423.408096ms","start":"2026-10-26T00:56:47.608563Z","end":"2026-10-26T00:56:48.031971Z","steps":["trace[23142083] 'process raft request' (duration: 421.797079ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:48.033418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:47.608542Z","time spent":"424.833928ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:56:48.032123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.587738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T00:56:48.033672Z","caller":"traceutil/trace.go:171","msg":"trace[1424729984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:562; }","duration":"134.182324ms","start":"2026-10-26T00:56:47.899475Z","end":"2026-10-26T00:56:48.033657Z","steps":["trace[1424729984] 'agreement among raft nodes before linearized reading' (duration: 132.513837ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:48.032166Z","caller":"traceutil/trace.go:171","msg":"trace[1241718841] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"240.567941ms","start":"2026-10-26T00:56:47.791586Z","end":"2026-10-26T00:56:48.032154Z","steps":["trace[1241718841] 'process raft request' (duration: 239.253381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:48.034072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:47.626585Z","time spent":"405.866248ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:56:49.275177Z","caller":"traceutil/trace.go:171","msg":"trace[380036358] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"106.196492ms","start":"2026-10-26T00:56:49.168958Z","end":"2026-10-26T00:56:49.275154Z","steps":["trace[380036358] 'process raft request' (duration: 104.948304ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:49.300886Z","caller":"traceutil/trace.go:171","msg":"trace[1802909029] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"105.556307ms","start":"2026-10-26T00:56:49.195307Z","end":"2026-10-26T00:56:49.300863Z","steps":["trace[1802909029] 'process raft request' (duration: 89.609167ms)","trace[1802909029] 'compare' (duration: 15.229344ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:50.180989Z","caller":"traceutil/trace.go:171","msg":"trace[603436622] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"100.048364ms","start":"2026-10-26T00:56:50.080921Z","end":"2026-10-26T00:56:50.180969Z","steps":["trace[603436622] 'process raft request' (duration: 99.913261ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:50.295881Z","caller":"traceutil/trace.go:171","msg":"trace[554461544] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"212.466025ms","start":"2026-10-26T00:56:50.083389Z","end":"2026-10-26T00:56:50.295855Z","steps":["trace[554461544] 'process raft request' (duration: 202.98051ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:50.296005Z","caller":"traceutil/trace.go:171","msg":"trace[2112410487] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:656; }","duration":"126.264226ms","start":"2026-10-26T00:56:50.169717Z","end":"2026-10-26T00:56:50.295981Z","steps":["trace[2112410487] 'read index received' (duration: 11.151542ms)","trace[2112410487] 'applied index is now lower than readState.Index' (duration: 115.111574ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:50.296041Z","caller":"traceutil/trace.go:171","msg":"trace[1838919030] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"211.650176ms","start":"2026-10-26T00:56:50.08436Z","end":"2026-10-26T00:56:50.29601Z","steps":["trace[1838919030] 'process raft request' (duration: 211.456582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:50.296283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.542571ms","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-10-26T00:56:50.296373Z","caller":"traceutil/trace.go:171","msg":"trace[1220420936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"126.635463ms","start":"2026-10-26T00:56:50.169705Z","end":"2026-10-26T00:56:50.296341Z","steps":["trace[1220420936] 'agreement among raft nodes before linearized reading' (duration: 126.344257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:50.983444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.63795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:56:50.983543Z","caller":"traceutil/trace.go:171","msg":"trace[1292775100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"553.784344ms","start":"2026-10-26T00:56:50.429741Z","end":"2026-10-26T00:56:50.983525Z","steps":["trace[1292775100] 'range keys from in-memory index tree' (duration: 553.586809ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:50.983584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:50.429724Z","time spent":"553.851485ms","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T00:56:52.37297Z","caller":"traceutil/trace.go:171","msg":"trace[1128415046] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"188.872991ms","start":"2026-10-26T00:56:52.18403Z","end":"2026-10-26T00:56:52.372902Z","steps":["trace[1128415046] 'read index received' (duration: 180.418299ms)","trace[1128415046] 'applied index is now lower than readState.Index' (duration: 8.452521ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:52.373021Z","caller":"traceutil/trace.go:171","msg":"trace[320922907] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"198.672483ms","start":"2026-10-26T00:56:52.174318Z","end":"2026-10-26T00:56:52.37299Z","steps":["trace[320922907] 'process raft request' (duration: 196.285349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:52.373285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.218299ms","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-10-26T00:56:52.373398Z","caller":"traceutil/trace.go:171","msg":"trace[1778191782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"189.386203ms","start":"2026-10-26T00:56:52.183985Z","end":"2026-10-26T00:56:52.373371Z","steps":["trace[1778191782] 'agreement among raft nodes before linearized reading' (duration: 189.089466ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:54.810322Z","caller":"traceutil/trace.go:171","msg":"trace[250003442] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"134.621944ms","start":"2026-10-26T00:56:54.675678Z","end":"2026-10-26T00:56:54.8103Z","steps":["trace[250003442] 'process raft request' (duration: 133.734135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:55.736035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.839373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:56:55.736201Z","caller":"traceutil/trace.go:171","msg":"trace[721378737] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"250.654978ms","start":"2026-10-26T00:56:55.485528Z","end":"2026-10-26T00:56:55.736183Z","steps":["trace[721378737] 'process raft request' (duration: 33.605009ms)","trace[721378737] 'compare' (duration: 216.619699ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:56.358448Z","caller":"traceutil/trace.go:171","msg":"trace[394389692] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"191.620034ms","start":"2026-10-26T00:56:56.166802Z","end":"2026-10-26T00:56:56.358422Z","steps":["trace[394389692] 'process raft request' (duration: 191.047862ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:56.762307Z","caller":"traceutil/trace.go:171","msg":"trace[563120604] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"304.364713ms","start":"2026-10-26T00:56:56.457915Z","end":"2026-10-26T00:56:56.76228Z","steps":["trace[563120604] 'read index received' (duration: 304.22836ms)","trace[563120604] 'applied index is now lower than readState.Index' (duration: 135.593µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:56.762361Z","caller":"traceutil/trace.go:171","msg":"trace[1751713593] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"319.470135ms","start":"2026-10-26T00:56:56.442872Z","end":"2026-10-26T00:56:56.762342Z","steps":["trace[1751713593] 'process raft request' (duration: 319.289631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:56.762498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:56.442847Z","time spent":"319.559617ms","remote":"127.0.0.1:52580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:56:56.762627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.478781ms","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-10-26T00:56:56.762684Z","caller":"traceutil/trace.go:171","msg":"trace[367916012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:666; }","duration":"302.560283ms","start":"2026-10-26T00:56:56.460108Z","end":"2026-10-26T00:56:56.762668Z","steps":["trace[367916012] 'agreement among raft nodes before linearized reading' (duration: 302.377349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:56.762615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.681181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-26T00:56:56.762781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.792866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-26T00:56:56.762811Z","caller":"traceutil/trace.go:171","msg":"trace[1625670467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:666; }","duration":"301.822587ms","start":"2026-10-26T00:56:56.460981Z","end":"2026-10-26T00:56:56.762803Z","steps":["trace[1625670467] 'agreement among raft nodes before linearized reading' (duration: 301.748175ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:56.762833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:56.460956Z","time spent":"301.871977ms","remote":"127.0.0.1:52610","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-10-26T00:56:56.762857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.908441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T00:56:56.762903Z","caller":"traceutil/trace.go:171","msg":"trace[305050014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:666; }","duration":"304.842105ms","start":"2026-10-26T00:56:56.457898Z","end":"2026-10-26T00:56:56.762741Z","steps":["trace[305050014] 'agreement among raft nodes before linearized reading' (duration: 304.480756ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:56.762967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:56.457839Z","time spent":"305.11364ms","remote":"127.0.0.1:52610","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-10-26T00:56:56.762956Z","caller":"traceutil/trace.go:171","msg":"trace[1773103017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:666; }","duration":"284.105755ms","start":"2026-10-26T00:56:56.47883Z","end":"2026-10-26T00:56:56.762936Z","steps":["trace[1773103017] 'agreement among raft nodes before linearized reading' (duration: 283.700906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:56.762713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:56.460097Z","time spent":"302.609244ms","remote":"127.0.0.1:52610","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-10-26T00:56:57.001227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.744804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:56:57.001328Z","caller":"traceutil/trace.go:171","msg":"trace[1528978294] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"231.73183ms","start":"2026-10-26T00:56:56.769581Z","end":"2026-10-26T00:56:57.001313Z","steps":["trace[1528978294] 'read index received' (duration: 82.712711ms)","trace[1528978294] 'applied index is now lower than readState.Index' (duration: 149.017809ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:56:57.001394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.811662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-10-26T00:56:57.001425Z","caller":"traceutil/trace.go:171","msg":"trace[418283177] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:667; }","duration":"231.872844ms","start":"2026-10-26T00:56:56.769542Z","end":"2026-10-26T00:56:57.001415Z","steps":["trace[418283177] 'agreement among raft nodes before linearized reading' (duration: 231.811582ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:57.00138Z","caller":"traceutil/trace.go:171","msg":"trace[1859220119] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"233.371068ms","start":"2026-10-26T00:56:56.767978Z","end":"2026-10-26T00:56:57.001349Z","steps":["trace[1859220119] 'process raft request' (duration: 84.304007ms)","trace[1859220119] 'compare' (duration: 148.601921ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:57.03445Z","caller":"traceutil/trace.go:171","msg":"trace[1564815557] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"264.737657ms","start":"2026-10-26T00:56:56.769691Z","end":"2026-10-26T00:56:57.034429Z","steps":["trace[1564815557] 'process raft request' (duration: 264.574143ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:57.034502Z","caller":"traceutil/trace.go:171","msg":"trace[649481059] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"263.55437ms","start":"2026-10-26T00:56:56.770927Z","end":"2026-10-26T00:56:57.034482Z","steps":["trace[649481059] 'process raft request' (duration: 263.371946ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:57.034468Z","caller":"traceutil/trace.go:171","msg":"trace[1766274967] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"264.813798ms","start":"2026-10-26T00:56:56.769596Z","end":"2026-10-26T00:56:57.03441Z","steps":["trace[1766274967] 'process raft request' (duration: 264.557793ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:57.196196Z","caller":"traceutil/trace.go:171","msg":"trace[1318342604] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"133.016538ms","start":"2026-10-26T00:56:57.063157Z","end":"2026-10-26T00:56:57.196174Z","steps":["trace[1318342604] 'process raft request' (duration: 132.960107ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:57.19623Z","caller":"traceutil/trace.go:171","msg":"trace[1333736757] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"134.292277ms","start":"2026-10-26T00:56:57.061902Z","end":"2026-10-26T00:56:57.196194Z","steps":["trace[1333736757] 'process raft request' (duration: 134.179654ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:57.196712Z","caller":"traceutil/trace.go:171","msg":"trace[1866545047] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"135.183378ms","start":"2026-10-26T00:56:57.061506Z","end":"2026-10-26T00:56:57.19669Z","steps":["trace[1866545047] 'process raft request' (duration: 134.43965ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:58.510613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.751961ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-10-26T00:56:59.538891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6694215423052822432,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T00:56:59.888216Z","caller":"traceutil/trace.go:171","msg":"trace[1888473948] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"850.313329ms","start":"2026-10-26T00:56:59.037875Z","end":"2026-10-26T00:56:59.888188Z","steps":["trace[1888473948] 'read index received' (duration: 850.197267ms)","trace[1888473948] 'applied index is now lower than readState.Index' (duration: 114.712µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:59.888343Z","caller":"traceutil/trace.go:171","msg":"trace[237163535] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"879.152653ms","start":"2026-10-26T00:56:59.009163Z","end":"2026-10-26T00:56:59.888315Z","steps":["trace[237163535] 'process raft request' (duration: 878.818676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:59.888425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.576308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:56:59.888495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.339291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-26T00:56:59.888517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:59.009147Z","time spent":"879.256625ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:56:59.888539Z","caller":"traceutil/trace.go:171","msg":"trace[654146452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:676; }","duration":"850.401242ms","start":"2026-10-26T00:56:59.038124Z","end":"2026-10-26T00:56:59.888526Z","steps":["trace[654146452] 'agreement among raft nodes before linearized reading' (duration: 850.218588ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:59.888538Z","caller":"traceutil/trace.go:171","msg":"trace[320180995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"457.729442ms","start":"2026-10-26T00:56:59.430789Z","end":"2026-10-26T00:56:59.888519Z","steps":["trace[320180995] 'agreement among raft nodes before linearized reading' (duration: 457.572358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:59.888585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:59.430773Z","time spent":"457.799223ms","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T00:56:59.888585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:59.038118Z","time spent":"850.454783ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T00:56:59.888599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.415752ms","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-10-26T00:56:59.888645Z","caller":"traceutil/trace.go:171","msg":"trace[912430185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"850.473073ms","start":"2026-10-26T00:56:59.038158Z","end":"2026-10-26T00:56:59.888631Z","steps":["trace[912430185] 'agreement among raft nodes before linearized reading' (duration: 850.287629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:59.888681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:59.038154Z","time spent":"850.519424ms","remote":"127.0.0.1:52610","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-10-26T00:56:59.889131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.246582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T00:56:59.889183Z","caller":"traceutil/trace.go:171","msg":"trace[118925771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"851.319553ms","start":"2026-10-26T00:56:59.037851Z","end":"2026-10-26T00:56:59.88917Z","steps":["trace[118925771] 'agreement among raft nodes before linearized reading' (duration: 851.19629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:59.889209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:59.03784Z","time spent":"851.361984ms","remote":"127.0.0.1:52610","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-10-26T00:57:00.443153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.350474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:57:00.443249Z","caller":"traceutil/trace.go:171","msg":"trace[1737353787] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"301.415917ms","start":"2026-10-26T00:57:00.141813Z","end":"2026-10-26T00:57:00.443229Z","steps":["trace[1737353787] 'process raft request' (duration: 145.92658ms)","trace[1737353787] 'compare' (duration: 154.991036ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:57:00.443297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:00.141786Z","time spent":"301.491468ms","remote":"127.0.0.1:52436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:57:02.145795Z","caller":"traceutil/trace.go:171","msg":"trace[1223168220] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"149.242834ms","start":"2026-10-26T00:57:01.996533Z","end":"2026-10-26T00:57:02.145775Z","steps":["trace[1223168220] 'process raft request' (duration: 149.137912ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:02.286903Z","caller":"traceutil/trace.go:171","msg":"trace[947466692] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"256.651054ms","start":"2026-10-26T00:57:02.030231Z","end":"2026-10-26T00:57:02.286882Z","steps":["trace[947466692] 'process raft request' (duration: 256.593272ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:02.287183Z","caller":"traceutil/trace.go:171","msg":"trace[940665190] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"271.44903ms","start":"2026-10-26T00:57:02.01571Z","end":"2026-10-26T00:57:02.287159Z","steps":["trace[940665190] 'process raft request' (duration: 270.865687ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:02.287247Z","caller":"traceutil/trace.go:171","msg":"trace[1540678130] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"270.550078ms","start":"2026-10-26T00:57:02.016687Z","end":"2026-10-26T00:57:02.287238Z","steps":["trace[1540678130] 'process raft request' (duration: 270.099259ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:02.28717Z","caller":"traceutil/trace.go:171","msg":"trace[979209618] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"270.682841ms","start":"2026-10-26T00:57:02.016453Z","end":"2026-10-26T00:57:02.287136Z","steps":["trace[979209618] 'process raft request' (duration: 270.296143ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:02.846696Z","caller":"traceutil/trace.go:171","msg":"trace[85646276] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"129.812566ms","start":"2026-10-26T00:57:02.716861Z","end":"2026-10-26T00:57:02.846674Z","steps":["trace[85646276] 'process raft request' (duration: 129.691663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:03.704691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.462085ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-10-26T00:57:03.704801Z","caller":"traceutil/trace.go:171","msg":"trace[1387416945] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"273.605469ms","start":"2026-10-26T00:57:03.431179Z","end":"2026-10-26T00:57:03.704784Z","steps":["trace[1387416945] 'read index received' (duration: 129.95994ms)","trace[1387416945] 'applied index is now lower than readState.Index' (duration: 143.644169ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:57:03.704861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:57:03.704829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:03.112194Z","time spent":"592.628603ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-26T00:57:03.704883Z","caller":"traceutil/trace.go:171","msg":"trace[347229384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"273.738641ms","start":"2026-10-26T00:57:03.431136Z","end":"2026-10-26T00:57:03.704875Z","steps":["trace[347229384] 'agreement among raft nodes before linearized reading' (duration: 273.68843ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:04.272196Z","caller":"traceutil/trace.go:171","msg":"trace[732242851] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:731; }","duration":"122.790737ms","start":"2026-10-26T00:57:04.14937Z","end":"2026-10-26T00:57:04.272161Z","steps":["trace[732242851] 'read index received' (duration: 26.06035ms)","trace[732242851] 'applied index is now lower than readState.Index' (duration: 96.729307ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:04.272235Z","caller":"traceutil/trace.go:171","msg":"trace[384645024] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"127.35633ms","start":"2026-10-26T00:57:04.144845Z","end":"2026-10-26T00:57:04.272201Z","steps":["trace[384645024] 'process raft request' (duration: 87.340776ms)","trace[384645024] 'compare' (duration: 39.871491ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:57:04.272732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.341299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wkwmp\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-10-26T00:57:04.272832Z","caller":"traceutil/trace.go:171","msg":"trace[1169598973] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wkwmp; range_end:; response_count:1; response_revision:702; }","duration":"123.478874ms","start":"2026-10-26T00:57:04.149336Z","end":"2026-10-26T00:57:04.272815Z","steps":["trace[1169598973] 'agreement among raft nodes before linearized reading' (duration: 123.165766ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:04.477335Z","caller":"traceutil/trace.go:171","msg":"trace[1651749695] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"200.390864ms","start":"2026-10-26T00:57:04.276921Z","end":"2026-10-26T00:57:04.477312Z","steps":["trace[1651749695] 'process raft request' (duration: 123.884763ms)","trace[1651749695] 'compare' (duration: 76.391598ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:04.483941Z","caller":"traceutil/trace.go:171","msg":"trace[926637632] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:733; }","duration":"192.134755ms","start":"2026-10-26T00:57:04.291788Z","end":"2026-10-26T00:57:04.483923Z","steps":["trace[926637632] 'read index received' (duration: 109.103088ms)","trace[926637632] 'applied index is now lower than readState.Index' (duration: 83.030677ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:04.484066Z","caller":"traceutil/trace.go:171","msg":"trace[1585603123] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"193.361553ms","start":"2026-10-26T00:57:04.290694Z","end":"2026-10-26T00:57:04.484056Z","steps":["trace[1585603123] 'process raft request' (duration: 193.113218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:04.484338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.543845ms","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-10-26T00:57:04.484376Z","caller":"traceutil/trace.go:171","msg":"trace[782977310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:705; }","duration":"192.631697ms","start":"2026-10-26T00:57:04.291735Z","end":"2026-10-26T00:57:04.484366Z","steps":["trace[782977310] 'agreement among raft nodes before linearized reading' (duration: 192.496244ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:04.484444Z","caller":"traceutil/trace.go:171","msg":"trace[985224333] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"206.069441ms","start":"2026-10-26T00:57:04.278323Z","end":"2026-10-26T00:57:04.484392Z","steps":["trace[985224333] 'process raft request' (duration: 205.328204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:04.484636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.118477ms","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-10-26T00:57:04.484675Z","caller":"traceutil/trace.go:171","msg":"trace[680427747] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:705; }","duration":"101.177898ms","start":"2026-10-26T00:57:04.383487Z","end":"2026-10-26T00:57:04.484665Z","steps":["trace[680427747] 'agreement among raft nodes before linearized reading' (duration: 101.125117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:04.484792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.657455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-26T00:57:04.484588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.245738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T00:57:04.484836Z","caller":"traceutil/trace.go:171","msg":"trace[1807506117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:705; }","duration":"191.788908ms","start":"2026-10-26T00:57:04.293034Z","end":"2026-10-26T00:57:04.484823Z","steps":["trace[1807506117] 'agreement among raft nodes before linearized reading' (duration: 191.605554ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:04.484846Z","caller":"traceutil/trace.go:171","msg":"trace[2058638631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:705; }","duration":"192.535205ms","start":"2026-10-26T00:57:04.292297Z","end":"2026-10-26T00:57:04.484832Z","steps":["trace[2058638631] 'agreement among raft nodes before linearized reading' (duration: 192.131716ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:04.74586Z","caller":"traceutil/trace.go:171","msg":"trace[994653541] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"256.153702ms","start":"2026-10-26T00:57:04.489687Z","end":"2026-10-26T00:57:04.745841Z","steps":["trace[994653541] 'process raft request' (duration: 255.953398ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:04.746068Z","caller":"traceutil/trace.go:171","msg":"trace[1122236628] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"249.632496ms","start":"2026-10-26T00:57:04.496413Z","end":"2026-10-26T00:57:04.746046Z","steps":["trace[1122236628] 'process raft request' (duration: 249.376619ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:04.746278Z","caller":"traceutil/trace.go:171","msg":"trace[2138080559] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"254.910946ms","start":"2026-10-26T00:57:04.491136Z","end":"2026-10-26T00:57:04.746047Z","steps":["trace[2138080559] 'process raft request' (duration: 254.605728ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:04.843249Z","caller":"traceutil/trace.go:171","msg":"trace[1605673291] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"256.620244ms","start":"2026-10-26T00:57:04.586607Z","end":"2026-10-26T00:57:04.843227Z","steps":["trace[1605673291] 'process raft request' (duration: 256.492741ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:04.983373Z","caller":"traceutil/trace.go:171","msg":"trace[559037004] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"229.613062ms","start":"2026-10-26T00:57:04.753735Z","end":"2026-10-26T00:57:04.983348Z","steps":["trace[559037004] 'process raft request' (duration: 229.421138ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:04.983545Z","caller":"traceutil/trace.go:171","msg":"trace[383078390] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"198.647462ms","start":"2026-10-26T00:57:04.784884Z","end":"2026-10-26T00:57:04.983531Z","steps":["trace[383078390] 'process raft request' (duration: 198.406327ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.228118Z","caller":"traceutil/trace.go:171","msg":"trace[1767191681] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"376.154787ms","start":"2026-10-26T00:57:04.851938Z","end":"2026-10-26T00:57:05.228092Z","steps":["trace[1767191681] 'process raft request' (duration: 376.024745ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.228161Z","caller":"traceutil/trace.go:171","msg":"trace[1148350861] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"380.308371ms","start":"2026-10-26T00:57:04.847811Z","end":"2026-10-26T00:57:05.228119Z","steps":["trace[1148350861] 'process raft request' (duration: 379.999744ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:05.228291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:04.847793Z","time spent":"380.438885ms","remote":"127.0.0.1:45178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:57:05.228295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:04.851926Z","time spent":"376.26888ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:57:05.42682Z","caller":"traceutil/trace.go:171","msg":"trace[641794264] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"441.829872ms","start":"2026-10-26T00:57:04.98496Z","end":"2026-10-26T00:57:05.42679Z","steps":["trace[641794264] 'process raft request' (duration: 410.588645ms)","trace[641794264] 'compare' (duration: 30.902369ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:05.426832Z","caller":"traceutil/trace.go:171","msg":"trace[359173364] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"435.189812ms","start":"2026-10-26T00:57:04.991615Z","end":"2026-10-26T00:57:05.426804Z","steps":["trace[359173364] 'process raft request' (duration: 434.999148ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:05.426965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:04.984941Z","time spent":"441.945266ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:57:05.426972Z","caller":"traceutil/trace.go:171","msg":"trace[54116512] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:743; }","duration":"273.949436ms","start":"2026-10-26T00:57:05.152992Z","end":"2026-10-26T00:57:05.426941Z","steps":["trace[54116512] 'read index received' (duration: 74.875254ms)","trace[54116512] 'applied index is now lower than readState.Index' (duration: 199.072282ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:57:05.427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:04.991606Z","time spent":"435.292855ms","remote":"127.0.0.1:45100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4694,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:57:05.427104Z","caller":"traceutil/trace.go:171","msg":"trace[227235649] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"242.192508ms","start":"2026-10-26T00:57:05.184894Z","end":"2026-10-26T00:57:05.427087Z","steps":["trace[227235649] 'process raft request' (duration: 241.8339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:05.427182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.17345ms","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-10-26T00:57:05.42722Z","caller":"traceutil/trace.go:171","msg":"trace[350630003] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:718; }","duration":"274.264873ms","start":"2026-10-26T00:57:05.152945Z","end":"2026-10-26T00:57:05.42721Z","steps":["trace[350630003] 'agreement among raft nodes before linearized reading' (duration: 274.089259ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.427243Z","caller":"traceutil/trace.go:171","msg":"trace[685946159] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"249.490192ms","start":"2026-10-26T00:57:05.177737Z","end":"2026-10-26T00:57:05.427227Z","steps":["trace[685946159] 'process raft request' (duration: 248.93748ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.61603Z","caller":"traceutil/trace.go:171","msg":"trace[1267704009] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"381.371766ms","start":"2026-10-26T00:57:05.234636Z","end":"2026-10-26T00:57:05.616007Z","steps":["trace[1267704009] 'process raft request' (duration: 381.193362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:05.61619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:05.234612Z","time spent":"381.487139ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:57:05.616417Z","caller":"traceutil/trace.go:171","msg":"trace[1284354431] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"379.517383ms","start":"2026-10-26T00:57:05.236883Z","end":"2026-10-26T00:57:05.6164Z","steps":["trace[1284354431] 'process raft request' (duration: 379.081474ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.61643Z","caller":"traceutil/trace.go:171","msg":"trace[510568650] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:749; }","duration":"185.840053ms","start":"2026-10-26T00:57:05.430567Z","end":"2026-10-26T00:57:05.616407Z","steps":["trace[510568650] 'read index received' (duration: 185.24392ms)","trace[510568650] 'applied index is now lower than readState.Index' (duration: 595.023µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:57:05.61652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:05.236869Z","time spent":"379.609305ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:57:05.616599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.010687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:57:05.616637Z","caller":"traceutil/trace.go:171","msg":"trace[612297068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"186.097279ms","start":"2026-10-26T00:57:05.43053Z","end":"2026-10-26T00:57:05.616627Z","steps":["trace[612297068] 'agreement among raft nodes before linearized reading' (duration: 185.974386ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.616736Z","caller":"traceutil/trace.go:171","msg":"trace[668256224] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"231.524296ms","start":"2026-10-26T00:57:05.385088Z","end":"2026-10-26T00:57:05.616612Z","steps":["trace[668256224] 'process raft request' (duration: 231.226019ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.919593Z","caller":"traceutil/trace.go:171","msg":"trace[1899669688] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"487.444744ms","start":"2026-10-26T00:57:05.432115Z","end":"2026-10-26T00:57:05.91956Z","steps":["trace[1899669688] 'process raft request' (duration: 487.170507ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.919631Z","caller":"traceutil/trace.go:171","msg":"trace[665528763] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:752; }","duration":"300.960357ms","start":"2026-10-26T00:57:05.618634Z","end":"2026-10-26T00:57:05.919594Z","steps":["trace[665528763] 'read index received' (duration: 300.720291ms)","trace[665528763] 'applied index is now lower than readState.Index' (duration: 238.756µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:05.919702Z","caller":"traceutil/trace.go:171","msg":"trace[808492360] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"333.997094ms","start":"2026-10-26T00:57:05.585681Z","end":"2026-10-26T00:57:05.919679Z","steps":["trace[808492360] 'process raft request' (duration: 333.849501ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.919728Z","caller":"traceutil/trace.go:171","msg":"trace[1909677423] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"486.468752ms","start":"2026-10-26T00:57:05.433211Z","end":"2026-10-26T00:57:05.91968Z","steps":["trace[1909677423] 'process raft request' (duration: 486.219377ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:05.919747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:05.432097Z","time spent":"487.585296ms","remote":"127.0.0.1:52436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:57:05.919826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.168191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:57:05.919857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:05.585663Z","time spent":"334.090036ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:57:05.91986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:05.433199Z","time spent":"486.600015ms","remote":"127.0.0.1:45178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:57:05.919873Z","caller":"traceutil/trace.go:171","msg":"trace[542087033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"301.260193ms","start":"2026-10-26T00:57:05.618598Z","end":"2026-10-26T00:57:05.919858Z","steps":["trace[542087033] 'agreement among raft nodes before linearized reading' (duration: 301.10286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:05.919921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:05.618583Z","time spent":"301.328324ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T00:57:05.982371Z","caller":"traceutil/trace.go:171","msg":"trace[1517640888] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"360.068034ms","start":"2026-10-26T00:57:05.622274Z","end":"2026-10-26T00:57:05.982342Z","steps":["trace[1517640888] 'process raft request' (duration: 359.825358ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.982475Z","caller":"traceutil/trace.go:171","msg":"trace[160059672] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"197.091208ms","start":"2026-10-26T00:57:05.785338Z","end":"2026-10-26T00:57:05.982429Z","steps":["trace[160059672] 'process raft request' (duration: 196.949644ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:05.982598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:05.622253Z","time spent":"360.194917ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:57:05.982636Z","caller":"traceutil/trace.go:171","msg":"trace[1538328320] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"359.187914ms","start":"2026-10-26T00:57:05.623438Z","end":"2026-10-26T00:57:05.982626Z","steps":["trace[1538328320] 'process raft request' (duration: 358.790034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:05.98272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:05.623389Z","time spent":"359.266416ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:57:05.982791Z","caller":"traceutil/trace.go:171","msg":"trace[857208812] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"164.706156ms","start":"2026-10-26T00:57:05.817994Z","end":"2026-10-26T00:57:05.9827Z","steps":["trace[857208812] 'process raft request' (duration: 164.336048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:06.154859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.901494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:57:06.154949Z","caller":"traceutil/trace.go:171","msg":"trace[2137776659] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"158.873914ms","start":"2026-10-26T00:57:05.996057Z","end":"2026-10-26T00:57:06.15493Z","steps":["trace[2137776659] 'process raft request' (duration: 26.827777ms)","trace[2137776659] 'compare' (duration: 131.813682ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:06.186602Z","caller":"traceutil/trace.go:171","msg":"trace[1830720269] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"185.337691ms","start":"2026-10-26T00:57:06.001242Z","end":"2026-10-26T00:57:06.18658Z","steps":["trace[1830720269] 'process raft request' (duration: 185.162287ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:06.186789Z","caller":"traceutil/trace.go:171","msg":"trace[2139441928] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"185.411614ms","start":"2026-10-26T00:57:06.001359Z","end":"2026-10-26T00:57:06.186771Z","steps":["trace[2139441928] 'process raft request' (duration: 185.182158ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:06.186839Z","caller":"traceutil/trace.go:171","msg":"trace[315323068] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"180.464801ms","start":"2026-10-26T00:57:06.006358Z","end":"2026-10-26T00:57:06.186823Z","steps":["trace[315323068] 'process raft request' (duration: 180.340948ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:06.231055Z","caller":"traceutil/trace.go:171","msg":"trace[1228287448] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"155.333732ms","start":"2026-10-26T00:57:06.075696Z","end":"2026-10-26T00:57:06.23103Z","steps":["trace[1228287448] 'process raft request' (duration: 155.154359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:06.715549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.794914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T00:57:06.715636Z","caller":"traceutil/trace.go:171","msg":"trace[1737162726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"186.898047ms","start":"2026-10-26T00:57:06.528718Z","end":"2026-10-26T00:57:06.715616Z","steps":["trace[1737162726] 'range keys from in-memory index tree' (duration: 186.548338ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:06.718352Z","caller":"traceutil/trace.go:171","msg":"trace[256044974] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"176.052052ms","start":"2026-10-26T00:57:06.542274Z","end":"2026-10-26T00:57:06.718326Z","steps":["trace[256044974] 'process raft request' (duration: 175.828627ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:15.471253Z","caller":"traceutil/trace.go:171","msg":"trace[1542251198] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"247.59435ms","start":"2026-10-26T00:57:15.223626Z","end":"2026-10-26T00:57:15.47122Z","steps":["trace[1542251198] 'process raft request' (duration: 247.392614ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:18.7042Z","caller":"traceutil/trace.go:171","msg":"trace[796460548] linearizableReadLoop","detail":"{readStateIndex:872; appliedIndex:871; }","duration":"448.612506ms","start":"2026-10-26T00:57:18.255555Z","end":"2026-10-26T00:57:18.704168Z","steps":["trace[796460548] 'read index received' (duration: 448.431532ms)","trace[796460548] 'applied index is now lower than readState.Index' (duration: 179.374µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:18.704269Z","caller":"traceutil/trace.go:171","msg":"trace[1472098560] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"465.869206ms","start":"2026-10-26T00:57:18.23836Z","end":"2026-10-26T00:57:18.704229Z","steps":["trace[1472098560] 'process raft request' (duration: 465.579749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:18.704442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:18.238328Z","time spent":"466.025639ms","remote":"127.0.0.1:52536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:57:18.704439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.875462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:57:18.704534Z","caller":"traceutil/trace.go:171","msg":"trace[251553912] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:838; }","duration":"216.020275ms","start":"2026-10-26T00:57:18.4885Z","end":"2026-10-26T00:57:18.70452Z","steps":["trace[251553912] 'agreement among raft nodes before linearized reading' (duration: 215.886742ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:18.704621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.029556ms","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-10-26T00:57:18.704749Z","caller":"traceutil/trace.go:171","msg":"trace[954017305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:838; }","duration":"449.2206ms","start":"2026-10-26T00:57:18.255498Z","end":"2026-10-26T00:57:18.704718Z","steps":["trace[954017305] 'agreement among raft nodes before linearized reading' (duration: 448.849042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:18.704825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.0226ms","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-10-26T00:57:18.704855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:18.25548Z","time spent":"449.356673ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T00:57:18.704889Z","caller":"traceutil/trace.go:171","msg":"trace[517195968] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:838; }","duration":"126.155443ms","start":"2026-10-26T00:57:18.57872Z","end":"2026-10-26T00:57:18.704876Z","steps":["trace[517195968] 'agreement among raft nodes before linearized reading' (duration: 125.687802ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:18.889477Z","caller":"traceutil/trace.go:171","msg":"trace[311694055] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"178.710962ms","start":"2026-10-26T00:57:18.710712Z","end":"2026-10-26T00:57:18.889423Z","steps":["trace[311694055] 'process raft request' (duration: 178.313724ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:18.890118Z","caller":"traceutil/trace.go:171","msg":"trace[1105054977] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"178.457925ms","start":"2026-10-26T00:57:18.711638Z","end":"2026-10-26T00:57:18.890096Z","steps":["trace[1105054977] 'process raft request' (duration: 178.177479ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:18.894592Z","caller":"traceutil/trace.go:171","msg":"trace[984212432] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:872; }","duration":"121.040148ms","start":"2026-10-26T00:57:18.773523Z","end":"2026-10-26T00:57:18.894564Z","steps":["trace[984212432] 'read index received' (duration: 115.521373ms)","trace[984212432] 'applied index is now lower than readState.Index' (duration: 5.517345ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:57:18.894889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.332644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T00:57:18.894929Z","caller":"traceutil/trace.go:171","msg":"trace[960189324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:840; }","duration":"121.443667ms","start":"2026-10-26T00:57:18.773474Z","end":"2026-10-26T00:57:18.894918Z","steps":["trace[960189324] 'agreement among raft nodes before linearized reading' (duration: 121.232722ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:23.197254Z","caller":"traceutil/trace.go:171","msg":"trace[386563455] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"201.17946ms","start":"2026-10-26T00:57:22.996051Z","end":"2026-10-26T00:57:23.197231Z","steps":["trace[386563455] 'process raft request' (duration: 201.104329ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:23.197314Z","caller":"traceutil/trace.go:171","msg":"trace[733390814] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"201.225101ms","start":"2026-10-26T00:57:22.996036Z","end":"2026-10-26T00:57:23.197261Z","steps":["trace[733390814] 'process raft request' (duration: 158.224598ms)","trace[733390814] 'compare' (duration: 42.757417ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:23.197534Z","caller":"traceutil/trace.go:171","msg":"trace[480011045] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"201.429036ms","start":"2026-10-26T00:57:22.996088Z","end":"2026-10-26T00:57:23.197517Z","steps":["trace[480011045] 'process raft request' (duration: 201.103678ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:23.372286Z","caller":"traceutil/trace.go:171","msg":"trace[1646622478] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"110.839008ms","start":"2026-10-26T00:57:23.26139Z","end":"2026-10-26T00:57:23.372229Z","steps":["trace[1646622478] 'process raft request' (duration: 58.398861ms)","trace[1646622478] 'compare' (duration: 52.277083ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:24.379677Z","caller":"traceutil/trace.go:171","msg":"trace[852514437] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"113.029437ms","start":"2026-10-26T00:57:24.266617Z","end":"2026-10-26T00:57:24.379647Z","steps":["trace[852514437] 'process raft request' (duration: 112.820392ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:53.314715Z","caller":"traceutil/trace.go:171","msg":"trace[1557656092] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1093; }","duration":"133.870306ms","start":"2026-10-26T00:57:53.180805Z","end":"2026-10-26T00:57:53.314675Z","steps":["trace[1557656092] 'read index received' (duration: 133.455907ms)","trace[1557656092] 'applied index is now lower than readState.Index' (duration: 412.609µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:53.314741Z","caller":"traceutil/trace.go:171","msg":"trace[2019363133] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"162.569326ms","start":"2026-10-26T00:57:53.152147Z","end":"2026-10-26T00:57:53.314716Z","steps":["trace[2019363133] 'process raft request' (duration: 162.184097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:53.314878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.037009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-26T00:57:53.314914Z","caller":"traceutil/trace.go:171","msg":"trace[990360176] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1050; }","duration":"134.170792ms","start":"2026-10-26T00:57:53.180733Z","end":"2026-10-26T00:57:53.314904Z","steps":["trace[990360176] 'agreement among raft nodes before linearized reading' (duration: 134.059709ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:59.90019Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6694215423052823417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-26T00:58:00.400629Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6694215423052823417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-26T00:58:00.755109Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.390945042s","expected-duration":"1s"} {"level":"info","ts":"2026-10-26T00:58:00.75552Z","caller":"traceutil/trace.go:171","msg":"trace[1281346739] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"1.391411882s","start":"2026-10-26T00:57:59.364083Z","end":"2026-10-26T00:58:00.755494Z","steps":["trace[1281346739] 'process raft request' (duration: 1.391247308s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:00.755672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:59.364066Z","time spent":"1.391512815s","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:58:00.756108Z","caller":"traceutil/trace.go:171","msg":"trace[1055406927] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1168; }","duration":"1.356060752s","start":"2026-10-26T00:57:59.400017Z","end":"2026-10-26T00:58:00.756078Z","steps":["trace[1055406927] 'read index received' (duration: 1.356051062s)","trace[1055406927] 'applied index is now lower than readState.Index' (duration: 7.35µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:00.756302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356269696s","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-10-26T00:58:00.756362Z","caller":"traceutil/trace.go:171","msg":"trace[1031443453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1122; }","duration":"1.356368129s","start":"2026-10-26T00:57:59.39998Z","end":"2026-10-26T00:58:00.756349Z","steps":["trace[1031443453] 'agreement among raft nodes before linearized reading' (duration: 1.356191345s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:00.756385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:59.399963Z","time spent":"1.356417049s","remote":"127.0.0.1:52610","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-10-26T00:58:00.758386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205768054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:58:00.758558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.670958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:58:00.758554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257896316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-26T00:58:00.758562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327240288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:00.75863Z","caller":"traceutil/trace.go:171","msg":"trace[1621264140] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1123; }","duration":"1.206065752s","start":"2026-10-26T00:57:59.552555Z","end":"2026-10-26T00:58:00.758621Z","steps":["trace[1621264140] 'agreement among raft nodes before linearized reading' (duration: 1.205781544s)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:00.758621Z","caller":"traceutil/trace.go:171","msg":"trace[17606563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1123; }","duration":"1.258001689s","start":"2026-10-26T00:57:59.5006Z","end":"2026-10-26T00:58:00.758601Z","steps":["trace[17606563] 'agreement among raft nodes before linearized reading' (duration: 1.257769043s)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:00.758634Z","caller":"traceutil/trace.go:171","msg":"trace[813865827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1123; }","duration":"1.32735017s","start":"2026-10-26T00:57:59.431263Z","end":"2026-10-26T00:58:00.758613Z","steps":["trace[813865827] 'agreement among raft nodes before linearized reading' (duration: 1.327237747s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:00.758652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:59.552535Z","time spent":"1.206110982s","remote":"127.0.0.1:52568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-10-26T00:58:00.758587Z","caller":"traceutil/trace.go:171","msg":"trace[610023735] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1123; }","duration":"529.74179ms","start":"2026-10-26T00:58:00.228834Z","end":"2026-10-26T00:58:00.758575Z","steps":["trace[610023735] 'agreement among raft nodes before linearized reading' (duration: 529.702349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:00.758667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:59.500586Z","time spent":"1.25807091s","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T00:58:00.758681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:00.22881Z","time spent":"529.867143ms","remote":"127.0.0.1:45036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-10-26T00:58:00.758677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:59.431242Z","time spent":"1.327425762s","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T00:58:00.758382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356821759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-26T00:58:00.759049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258376147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T00:58:00.759052Z","caller":"traceutil/trace.go:171","msg":"trace[1460138424] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1123; }","duration":"1.357515405s","start":"2026-10-26T00:57:59.401515Z","end":"2026-10-26T00:58:00.75903Z","steps":["trace[1460138424] 'agreement among raft nodes before linearized reading' (duration: 1.356778007s)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:00.759101Z","caller":"traceutil/trace.go:171","msg":"trace[698926855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1123; }","duration":"1.258440349s","start":"2026-10-26T00:57:59.500645Z","end":"2026-10-26T00:58:00.759085Z","steps":["trace[698926855] 'agreement among raft nodes before linearized reading' (duration: 1.257761253s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:00.759117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:59.401505Z","time spent":"1.357591806s","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-26T00:58:00.759143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:59.500641Z","time spent":"1.25849204s","remote":"127.0.0.1:52610","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-10-26T00:58:13.378088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.820309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.152\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-26T00:58:13.378156Z","caller":"traceutil/trace.go:171","msg":"trace[2079253277] range","detail":"{range_begin:/registry/masterleases/162.253.55.152; range_end:; response_count:1; response_revision:1165; }","duration":"262.919101ms","start":"2026-10-26T00:58:13.115219Z","end":"2026-10-26T00:58:13.378138Z","steps":["trace[2079253277] 'range keys from in-memory index tree' (duration: 262.655105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:13.377764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.987168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:58:13.378393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.091953ms","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-10-26T00:58:13.378425Z","caller":"traceutil/trace.go:171","msg":"trace[163927295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1165; }","duration":"124.124054ms","start":"2026-10-26T00:58:13.254292Z","end":"2026-10-26T00:58:13.378416Z","steps":["trace[163927295] 'range keys from in-memory index tree' (duration: 123.96365ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:13.378426Z","caller":"traceutil/trace.go:171","msg":"trace[1789993734] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1165; }","duration":"318.700765ms","start":"2026-10-26T00:58:13.059693Z","end":"2026-10-26T00:58:13.378394Z","steps":["trace[1789993734] 'count revisions from in-memory index tree' (duration: 317.859376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:13.37854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:13.059662Z","time spent":"318.839908ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-10-26T00:58:14.794776Z","caller":"traceutil/trace.go:171","msg":"trace[1229011224] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"248.335169ms","start":"2026-10-26T00:58:14.546404Z","end":"2026-10-26T00:58:14.794739Z","steps":["trace[1229011224] 'process raft request' (duration: 248.102334ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:15.126654Z","caller":"traceutil/trace.go:171","msg":"trace[1332342557] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"276.993919ms","start":"2026-10-26T00:58:14.849625Z","end":"2026-10-26T00:58:15.126619Z","steps":["trace[1332342557] 'read index received' (duration: 276.707752ms)","trace[1332342557] 'applied index is now lower than readState.Index' (duration: 285.047µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:15.127163Z","caller":"traceutil/trace.go:171","msg":"trace[557510394] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"283.744303ms","start":"2026-10-26T00:58:14.843404Z","end":"2026-10-26T00:58:15.127149Z","steps":["trace[557510394] 'process raft request' (duration: 282.990196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:15.127555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.91182ms","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-10-26T00:58:15.127635Z","caller":"traceutil/trace.go:171","msg":"trace[328496122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1169; }","duration":"278.038452ms","start":"2026-10-26T00:58:14.849574Z","end":"2026-10-26T00:58:15.127612Z","steps":["trace[328496122] 'agreement among raft nodes before linearized reading' (duration: 277.795127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:15.127713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.69936ms","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-10-26T00:58:15.127766Z","caller":"traceutil/trace.go:171","msg":"trace[1577448900] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1169; }","duration":"245.845223ms","start":"2026-10-26T00:58:14.881909Z","end":"2026-10-26T00:58:15.127754Z","steps":["trace[1577448900] 'agreement among raft nodes before linearized reading' (duration: 245.633058ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:15.127907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.019142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T00:58:15.127942Z","caller":"traceutil/trace.go:171","msg":"trace[623822511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1169; }","duration":"229.071313ms","start":"2026-10-26T00:58:14.898863Z","end":"2026-10-26T00:58:15.127934Z","steps":["trace[623822511] 'agreement among raft nodes before linearized reading' (duration: 228.983951ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:15.128064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.856651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-26T00:58:15.128094Z","caller":"traceutil/trace.go:171","msg":"trace[149048973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1169; }","duration":"233.909423ms","start":"2026-10-26T00:58:14.894178Z","end":"2026-10-26T00:58:15.128088Z","steps":["trace[149048973] 'agreement among raft nodes before linearized reading' (duration: 233.830941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:15.600497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.563928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:58:15.600741Z","caller":"traceutil/trace.go:171","msg":"trace[961961903] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1218; }","duration":"166.982746ms","start":"2026-10-26T00:58:15.43374Z","end":"2026-10-26T00:58:15.600723Z","steps":["trace[961961903] 'read index received' (duration: 61.438893ms)","trace[961961903] 'applied index is now lower than readState.Index' (duration: 105.539873ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:15.600812Z","caller":"traceutil/trace.go:171","msg":"trace[798762390] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"467.247313ms","start":"2026-10-26T00:58:15.13349Z","end":"2026-10-26T00:58:15.600738Z","steps":["trace[798762390] 'process raft request' (duration: 467.10408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:15.600927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.19503ms","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-10-26T00:58:15.600957Z","caller":"traceutil/trace.go:171","msg":"trace[1712417626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1173; }","duration":"167.276782ms","start":"2026-10-26T00:58:15.43367Z","end":"2026-10-26T00:58:15.600947Z","steps":["trace[1712417626] 'agreement among raft nodes before linearized reading' (duration: 167.131678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:15.601027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:15.133448Z","time spent":"467.457727ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:58:15.601094Z","caller":"traceutil/trace.go:171","msg":"trace[863507327] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"466.200648ms","start":"2026-10-26T00:58:15.134862Z","end":"2026-10-26T00:58:15.601063Z","steps":["trace[863507327] 'process raft request' (duration: 465.782009ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:15.601159Z","caller":"traceutil/trace.go:171","msg":"trace[303084676] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"466.206179ms","start":"2026-10-26T00:58:15.13494Z","end":"2026-10-26T00:58:15.601146Z","steps":["trace[303084676] 'process raft request' (duration: 465.738329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:15.601217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:15.134932Z","time spent":"466.25212ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:15.601256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:15.13485Z","time spent":"466.313751ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:58:15.601289Z","caller":"traceutil/trace.go:171","msg":"trace[958363421] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"467.826915ms","start":"2026-10-26T00:58:15.133426Z","end":"2026-10-26T00:58:15.601253Z","steps":["trace[958363421] 'process raft request' (duration: 82.385438ms)","trace[958363421] 'compare' (duration: 384.100137ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:15.601452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:15.133403Z","time spent":"467.961168ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:15.852571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.515156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:58:15.852678Z","caller":"traceutil/trace.go:171","msg":"trace[1587055942] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"246.15164ms","start":"2026-10-26T00:58:15.606506Z","end":"2026-10-26T00:58:15.852657Z","steps":["trace[1587055942] 'process raft request' (duration: 131.420469ms)","trace[1587055942] 'compare' (duration: 114.328082ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:17.1809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207037574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:17.180983Z","caller":"traceutil/trace.go:171","msg":"trace[561715913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1174; }","duration":"1.207187178s","start":"2026-10-26T00:58:15.973773Z","end":"2026-10-26T00:58:17.18096Z","steps":["trace[561715913] 'range keys from in-memory index tree' (duration: 1.20689638s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.181033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:15.97374Z","time spent":"1.207280039s","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T00:58:17.181045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.380276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:17.181123Z","caller":"traceutil/trace.go:171","msg":"trace[1003041143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1174; }","duration":"748.499858ms","start":"2026-10-26T00:58:16.432593Z","end":"2026-10-26T00:58:17.181093Z","steps":["trace[1003041143] 'range keys from in-memory index tree' (duration: 748.306315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.181166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:16.432569Z","time spent":"748.582521ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T00:58:17.181903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.100872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T00:58:17.181947Z","caller":"traceutil/trace.go:171","msg":"trace[223303627] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1174; }","duration":"236.189394ms","start":"2026-10-26T00:58:16.945742Z","end":"2026-10-26T00:58:17.181931Z","steps":["trace[223303627] 'count revisions from in-memory index tree' (duration: 236.037481ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:17.716014Z","caller":"traceutil/trace.go:171","msg":"trace[1247949145] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1223; }","duration":"284.186482ms","start":"2026-10-26T00:58:17.431802Z","end":"2026-10-26T00:58:17.715989Z","steps":["trace[1247949145] 'read index received' (duration: 284.019229ms)","trace[1247949145] 'applied index is now lower than readState.Index' (duration: 166.073µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:17.716084Z","caller":"traceutil/trace.go:171","msg":"trace[1427557012] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"581.515414ms","start":"2026-10-26T00:58:17.134528Z","end":"2026-10-26T00:58:17.716043Z","steps":["trace[1427557012] 'process raft request' (duration: 581.293589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.716175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.346866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:17.716246Z","caller":"traceutil/trace.go:171","msg":"trace[1567615358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1175; }","duration":"284.468198ms","start":"2026-10-26T00:58:17.431744Z","end":"2026-10-26T00:58:17.716213Z","steps":["trace[1567615358] 'agreement among raft nodes before linearized reading' (duration: 284.372146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.716458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:17.134498Z","time spent":"581.85911ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:17.987448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.941469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:58:17.987572Z","caller":"traceutil/trace.go:171","msg":"trace[1076425886] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"271.470995ms","start":"2026-10-26T00:58:17.716086Z","end":"2026-10-26T00:58:17.987557Z","steps":["trace[1076425886] 'read index received' (duration: 151.433853ms)","trace[1076425886] 'applied index is now lower than readState.Index' (duration: 120.035962ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:17.987589Z","caller":"traceutil/trace.go:171","msg":"trace[1164862988] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"375.563324ms","start":"2026-10-26T00:58:17.612003Z","end":"2026-10-26T00:58:17.987566Z","steps":["trace[1164862988] 'process raft request' (duration: 255.906171ms)","trace[1164862988] 'compare' (duration: 119.342236ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:17.987674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:17.611986Z","time spent":"375.648626ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:17.987728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.187691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T00:58:17.987752Z","caller":"traceutil/trace.go:171","msg":"trace[1827848166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1176; }","duration":"381.251053ms","start":"2026-10-26T00:58:17.606492Z","end":"2026-10-26T00:58:17.987744Z","steps":["trace[1827848166] 'agreement among raft nodes before linearized reading' (duration: 381.14182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.987756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.760849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-26T00:58:17.987813Z","caller":"traceutil/trace.go:171","msg":"trace[673786958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1176; }","duration":"379.839521ms","start":"2026-10-26T00:58:17.607958Z","end":"2026-10-26T00:58:17.987797Z","steps":["trace[673786958] 'agreement among raft nodes before linearized reading' (duration: 379.656267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.987857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.602351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-26T00:58:17.987777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:17.606457Z","time spent":"381.314514ms","remote":"127.0.0.1:52610","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-10-26T00:58:17.987835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.265247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:17.987919Z","caller":"traceutil/trace.go:171","msg":"trace[926364854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1176; }","duration":"270.367679ms","start":"2026-10-26T00:58:17.717534Z","end":"2026-10-26T00:58:17.987902Z","steps":["trace[926364854] 'agreement among raft nodes before linearized reading' (duration: 270.217556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.987922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.403141ms","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-10-26T00:58:17.987854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:17.607948Z","time spent":"379.894772ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T00:58:17.987947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.442937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-26T00:58:17.987988Z","caller":"traceutil/trace.go:171","msg":"trace[314958719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1176; }","duration":"379.520024ms","start":"2026-10-26T00:58:17.608452Z","end":"2026-10-26T00:58:17.987972Z","steps":["trace[314958719] 'agreement among raft nodes before linearized reading' (duration: 379.296429ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:17.988005Z","caller":"traceutil/trace.go:171","msg":"trace[1316754632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1176; }","duration":"130.562179ms","start":"2026-10-26T00:58:17.857429Z","end":"2026-10-26T00:58:17.987991Z","steps":["trace[1316754632] 'agreement among raft nodes before linearized reading' (duration: 130.389045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.98803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:17.608445Z","time spent":"379.576775ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-26T00:58:17.987883Z","caller":"traceutil/trace.go:171","msg":"trace[1137366502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1176; }","duration":"381.683843ms","start":"2026-10-26T00:58:17.606191Z","end":"2026-10-26T00:58:17.987875Z","steps":["trace[1137366502] 'agreement among raft nodes before linearized reading' (duration: 381.612331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.988119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:17.606176Z","time spent":"381.933339ms","remote":"127.0.0.1:52610","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-10-26T00:58:18.230119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.161126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:58:18.230284Z","caller":"traceutil/trace.go:171","msg":"trace[19453162] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"238.679311ms","start":"2026-10-26T00:58:17.991557Z","end":"2026-10-26T00:58:18.230236Z","steps":["trace[19453162] 'process raft request' (duration: 121.271799ms)","trace[19453162] 'compare' (duration: 117.018143ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:18.2903Z","caller":"traceutil/trace.go:171","msg":"trace[1451715803] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"298.485206ms","start":"2026-10-26T00:58:17.991791Z","end":"2026-10-26T00:58:18.290276Z","steps":["trace[1451715803] 'process raft request' (duration: 298.312542ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:18.290643Z","caller":"traceutil/trace.go:171","msg":"trace[1639820502] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"297.713579ms","start":"2026-10-26T00:58:17.992902Z","end":"2026-10-26T00:58:18.290615Z","steps":["trace[1639820502] 'process raft request' (duration: 297.621597ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:18.290666Z","caller":"traceutil/trace.go:171","msg":"trace[421041460] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"298.056777ms","start":"2026-10-26T00:58:17.992598Z","end":"2026-10-26T00:58:18.290654Z","steps":["trace[421041460] 'process raft request' (duration: 297.619288ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:18.290907Z","caller":"traceutil/trace.go:171","msg":"trace[817428086] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"297.851201ms","start":"2026-10-26T00:58:17.993025Z","end":"2026-10-26T00:58:18.290877Z","steps":["trace[817428086] 'process raft request' (duration: 297.559725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:19.033996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.118338ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-10-26T00:58:19.836198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.97631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:58:19.836352Z","caller":"traceutil/trace.go:171","msg":"trace[1510904259] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"442.989492ms","start":"2026-10-26T00:58:19.393313Z","end":"2026-10-26T00:58:19.836303Z","steps":["trace[1510904259] 'process raft request' (duration: 142.853158ms)","trace[1510904259] 'compare' (duration: 299.741215ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:19.83641Z","caller":"traceutil/trace.go:171","msg":"trace[43329710] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"114.965126ms","start":"2026-10-26T00:58:19.72141Z","end":"2026-10-26T00:58:19.836375Z","steps":["trace[43329710] 'process raft request' (duration: 114.889395ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:19.836508Z","caller":"traceutil/trace.go:171","msg":"trace[2108765057] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"406.083286ms","start":"2026-10-26T00:58:19.430406Z","end":"2026-10-26T00:58:19.836489Z","steps":["trace[2108765057] 'read index received' (duration: 105.749438ms)","trace[2108765057] 'applied index is now lower than readState.Index' (duration: 300.331578ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:19.836562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:19.393291Z","time spent":"443.127305ms","remote":"127.0.0.1:52436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":839,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:58:19.836657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.247329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:19.836692Z","caller":"traceutil/trace.go:171","msg":"trace[636966684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1183; }","duration":"406.343631ms","start":"2026-10-26T00:58:19.430339Z","end":"2026-10-26T00:58:19.836683Z","steps":["trace[636966684] 'agreement among raft nodes before linearized reading' (duration: 406.262469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:19.836723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:19.430318Z","time spent":"406.396313ms","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T00:58:19.836185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:19.509198Z","time spent":"326.936101ms","remote":"127.0.0.1:60486","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-26T00:58:20.656316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.67769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-26T00:58:20.656413Z","caller":"traceutil/trace.go:171","msg":"trace[729138357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1183; }","duration":"361.836463ms","start":"2026-10-26T00:58:20.294551Z","end":"2026-10-26T00:58:20.656388Z","steps":["trace[729138357] 'range keys from in-memory index tree' (duration: 361.528916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.656381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.316848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:58:20.656501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.294529Z","time spent":"361.927596ms","remote":"127.0.0.1:52610","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-10-26T00:58:20.656519Z","caller":"traceutil/trace.go:171","msg":"trace[2136908133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1183; }","duration":"225.494911ms","start":"2026-10-26T00:58:20.430998Z","end":"2026-10-26T00:58:20.656493Z","steps":["trace[2136908133] 'range keys from in-memory index tree' (duration: 225.225475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.656554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.469537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-26T00:58:20.656608Z","caller":"traceutil/trace.go:171","msg":"trace[791579740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1183; }","duration":"422.56114ms","start":"2026-10-26T00:58:20.234032Z","end":"2026-10-26T00:58:20.656593Z","steps":["trace[791579740] 'range keys from in-memory index tree' (duration: 422.315024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.656646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.234011Z","time spent":"422.624381ms","remote":"127.0.0.1:52610","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-10-26T00:58:20.656664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.72158ms","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-10-26T00:58:20.656713Z","caller":"traceutil/trace.go:171","msg":"trace[100155599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1183; }","duration":"361.772041ms","start":"2026-10-26T00:58:20.294926Z","end":"2026-10-26T00:58:20.656698Z","steps":["trace[100155599] 'range keys from in-memory index tree' (duration: 361.522755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.656746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.29489Z","time spent":"361.846603ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-26T00:58:20.656795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.15207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-26T00:58:20.656795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.302273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T00:58:20.656869Z","caller":"traceutil/trace.go:171","msg":"trace[1928141789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1183; }","duration":"362.435096ms","start":"2026-10-26T00:58:20.294419Z","end":"2026-10-26T00:58:20.656854Z","steps":["trace[1928141789] 'range keys from in-memory index tree' (duration: 362.157441ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:20.656869Z","caller":"traceutil/trace.go:171","msg":"trace[564911297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1183; }","duration":"362.281953ms","start":"2026-10-26T00:58:20.294567Z","end":"2026-10-26T00:58:20.656849Z","steps":["trace[564911297] 'range keys from in-memory index tree' (duration: 361.849073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.656907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.29456Z","time spent":"362.338284ms","remote":"127.0.0.1:52610","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-10-26T00:58:20.656907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.294403Z","time spent":"362.495267ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T00:58:21.077682Z","caller":"traceutil/trace.go:171","msg":"trace[1924183056] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"414.914517ms","start":"2026-10-26T00:58:20.66274Z","end":"2026-10-26T00:58:21.077655Z","steps":["trace[1924183056] 'process raft request' (duration: 414.651261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:21.077938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.662708Z","time spent":"415.06539ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:21.634058Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6694215423052823579,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T00:58:21.75071Z","caller":"traceutil/trace.go:171","msg":"trace[831794209] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"1.087811791s","start":"2026-10-26T00:58:20.662862Z","end":"2026-10-26T00:58:21.750674Z","steps":["trace[831794209] 'process raft request' (duration: 1.05380132s)","trace[831794209] 'compare' (duration: 33.395357ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:21.750882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.66283Z","time spent":"1.087952514s","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:58:21.750948Z","caller":"traceutil/trace.go:171","msg":"trace[1437791479] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1235; }","duration":"617.517689ms","start":"2026-10-26T00:58:21.133406Z","end":"2026-10-26T00:58:21.750924Z","steps":["trace[1437791479] 'read index received' (duration: 583.136469ms)","trace[1437791479] 'applied index is now lower than readState.Index' (duration: 34.37994ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:21.751202Z","caller":"traceutil/trace.go:171","msg":"trace[1759278073] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"1.087155046s","start":"2026-10-26T00:58:20.664028Z","end":"2026-10-26T00:58:21.751183Z","steps":["trace[1759278073] 'process raft request' (duration: 1.086818298s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:21.751232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.814845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:21.751268Z","caller":"traceutil/trace.go:171","msg":"trace[1050174819] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1188; }","duration":"617.911998ms","start":"2026-10-26T00:58:21.133343Z","end":"2026-10-26T00:58:21.751255Z","steps":["trace[1050174819] 'agreement among raft nodes before linearized reading' (duration: 617.836937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:21.7513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.664016Z","time spent":"1.087228958s","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:21.7513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:21.133319Z","time spent":"617.97179ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-26T00:58:21.7514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.660461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:21.751435Z","caller":"traceutil/trace.go:171","msg":"trace[879200320] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"1.088467965s","start":"2026-10-26T00:58:20.662961Z","end":"2026-10-26T00:58:21.751429Z","steps":["trace[879200320] 'process raft request' (duration: 1.087705187s)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:21.751444Z","caller":"traceutil/trace.go:171","msg":"trace[766883033] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1188; }","duration":"343.764973ms","start":"2026-10-26T00:58:21.407665Z","end":"2026-10-26T00:58:21.75143Z","steps":["trace[766883033] 'agreement among raft nodes before linearized reading' (duration: 343.700342ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:21.751493Z","caller":"traceutil/trace.go:171","msg":"trace[88342491] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"1.088204129s","start":"2026-10-26T00:58:20.663282Z","end":"2026-10-26T00:58:21.751486Z","steps":["trace[88342491] 'process raft request' (duration: 1.087523913s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:21.751502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:21.407632Z","time spent":"343.861146ms","remote":"127.0.0.1:55138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-10-26T00:58:21.75152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.663247Z","time spent":"1.08825961s","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:21.751476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.662953Z","time spent":"1.088493475s","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:21.75166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.487978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T00:58:21.751693Z","caller":"traceutil/trace.go:171","msg":"trace[280495236] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1188; }","duration":"201.544369ms","start":"2026-10-26T00:58:21.550133Z","end":"2026-10-26T00:58:21.751678Z","steps":["trace[280495236] 'agreement among raft nodes before linearized reading' (duration: 201.496358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:21.751713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.904891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:21.751733Z","caller":"traceutil/trace.go:171","msg":"trace[117502679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1188; }","duration":"319.972613ms","start":"2026-10-26T00:58:21.431755Z","end":"2026-10-26T00:58:21.751727Z","steps":["trace[117502679] 'agreement among raft nodes before linearized reading' (duration: 319.938342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:21.751747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:21.431705Z","time spent":"320.038944ms","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T00:58:22.020683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.635987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:58:22.020881Z","caller":"traceutil/trace.go:171","msg":"trace[349363116] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"178.095627ms","start":"2026-10-26T00:58:21.842755Z","end":"2026-10-26T00:58:22.020851Z","steps":["trace[349363116] 'process raft request' (duration: 45.184304ms)","trace[349363116] 'compare' (duration: 132.495354ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:23.879754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.702763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:58:23.879834Z","caller":"traceutil/trace.go:171","msg":"trace[400310526] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"507.738901ms","start":"2026-10-26T00:58:23.372082Z","end":"2026-10-26T00:58:23.879821Z","steps":["trace[400310526] 'read index received' (duration: 28.311µs)","trace[400310526] 'applied index is now lower than readState.Index' (duration: 507.7097ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:23.87989Z","caller":"traceutil/trace.go:171","msg":"trace[1029413985] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"656.801919ms","start":"2026-10-26T00:58:23.223079Z","end":"2026-10-26T00:58:23.87988Z","steps":["trace[1029413985] 'process raft request' (duration: 72.883572ms)","trace[1029413985] 'compare' (duration: 583.610131ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:23.879939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.223054Z","time spent":"656.858151ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:23.880154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.064488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:23.880186Z","caller":"traceutil/trace.go:171","msg":"trace[1578693165] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1191; }","duration":"508.125419ms","start":"2026-10-26T00:58:23.372053Z","end":"2026-10-26T00:58:23.880179Z","steps":["trace[1578693165] 'agreement among raft nodes before linearized reading' (duration: 508.045607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:23.880207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.372039Z","time spent":"508.16267ms","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-26T00:58:24.379958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6694215423052823599,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T00:58:24.897275Z","caller":"traceutil/trace.go:171","msg":"trace[2138694820] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"1.10455827s","start":"2026-10-26T00:58:23.792693Z","end":"2026-10-26T00:58:24.897252Z","steps":["trace[2138694820] 'process raft request' (duration: 1.012003602s)","trace[2138694820] 'compare' (duration: 92.417995ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:24.897364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.79267Z","time spent":"1.104651712s","remote":"127.0.0.1:52436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:58:24.930736Z","caller":"traceutil/trace.go:171","msg":"trace[217055655] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"904.478914ms","start":"2026-10-26T00:58:24.026233Z","end":"2026-10-26T00:58:24.930712Z","steps":["trace[217055655] 'process raft request' (duration: 904.118435ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.930841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.499436762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:58:24.930913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:24.026196Z","time spent":"904.617917ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:24.931058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174887325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T00:58:24.931134Z","caller":"traceutil/trace.go:171","msg":"trace[1779322577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1193; }","duration":"1.175033068s","start":"2026-10-26T00:58:23.756083Z","end":"2026-10-26T00:58:24.931116Z","steps":["trace[1779322577] 'agreement among raft nodes before linearized reading' (duration: 1.174779572s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.931174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.756055Z","time spent":"1.175109569s","remote":"127.0.0.1:52610","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-10-26T00:58:24.931236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174873144s","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-10-26T00:58:24.931279Z","caller":"traceutil/trace.go:171","msg":"trace[1374035377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1193; }","duration":"1.174918495s","start":"2026-10-26T00:58:23.756348Z","end":"2026-10-26T00:58:24.931266Z","steps":["trace[1374035377] 'agreement among raft nodes before linearized reading' (duration: 1.174737031s)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:24.930736Z","caller":"traceutil/trace.go:171","msg":"trace[414775601] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1243; }","duration":"1.050857543s","start":"2026-10-26T00:58:23.879853Z","end":"2026-10-26T00:58:24.93071Z","steps":["trace[414775601] 'read index received' (duration: 924.853306ms)","trace[414775601] 'applied index is now lower than readState.Index' (duration: 126.001687ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:24.931315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.756331Z","time spent":"1.174977106s","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-26T00:58:24.931419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047739751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-26T00:58:24.931446Z","caller":"traceutil/trace.go:171","msg":"trace[564968806] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1193; }","duration":"1.047824664s","start":"2026-10-26T00:58:23.883612Z","end":"2026-10-26T00:58:24.931437Z","steps":["trace[564968806] 'agreement among raft nodes before linearized reading' (duration: 1.047757372s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.931488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.91171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5274"} {"level":"warn","ts":"2026-10-26T00:58:24.931491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.883596Z","time spent":"1.047887025s","remote":"127.0.0.1:52522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-10-26T00:58:24.931515Z","caller":"traceutil/trace.go:171","msg":"trace[1682509990] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1193; }","duration":"907.012692ms","start":"2026-10-26T00:58:24.024493Z","end":"2026-10-26T00:58:24.931506Z","steps":["trace[1682509990] 'agreement among raft nodes before linearized reading' (duration: 906.90736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.931503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176563752s","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-10-26T00:58:24.931511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175465757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-26T00:58:24.931547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:24.024437Z","time spent":"907.098634ms","remote":"127.0.0.1:52530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5296,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-26T00:58:24.93157Z","caller":"traceutil/trace.go:171","msg":"trace[1614858949] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1193; }","duration":"1.176634703s","start":"2026-10-26T00:58:23.754916Z","end":"2026-10-26T00:58:24.931551Z","steps":["trace[1614858949] 'agreement among raft nodes before linearized reading' (duration: 1.176408538s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.931616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.754855Z","time spent":"1.176749636s","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T00:58:24.931623Z","caller":"traceutil/trace.go:171","msg":"trace[1699616269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1193; }","duration":"1.17564063s","start":"2026-10-26T00:58:23.755921Z","end":"2026-10-26T00:58:24.931562Z","steps":["trace[1699616269] 'agreement among raft nodes before linearized reading' (duration: 1.175377295s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.931722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.755917Z","time spent":"1.175745213s","remote":"127.0.0.1:52610","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-10-26T00:58:24.931938Z","caller":"traceutil/trace.go:171","msg":"trace[1630834992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"1.499557065s","start":"2026-10-26T00:58:23.431335Z","end":"2026-10-26T00:58:24.930892Z","steps":["trace[1630834992] 'agreement among raft nodes before linearized reading' (duration: 1.499452242s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.932341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.431311Z","time spent":"1.501012667s","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T00:58:25.405269Z","caller":"traceutil/trace.go:171","msg":"trace[1609867144] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"466.021084ms","start":"2026-10-26T00:58:24.939219Z","end":"2026-10-26T00:58:25.40524Z","steps":["trace[1609867144] 'process raft request' (duration: 465.741287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:25.405456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:24.939206Z","time spent":"466.176958ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:58:25.405298Z","caller":"traceutil/trace.go:171","msg":"trace[2067414356] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"465.423841ms","start":"2026-10-26T00:58:24.939852Z","end":"2026-10-26T00:58:25.405276Z","steps":["trace[2067414356] 'process raft request' (duration: 465.179575ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:25.405637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:24.93982Z","time spent":"465.790539ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:58:25.405265Z","caller":"traceutil/trace.go:171","msg":"trace[566768773] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"466.925026ms","start":"2026-10-26T00:58:24.938292Z","end":"2026-10-26T00:58:25.405217Z","steps":["trace[566768773] 'process raft request' (duration: 466.357802ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:25.405685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.209782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-26T00:58:25.405855Z","caller":"traceutil/trace.go:171","msg":"trace[1828249306] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1197; }","duration":"467.434256ms","start":"2026-10-26T00:58:24.938406Z","end":"2026-10-26T00:58:25.405841Z","steps":["trace[1828249306] 'agreement among raft nodes before linearized reading' (duration: 467.112789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:25.405803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:24.938208Z","time spent":"467.529379ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:25.405925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:24.938396Z","time spent":"467.515379ms","remote":"127.0.0.1:52626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-10-26T00:58:25.405143Z","caller":"traceutil/trace.go:171","msg":"trace[643701046] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"466.69071ms","start":"2026-10-26T00:58:24.93843Z","end":"2026-10-26T00:58:25.405121Z","steps":["trace[643701046] 'read index received' (duration: 45.39349ms)","trace[643701046] 'applied index is now lower than readState.Index' (duration: 421.296449ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:25.428625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.413571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:25.428685Z","caller":"traceutil/trace.go:171","msg":"trace[1997738819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1199; }","duration":"484.518693ms","start":"2026-10-26T00:58:24.944152Z","end":"2026-10-26T00:58:25.42867Z","steps":["trace[1997738819] 'agreement among raft nodes before linearized reading' (duration: 484.407141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:25.428713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:24.944141Z","time spent":"484.566274ms","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T00:58:25.42867Z","caller":"traceutil/trace.go:171","msg":"trace[1544703821] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"138.815527ms","start":"2026-10-26T00:58:25.289811Z","end":"2026-10-26T00:58:25.428627Z","steps":["trace[1544703821] 'process raft request' (duration: 138.704054ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:25.428869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.132548ms","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-10-26T00:58:25.42892Z","caller":"traceutil/trace.go:171","msg":"trace[1932944118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1199; }","duration":"268.250491ms","start":"2026-10-26T00:58:25.160654Z","end":"2026-10-26T00:58:25.428905Z","steps":["trace[1932944118] 'agreement among raft nodes before linearized reading' (duration: 267.991695ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:25.428941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.62975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:25.429003Z","caller":"traceutil/trace.go:171","msg":"trace[1865913012] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1199; }","duration":"418.735823ms","start":"2026-10-26T00:58:25.010252Z","end":"2026-10-26T00:58:25.428987Z","steps":["trace[1865913012] 'agreement among raft nodes before linearized reading' (duration: 418.62603ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:25.429024Z","caller":"traceutil/trace.go:171","msg":"trace[1708231889] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"420.043392ms","start":"2026-10-26T00:58:25.008972Z","end":"2026-10-26T00:58:25.429015Z","steps":["trace[1708231889] 'process raft request' (duration: 419.447458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:25.429036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:25.010234Z","time spent":"418.793934ms","remote":"127.0.0.1:45036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"warn","ts":"2026-10-26T00:58:25.429092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:25.008941Z","time spent":"420.102403ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:58:29.59708Z","caller":"traceutil/trace.go:171","msg":"trace[866881864] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"102.992595ms","start":"2026-10-26T00:58:29.494062Z","end":"2026-10-26T00:58:29.597054Z","steps":["trace[866881864] 'process raft request' (duration: 102.680398ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:31.606185Z","caller":"traceutil/trace.go:171","msg":"trace[645578410] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"124.877991ms","start":"2026-10-26T00:58:31.48128Z","end":"2026-10-26T00:58:31.606158Z","steps":["trace[645578410] 'process raft request' (duration: 124.719038ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:31.612189Z","caller":"traceutil/trace.go:171","msg":"trace[1549496268] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"128.702788ms","start":"2026-10-26T00:58:31.483446Z","end":"2026-10-26T00:58:31.612148Z","steps":["trace[1549496268] 'process raft request' (duration: 128.541334ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:33.273312Z","caller":"traceutil/trace.go:171","msg":"trace[403550912] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"250.698473ms","start":"2026-10-26T00:58:33.022595Z","end":"2026-10-26T00:58:33.273294Z","steps":["trace[403550912] 'read index received' (duration: 250.632442ms)","trace[403550912] 'applied index is now lower than readState.Index' (duration: 65.031µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:33.273406Z","caller":"traceutil/trace.go:171","msg":"trace[361072136] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"294.490925ms","start":"2026-10-26T00:58:32.978901Z","end":"2026-10-26T00:58:33.273392Z","steps":["trace[361072136] 'process raft request' (duration: 294.25299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:33.273692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.065741ms","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-10-26T00:58:33.273771Z","caller":"traceutil/trace.go:171","msg":"trace[957188239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1251; }","duration":"251.166283ms","start":"2026-10-26T00:58:33.022586Z","end":"2026-10-26T00:58:33.273752Z","steps":["trace[957188239] 'agreement among raft nodes before linearized reading' (duration: 250.920248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:33.273835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.359446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.152\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-26T00:58:33.273863Z","caller":"traceutil/trace.go:171","msg":"trace[877319843] range","detail":"{range_begin:/registry/masterleases/162.253.55.152; range_end:; response_count:1; response_revision:1251; }","duration":"157.429918ms","start":"2026-10-26T00:58:33.116425Z","end":"2026-10-26T00:58:33.273855Z","steps":["trace[877319843] 'agreement among raft nodes before linearized reading' (duration: 157.298135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:34.168302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.277539ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-10-26T00:58:34.168514Z","caller":"traceutil/trace.go:171","msg":"trace[723882284] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1304; }","duration":"737.16728ms","start":"2026-10-26T00:58:33.43133Z","end":"2026-10-26T00:58:34.168498Z","steps":["trace[723882284] 'read index received' (duration: 121.31006ms)","trace[723882284] 'applied index is now lower than readState.Index' (duration: 615.85658ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:34.169021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.692044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:34.16908Z","caller":"traceutil/trace.go:171","msg":"trace[227555496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1252; }","duration":"737.775456ms","start":"2026-10-26T00:58:33.431276Z","end":"2026-10-26T00:58:34.169051Z","steps":["trace[227555496] 'agreement among raft nodes before linearized reading' (duration: 737.297564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:34.169105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:33.431253Z","time spent":"737.845527ms","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T00:58:34.1693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.363734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-10-26T00:58:34.169444Z","caller":"traceutil/trace.go:171","msg":"trace[353779555] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1252; }","duration":"703.509828ms","start":"2026-10-26T00:58:33.465876Z","end":"2026-10-26T00:58:34.169386Z","steps":["trace[353779555] 'agreement among raft nodes before linearized reading' (duration: 703.018497ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:34.169534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:33.465866Z","time spent":"703.652711ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-10-26T00:58:34.168592Z","caller":"traceutil/trace.go:171","msg":"trace[1556797328] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"890.053577ms","start":"2026-10-26T00:58:33.278493Z","end":"2026-10-26T00:58:34.168547Z","steps":["trace[1556797328] 'process raft request' (duration: 889.883623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:34.170001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:33.278439Z","time spent":"891.476199ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:34.170128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.178341ms","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-10-26T00:58:34.17013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.078157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T00:58:34.17017Z","caller":"traceutil/trace.go:171","msg":"trace[983392955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1252; }","duration":"553.239553ms","start":"2026-10-26T00:58:33.616921Z","end":"2026-10-26T00:58:34.170161Z","steps":["trace[983392955] 'agreement among raft nodes before linearized reading' (duration: 552.852213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:34.170199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:33.61691Z","time spent":"553.276573ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-26T00:58:34.170195Z","caller":"traceutil/trace.go:171","msg":"trace[1853136002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1252; }","duration":"560.171458ms","start":"2026-10-26T00:58:33.610009Z","end":"2026-10-26T00:58:34.17018Z","steps":["trace[1853136002] 'agreement among raft nodes before linearized reading' (duration: 559.918902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:34.170244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:33.609997Z","time spent":"560.22615ms","remote":"127.0.0.1:52610","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-10-26T00:58:34.170201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.515472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-26T00:58:34.170307Z","caller":"traceutil/trace.go:171","msg":"trace[1475623226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1252; }","duration":"689.654945ms","start":"2026-10-26T00:58:33.480632Z","end":"2026-10-26T00:58:34.170287Z","steps":["trace[1475623226] 'agreement among raft nodes before linearized reading' (duration: 689.175403ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:34.170361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:33.480621Z","time spent":"689.729576ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T00:58:34.170438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:33.274634Z","time spent":"895.801698ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-26T00:58:34.170608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.980907ms","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-10-26T00:58:34.170686Z","caller":"traceutil/trace.go:171","msg":"trace[997826948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1252; }","duration":"520.073541ms","start":"2026-10-26T00:58:33.650573Z","end":"2026-10-26T00:58:34.170647Z","steps":["trace[997826948] 'agreement among raft nodes before linearized reading' (duration: 519.723563ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:34.170733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:33.650551Z","time spent":"520.171432ms","remote":"127.0.0.1:52610","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-10-26T00:58:34.170783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.463217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T00:58:34.170879Z","caller":"traceutil/trace.go:171","msg":"trace[1449131195] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1252; }","duration":"145.632151ms","start":"2026-10-26T00:58:34.025213Z","end":"2026-10-26T00:58:34.170845Z","steps":["trace[1449131195] 'agreement among raft nodes before linearized reading' (duration: 144.933676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:41.643907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.123704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:41.644009Z","caller":"traceutil/trace.go:171","msg":"trace[553971377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"211.297209ms","start":"2026-10-26T00:58:41.432688Z","end":"2026-10-26T00:58:41.643985Z","steps":["trace[553971377] 'range keys from in-memory index tree' (duration: 210.92016ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:59:42.752361Z","caller":"traceutil/trace.go:171","msg":"trace[284177429] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"102.436253ms","start":"2026-10-26T00:59:42.649902Z","end":"2026-10-26T00:59:42.752338Z","steps":["trace[284177429] 'process raft request' (duration: 102.231318ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:00:23.134975Z","caller":"traceutil/trace.go:171","msg":"trace[174052896] linearizableReadLoop","detail":"{readStateIndex:1851; appliedIndex:1850; }","duration":"154.957649ms","start":"2026-10-26T01:00:22.979996Z","end":"2026-10-26T01:00:23.134954Z","steps":["trace[174052896] 'read index received' (duration: 154.810056ms)","trace[174052896] 'applied index is now lower than readState.Index' (duration: 146.743µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:00:23.135062Z","caller":"traceutil/trace.go:171","msg":"trace[1775017381] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"167.928155ms","start":"2026-10-26T01:00:22.967095Z","end":"2026-10-26T01:00:23.135024Z","steps":["trace[1775017381] 'process raft request' (duration: 167.749211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:00:23.135183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.170364ms","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-10-26T01:00:23.135218Z","caller":"traceutil/trace.go:171","msg":"trace[620728912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1776; }","duration":"155.259086ms","start":"2026-10-26T01:00:22.979948Z","end":"2026-10-26T01:00:23.135207Z","steps":["trace[620728912] 'agreement among raft nodes before linearized reading' (duration: 155.129023ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:00:23.267427Z","caller":"traceutil/trace.go:171","msg":"trace[946500600] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"128.108657ms","start":"2026-10-26T01:00:23.139296Z","end":"2026-10-26T01:00:23.267405Z","steps":["trace[946500600] 'process raft request' (duration: 128.030505ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:00:23.267622Z","caller":"traceutil/trace.go:171","msg":"trace[1663432376] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"127.829142ms","start":"2026-10-26T01:00:23.139776Z","end":"2026-10-26T01:00:23.267605Z","steps":["trace[1663432376] 'process raft request' (duration: 127.590835ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:00:23.267639Z","caller":"traceutil/trace.go:171","msg":"trace[992479793] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"128.357783ms","start":"2026-10-26T01:00:23.139237Z","end":"2026-10-26T01:00:23.267595Z","steps":["trace[992479793] 'process raft request' (duration: 80.38823ms)","trace[992479793] 'compare' (duration: 47.570554ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:00:23.26768Z","caller":"traceutil/trace.go:171","msg":"trace[1390322322] linearizableReadLoop","detail":"{readStateIndex:1855; appliedIndex:1851; }","duration":"126.907271ms","start":"2026-10-26T01:00:23.140749Z","end":"2026-10-26T01:00:23.267656Z","steps":["trace[1390322322] 'read index received' (duration: 37.269939ms)","trace[1390322322] 'applied index is now lower than readState.Index' (duration: 89.636142ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:00:23.268121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.35385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-10-26T01:00:23.268166Z","caller":"traceutil/trace.go:171","msg":"trace[1699582394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1780; }","duration":"127.413112ms","start":"2026-10-26T01:00:23.140742Z","end":"2026-10-26T01:00:23.268155Z","steps":["trace[1699582394] 'agreement among raft nodes before linearized reading' (duration: 127.001433ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:06.072019Z","caller":"traceutil/trace.go:171","msg":"trace[791571734] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"178.299742ms","start":"2026-10-26T01:03:05.893691Z","end":"2026-10-26T01:03:06.071991Z","steps":["trace[791571734] 'process raft request' (duration: 115.873469ms)","trace[791571734] 'compare' (duration: 62.266789ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:03:14.569812Z","caller":"traceutil/trace.go:171","msg":"trace[1816513286] transaction","detail":"{read_only:false; response_revision:2586; number_of_response:1; }","duration":"442.213796ms","start":"2026-10-26T01:03:14.127577Z","end":"2026-10-26T01:03:14.56979Z","steps":["trace[1816513286] 'process raft request' (duration: 442.078753ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:14.569894Z","caller":"traceutil/trace.go:171","msg":"trace[1035051042] linearizableReadLoop","detail":"{readStateIndex:2697; appliedIndex:2697; }","duration":"366.811317ms","start":"2026-10-26T01:03:14.20306Z","end":"2026-10-26T01:03:14.569871Z","steps":["trace[1035051042] 'read index received' (duration: 366.800447ms)","trace[1035051042] 'applied index is now lower than readState.Index' (duration: 8.91µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:03:14.569963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:03:14.127538Z","time spent":"442.346638ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:03:14.570126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.054263ms","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-10-26T01:03:14.570182Z","caller":"traceutil/trace.go:171","msg":"trace[185177486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2586; }","duration":"367.140775ms","start":"2026-10-26T01:03:14.203028Z","end":"2026-10-26T01:03:14.570169Z","steps":["trace[185177486] 'agreement among raft nodes before linearized reading' (duration: 366.93972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:14.570228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:03:14.203011Z","time spent":"367.202338ms","remote":"127.0.0.1:52610","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-10-26T01:03:14.576242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.192481ms","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-10-26T01:03:14.576372Z","caller":"traceutil/trace.go:171","msg":"trace[1397776602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2587; }","duration":"365.362375ms","start":"2026-10-26T01:03:14.210983Z","end":"2026-10-26T01:03:14.576346Z","steps":["trace[1397776602] 'agreement among raft nodes before linearized reading' (duration: 365.063628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:14.576461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:03:14.210973Z","time spent":"365.477607ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-26T01:03:14.576563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.179426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T01:03:14.576634Z","caller":"traceutil/trace.go:171","msg":"trace[162731251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2587; }","duration":"360.275058ms","start":"2026-10-26T01:03:14.216344Z","end":"2026-10-26T01:03:14.576619Z","steps":["trace[162731251] 'agreement among raft nodes before linearized reading' (duration: 360.080074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:14.576684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:03:14.216333Z","time spent":"360.324859ms","remote":"127.0.0.1:52610","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-10-26T01:03:14.577079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.853577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-26T01:03:14.577204Z","caller":"traceutil/trace.go:171","msg":"trace[1365997937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2587; }","duration":"363.043181ms","start":"2026-10-26T01:03:14.214123Z","end":"2026-10-26T01:03:14.577167Z","steps":["trace[1365997937] 'agreement among raft nodes before linearized reading' (duration: 362.55295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:14.577333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:03:14.214105Z","time spent":"363.157155ms","remote":"127.0.0.1:52610","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\" "}