{"level":"warn","ts":"2026-10-26T00:47:43.497678Z","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:47:43.497829Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.10: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.10:2380","--initial-cluster=instance=https://162.253.55.10:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.10:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.10: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:47:43.497927Z","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:47:43.49796Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-10-26T00:47:43.498007Z","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:47:43.498785Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"]} {"level":"info","ts":"2026-10-26T00:47:43.49891Z","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.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.10: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:47:43.524722Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"25.512682ms"} {"level":"info","ts":"2026-10-26T00:47:43.545324Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"373d5d49ce4adaff","cluster-id":"467836e907a15d60"} {"level":"info","ts":"2026-10-26T00:47:43.545451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T00:47:43.545492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 0"} {"level":"info","ts":"2026-10-26T00:47:43.545512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 373d5d49ce4adaff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T00:47:43.545524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became follower at term 1"} {"level":"info","ts":"2026-10-26T00:47:43.545593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"warn","ts":"2026-10-26T00:47:43.579909Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T00:47:43.582373Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T00:47:43.590859Z","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:47:43.597981Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"373d5d49ce4adaff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T00:47:43.60045Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"373d5d49ce4adaff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T00:47:43.601547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff switched to configuration voters=(3980440217240394495)"} {"level":"info","ts":"2026-10-26T00:47:43.601821Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","added-peer-id":"373d5d49ce4adaff","added-peer-peer-urls":["https://162.253.55.10:2380"]} {"level":"info","ts":"2026-10-26T00:47:43.602109Z","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:47:43.602485Z","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:47:43.602533Z","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:47:43.604753Z","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:47:43.604817Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-10-26T00:47:43.604967Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.10:2380"} {"level":"info","ts":"2026-10-26T00:47:43.606853Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"373d5d49ce4adaff","initial-advertise-peer-urls":["https://162.253.55.10:2380"],"listen-peer-urls":["https://162.253.55.10:2380"],"advertise-client-urls":["https://162.253.55.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T00:47:43.606908Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T00:47:44.346069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T00:47:44.346152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T00:47:44.346232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgPreVoteResp from 373d5d49ce4adaff at term 1"} {"level":"info","ts":"2026-10-26T00:47:44.346262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became candidate at term 2"} {"level":"info","ts":"2026-10-26T00:47:44.346273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff received MsgVoteResp from 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-10-26T00:47:44.346327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"373d5d49ce4adaff became leader at term 2"} {"level":"info","ts":"2026-10-26T00:47:44.346347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 373d5d49ce4adaff elected leader 373d5d49ce4adaff at term 2"} {"level":"info","ts":"2026-10-26T00:47:44.382249Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:47:44.392437Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"373d5d49ce4adaff","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.10:2379]}","request-path":"/0/members/373d5d49ce4adaff/attributes","cluster-id":"467836e907a15d60","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T00:47:44.392527Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T00:47:44.392528Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T00:47:44.392806Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T00:47:44.392922Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T00:47:44.395898Z","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:47:44.397196Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.10:2379"} {"level":"info","ts":"2026-10-26T00:47:44.39762Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"467836e907a15d60","local-member-id":"373d5d49ce4adaff","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:47:44.398954Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:47:44.39919Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-10-26T00:47:44.614282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.900876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-26T00:47:44.614393Z","caller":"traceutil/trace.go:171","msg":"trace[352979688] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"161.08084ms","start":"2026-10-26T00:47:44.453293Z","end":"2026-10-26T00:47:44.614374Z","steps":["trace[352979688] 'count revisions from in-memory index tree' (duration: 160.856126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:44.614606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.398764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-26T00:47:44.614652Z","caller":"traceutil/trace.go:171","msg":"trace[814452311] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"161.443195ms","start":"2026-10-26T00:47:44.45319Z","end":"2026-10-26T00:47:44.614633Z","steps":["trace[814452311] 'range keys from in-memory index tree' (duration: 161.197802ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:44.614894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.900384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-26T00:47:44.614953Z","caller":"traceutil/trace.go:171","msg":"trace[1983966346] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"102.002256ms","start":"2026-10-26T00:47:44.512936Z","end":"2026-10-26T00:47:44.614939Z","steps":["trace[1983966346] 'range keys from in-memory index tree' (duration: 101.802463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:44.615262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.820112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-26T00:47:44.61537Z","caller":"traceutil/trace.go:171","msg":"trace[1859549247] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"101.953705ms","start":"2026-10-26T00:47:44.513395Z","end":"2026-10-26T00:47:44.615349Z","steps":["trace[1859549247] 'count revisions from in-memory index tree' (duration: 101.726611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:44.616913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.630259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-26T00:47:44.617034Z","caller":"traceutil/trace.go:171","msg":"trace[91546284] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"117.797093ms","start":"2026-10-26T00:47:44.499198Z","end":"2026-10-26T00:47:44.616995Z","steps":["trace[91546284] 'count revisions from in-memory index tree' (duration: 115.446156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:48.816597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.961593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:47:48.816736Z","caller":"traceutil/trace.go:171","msg":"trace[1260335031] linearizableReadLoop","detail":"{readStateIndex:220; appliedIndex:219; }","duration":"156.438412ms","start":"2026-10-26T00:47:48.660276Z","end":"2026-10-26T00:47:48.816715Z","steps":["trace[1260335031] 'read index received' (duration: 5.611106ms)","trace[1260335031] 'applied index is now lower than readState.Index' (duration: 150.825616ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:47:48.816835Z","caller":"traceutil/trace.go:171","msg":"trace[1534579064] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"156.913329ms","start":"2026-10-26T00:47:48.659911Z","end":"2026-10-26T00:47:48.816824Z","steps":["trace[1534579064] 'compare' (duration: 149.707788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:48.817036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.738877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-10-26T00:47:48.81713Z","caller":"traceutil/trace.go:171","msg":"trace[512315414] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:214; }","duration":"156.867939ms","start":"2026-10-26T00:47:48.660242Z","end":"2026-10-26T00:47:48.81711Z","steps":["trace[512315414] 'agreement among raft nodes before linearized reading' (duration: 156.659175ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:48.817096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.243671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-10-26T00:47:48.817386Z","caller":"traceutil/trace.go:171","msg":"trace[259864301] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:214; }","duration":"102.596846ms","start":"2026-10-26T00:47:48.714766Z","end":"2026-10-26T00:47:48.817363Z","steps":["trace[259864301] 'agreement among raft nodes before linearized reading' (duration: 102.181609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:51.227364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306884774s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:47:51.227953Z","caller":"traceutil/trace.go:171","msg":"trace[1481950508] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"1.379853279s","start":"2026-10-26T00:47:49.84807Z","end":"2026-10-26T00:47:51.227923Z","steps":["trace[1481950508] 'process raft request' (duration: 72.242044ms)","trace[1481950508] 'compare' (duration: 1.305904269s)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:47:51.228021Z","caller":"traceutil/trace.go:171","msg":"trace[1561491768] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:251; }","duration":"1.323853493s","start":"2026-10-26T00:47:49.904147Z","end":"2026-10-26T00:47:51.228001Z","steps":["trace[1561491768] 'read index received' (duration: 16.446892ms)","trace[1561491768] 'applied index is now lower than readState.Index' (duration: 1.307405581s)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:47:51.22806Z","caller":"traceutil/trace.go:171","msg":"trace[1118163920] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"1.356909949s","start":"2026-10-26T00:47:49.871105Z","end":"2026-10-26T00:47:51.228015Z","steps":["trace[1118163920] 'process raft request' (duration: 1.356736616s)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:51.228108Z","caller":"traceutil/trace.go:171","msg":"trace[953200830] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"1.322692975s","start":"2026-10-26T00:47:49.9054Z","end":"2026-10-26T00:47:51.228093Z","steps":["trace[953200830] 'process raft request' (duration: 1.322530123s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:51.228088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:49.848048Z","time spent":"1.379996801s","remote":"127.0.0.1:49226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":373,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:47:51.228222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:49.905376Z","time spent":"1.322775187s","remote":"127.0.0.1:48962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:47:51.22826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:49.871065Z","time spent":"1.357092062s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:47:51.228296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324133118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:47:51.229398Z","caller":"traceutil/trace.go:171","msg":"trace[1284292948] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:248; }","duration":"1.325299834s","start":"2026-10-26T00:47:49.904071Z","end":"2026-10-26T00:47:51.229371Z","steps":["trace[1284292948] 'agreement among raft nodes before linearized reading' (duration: 1.324035125s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:51.229488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:49.904033Z","time spent":"1.325429647s","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-10-26T00:47:52.488898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.619942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-10-26T00:47:52.489386Z","caller":"traceutil/trace.go:171","msg":"trace[713675356] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"1.255095249s","start":"2026-10-26T00:47:51.234268Z","end":"2026-10-26T00:47:52.489364Z","steps":["trace[713675356] 'process raft request' (duration: 1.255044878s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.48947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:51.234261Z","time spent":"1.25517853s","remote":"127.0.0.1:48962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:47:52.489564Z","caller":"traceutil/trace.go:171","msg":"trace[1348711380] linearizableReadLoop","detail":"{readStateIndex:260; appliedIndex:254; }","duration":"1.261444566s","start":"2026-10-26T00:47:51.228059Z","end":"2026-10-26T00:47:52.489504Z","steps":["trace[1348711380] 'read index received' (duration: 363.021333ms)","trace[1348711380] 'applied index is now lower than readState.Index' (duration: 898.414523ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:47:52.489618Z","caller":"traceutil/trace.go:171","msg":"trace[890869143] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"2.46035549s","start":"2026-10-26T00:47:50.029009Z","end":"2026-10-26T00:47:52.489364Z","steps":["trace[890869143] 'process raft request' (duration: 2.460145247s)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:52.489835Z","caller":"traceutil/trace.go:171","msg":"trace[1807789890] transaction","detail":"{read_only:false; number_of_response:0; response_revision:250; }","duration":"2.460695546s","start":"2026-10-26T00:47:50.029071Z","end":"2026-10-26T00:47:52.489767Z","steps":["trace[1807789890] 'process raft request' (duration: 2.460147347s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.489898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.283394737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:47:52.489942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.585752085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-10-26T00:47:52.489946Z","caller":"traceutil/trace.go:171","msg":"trace[505282533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:252; }","duration":"2.283459708s","start":"2026-10-26T00:47:50.206469Z","end":"2026-10-26T00:47:52.489929Z","steps":["trace[505282533] 'agreement among raft nodes before linearized reading' (duration: 2.283391747s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.489986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256431801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:47:52.490006Z","caller":"traceutil/trace.go:171","msg":"trace[2071442205] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:252; }","duration":"2.585835527s","start":"2026-10-26T00:47:49.904156Z","end":"2026-10-26T00:47:52.489992Z","steps":["trace[2071442205] 'agreement among raft nodes before linearized reading' (duration: 2.585752806s)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:52.490032Z","caller":"traceutil/trace.go:171","msg":"trace[1770786378] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:252; }","duration":"1.256587703s","start":"2026-10-26T00:47:51.233431Z","end":"2026-10-26T00:47:52.490018Z","steps":["trace[1770786378] 'agreement among raft nodes before linearized reading' (duration: 1.256493591s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.490034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:50.206457Z","time spent":"2.283548209s","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-26T00:47:52.490049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:49.904033Z","time spent":"2.58600706s","remote":"127.0.0.1:49274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-10-26T00:47:52.490078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:51.233408Z","time spent":"1.256660023s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" "} {"level":"warn","ts":"2026-10-26T00:47:52.490081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:50.029035Z","time spent":"2.4609103s","remote":"127.0.0.1:49084","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:47:52.489852Z","caller":"traceutil/trace.go:171","msg":"trace[178618246] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"1.255706039s","start":"2026-10-26T00:47:51.234128Z","end":"2026-10-26T00:47:52.489834Z","steps":["trace[178618246] 'process raft request' (duration: 1.25514556s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.490274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:51.234102Z","time spent":"1.256088554s","remote":"127.0.0.1:49242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":400,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:47:52.490297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255730979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-10-26T00:47:52.490363Z","caller":"traceutil/trace.go:171","msg":"trace[38056] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:252; }","duration":"1.255866111s","start":"2026-10-26T00:47:51.234482Z","end":"2026-10-26T00:47:52.490348Z","steps":["trace[38056] 'agreement among raft nodes before linearized reading' (duration: 1.25575742s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.490421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:51.234455Z","time spent":"1.255949072s","remote":"127.0.0.1:49076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4038,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-26T00:47:52.490455Z","caller":"traceutil/trace.go:171","msg":"trace[1930099727] transaction","detail":"{read_only:false; number_of_response:0; response_revision:250; }","duration":"2.461147933s","start":"2026-10-26T00:47:50.029295Z","end":"2026-10-26T00:47:52.490443Z","steps":["trace[1930099727] 'process raft request' (duration: 2.459948154s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.48987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.28948382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-10-26T00:47:52.490519Z","caller":"traceutil/trace.go:171","msg":"trace[717545778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:252; }","duration":"2.290193791s","start":"2026-10-26T00:47:50.200304Z","end":"2026-10-26T00:47:52.490498Z","steps":["trace[717545778] 'agreement among raft nodes before linearized reading' (duration: 2.28944182s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.490541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:50.029281Z","time spent":"2.461211573s","remote":"127.0.0.1:49084","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:47:52.490586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:50.20029Z","time spent":"2.290265541s","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T00:47:52.489824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.287912715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-26T00:47:52.490628Z","caller":"traceutil/trace.go:171","msg":"trace[736749160] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"2.462393701s","start":"2026-10-26T00:47:50.028217Z","end":"2026-10-26T00:47:52.490611Z","steps":["trace[736749160] 'process raft request' (duration: 2.460829878s)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:52.490661Z","caller":"traceutil/trace.go:171","msg":"trace[2035752248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:252; }","duration":"2.288794009s","start":"2026-10-26T00:47:50.201852Z","end":"2026-10-26T00:47:52.490646Z","steps":["trace[2035752248] 'agreement among raft nodes before linearized reading' (duration: 2.287825575s)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:52.490684Z","caller":"traceutil/trace.go:171","msg":"trace[1448357825] transaction","detail":"{read_only:false; number_of_response:0; response_revision:249; }","duration":"2.462252229s","start":"2026-10-26T00:47:50.028419Z","end":"2026-10-26T00:47:52.490671Z","steps":["trace[1448357825] 'process raft request' (duration: 2.460709486s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.490703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:50.028167Z","time spent":"2.462502103s","remote":"127.0.0.1:49084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:47:52.490718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:50.20184Z","time spent":"2.28886446s","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T00:47:52.490759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:50.028397Z","time spent":"2.46232802s","remote":"127.0.0.1:49084","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:47:52.491225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.850697491s","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:47:52.491288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:50.028998Z","time spent":"2.461233904s","remote":"127.0.0.1:49084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:47:52.491313Z","caller":"traceutil/trace.go:171","msg":"trace[1033216084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:252; }","duration":"1.850840053s","start":"2026-10-26T00:47:50.640454Z","end":"2026-10-26T00:47:52.491294Z","steps":["trace[1033216084] 'agreement among raft nodes before linearized reading' (duration: 1.849075536s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.491347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:50.640431Z","time spent":"1.850908244s","remote":"127.0.0.1:49148","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":"info","ts":"2026-10-26T00:47:52.48938Z","caller":"traceutil/trace.go:171","msg":"trace[422095000] transaction","detail":"{read_only:false; number_of_response:0; response_revision:248; }","duration":"2.487867359s","start":"2026-10-26T00:47:50.001294Z","end":"2026-10-26T00:47:52.489161Z","steps":["trace[422095000] 'process raft request' (duration: 1.589904173s)","trace[422095000] 'compare' (duration: 897.54403ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:47:52.491644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:47:50.001254Z","time spent":"2.490356548s","remote":"127.0.0.1:49076","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:47:52.650906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.460865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:47:52.651014Z","caller":"traceutil/trace.go:171","msg":"trace[366595495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:261; }","duration":"145.600828ms","start":"2026-10-26T00:47:52.505393Z","end":"2026-10-26T00:47:52.650994Z","steps":["trace[366595495] 'agreement among raft nodes before linearized reading' (duration: 145.425305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.651057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.801076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-10-26T00:47:52.651142Z","caller":"traceutil/trace.go:171","msg":"trace[1631534484] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:261; }","duration":"150.954227ms","start":"2026-10-26T00:47:52.500171Z","end":"2026-10-26T00:47:52.651125Z","steps":["trace[1631534484] 'agreement among raft nodes before linearized reading' (duration: 150.744854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.651075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.818919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-26T00:47:52.651319Z","caller":"traceutil/trace.go:171","msg":"trace[427787112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:261; }","duration":"128.092573ms","start":"2026-10-26T00:47:52.523206Z","end":"2026-10-26T00:47:52.651299Z","steps":["trace[427787112] 'agreement among raft nodes before linearized reading' (duration: 127.790299ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:52.651559Z","caller":"traceutil/trace.go:171","msg":"trace[1773021309] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"127.088538ms","start":"2026-10-26T00:47:52.524415Z","end":"2026-10-26T00:47:52.651504Z","steps":["trace[1773021309] 'process raft request' (duration: 65.867444ms)","trace[1773021309] 'compare' (duration: 60.30306ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:47:52.692758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.354585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-10-26T00:47:52.69286Z","caller":"traceutil/trace.go:171","msg":"trace[416292199] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:264; }","duration":"102.511718ms","start":"2026-10-26T00:47:52.590327Z","end":"2026-10-26T00:47:52.692839Z","steps":["trace[416292199] 'agreement among raft nodes before linearized reading' (duration: 102.326325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.692779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.569198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:47:52.692999Z","caller":"traceutil/trace.go:171","msg":"trace[803477357] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:264; }","duration":"102.826542ms","start":"2026-10-26T00:47:52.590149Z","end":"2026-10-26T00:47:52.692975Z","steps":["trace[803477357] 'agreement among raft nodes before linearized reading' (duration: 102.568738ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:52.812297Z","caller":"traceutil/trace.go:171","msg":"trace[295641349] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"137.740258ms","start":"2026-10-26T00:47:52.674536Z","end":"2026-10-26T00:47:52.812276Z","steps":["trace[295641349] 'process raft request' (duration: 137.508675ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:52.812322Z","caller":"traceutil/trace.go:171","msg":"trace[843946184] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:274; }","duration":"119.675186ms","start":"2026-10-26T00:47:52.692618Z","end":"2026-10-26T00:47:52.812293Z","steps":["trace[843946184] 'read index received' (duration: 58.450822ms)","trace[843946184] 'applied index is now lower than readState.Index' (duration: 61.221684ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:47:52.812447Z","caller":"traceutil/trace.go:171","msg":"trace[1421053468] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"150.301387ms","start":"2026-10-26T00:47:52.662134Z","end":"2026-10-26T00:47:52.812435Z","steps":["trace[1421053468] 'process raft request' (duration: 88.957802ms)","trace[1421053468] 'compare' (duration: 60.827568ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:47:52.81271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.348871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-10-26T00:47:52.812811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.663415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-10-26T00:47:52.812835Z","caller":"traceutil/trace.go:171","msg":"trace[569071832] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:267; }","duration":"115.540663ms","start":"2026-10-26T00:47:52.697251Z","end":"2026-10-26T00:47:52.812792Z","steps":["trace[569071832] 'agreement among raft nodes before linearized reading' (duration: 115.34092ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:52.812867Z","caller":"traceutil/trace.go:171","msg":"trace[1250245143] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:267; }","duration":"113.722425ms","start":"2026-10-26T00:47:52.699129Z","end":"2026-10-26T00:47:52.812852Z","steps":["trace[1250245143] 'agreement among raft nodes before linearized reading' (duration: 113.610204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:52.813009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.325479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:47:52.813149Z","caller":"traceutil/trace.go:171","msg":"trace[211347120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:267; }","duration":"160.776116ms","start":"2026-10-26T00:47:52.652348Z","end":"2026-10-26T00:47:52.813124Z","steps":["trace[211347120] 'agreement among raft nodes before linearized reading' (duration: 160.082345ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:52.813415Z","caller":"traceutil/trace.go:171","msg":"trace[904907125] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"108.091051ms","start":"2026-10-26T00:47:52.704594Z","end":"2026-10-26T00:47:52.812685Z","steps":["trace[904907125] 'process raft request' (duration: 107.581384ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:53.028551Z","caller":"traceutil/trace.go:171","msg":"trace[26695623] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:291; }","duration":"122.780811ms","start":"2026-10-26T00:47:52.905737Z","end":"2026-10-26T00:47:53.028518Z","steps":["trace[26695623] 'read index received' (duration: 44.980078ms)","trace[26695623] 'applied index is now lower than readState.Index' (duration: 77.799623ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:47:53.02865Z","caller":"traceutil/trace.go:171","msg":"trace[835846528] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"127.174957ms","start":"2026-10-26T00:47:52.901448Z","end":"2026-10-26T00:47:53.028623Z","steps":["trace[835846528] 'process raft request' (duration: 49.364945ms)","trace[835846528] 'compare' (duration: 77.565949ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:47:53.028725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.943594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:47:53.028794Z","caller":"traceutil/trace.go:171","msg":"trace[1939164726] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:282; }","duration":"123.117017ms","start":"2026-10-26T00:47:52.905663Z","end":"2026-10-26T00:47:53.02878Z","steps":["trace[1939164726] 'agreement among raft nodes before linearized reading' (duration: 122.964663ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:53.233831Z","caller":"traceutil/trace.go:171","msg":"trace[1286979819] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"117.358453ms","start":"2026-10-26T00:47:53.116444Z","end":"2026-10-26T00:47:53.233802Z","steps":["trace[1286979819] 'process raft request' (duration: 117.304403ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:53.234095Z","caller":"traceutil/trace.go:171","msg":"trace[1327089936] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"118.700494ms","start":"2026-10-26T00:47:53.115356Z","end":"2026-10-26T00:47:53.234056Z","steps":["trace[1327089936] 'process raft request' (duration: 52.949686ms)","trace[1327089936] 'compare' (duration: 65.279441ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:47:54.868875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.277998ms","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:47:54.869105Z","caller":"traceutil/trace.go:171","msg":"trace[1652044999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:314; }","duration":"172.413999ms","start":"2026-10-26T00:47:54.696543Z","end":"2026-10-26T00:47:54.868957Z","steps":["trace[1652044999] 'range keys from in-memory index tree' (duration: 172.075044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:54.869549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.881748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-10-26T00:47:54.86967Z","caller":"traceutil/trace.go:171","msg":"trace[1802524622] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:314; }","duration":"153.07734ms","start":"2026-10-26T00:47:54.716571Z","end":"2026-10-26T00:47:54.869648Z","steps":["trace[1802524622] 'range keys from in-memory index tree' (duration: 152.766355ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:54.870115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.959619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4139"} {"level":"info","ts":"2026-10-26T00:47:54.8702Z","caller":"traceutil/trace.go:171","msg":"trace[1684905413] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:314; }","duration":"159.061821ms","start":"2026-10-26T00:47:54.71112Z","end":"2026-10-26T00:47:54.870182Z","steps":["trace[1684905413] 'range keys from in-memory index tree' (duration: 158.156187ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:54.869313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.617826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-10-26T00:47:54.873893Z","caller":"traceutil/trace.go:171","msg":"trace[1418364852] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:314; }","duration":"105.213644ms","start":"2026-10-26T00:47:54.768664Z","end":"2026-10-26T00:47:54.873878Z","steps":["trace[1418364852] 'range keys from in-memory index tree' (duration: 100.566505ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:55.160234Z","caller":"traceutil/trace.go:171","msg":"trace[993847733] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"171.822852ms","start":"2026-10-26T00:47:54.988354Z","end":"2026-10-26T00:47:55.160177Z","steps":["trace[993847733] 'process raft request' (duration: 150.329572ms)","trace[993847733] 'compare' (duration: 21.102325ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:47:55.160359Z","caller":"traceutil/trace.go:171","msg":"trace[1097197408] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"169.422746ms","start":"2026-10-26T00:47:54.990919Z","end":"2026-10-26T00:47:55.160342Z","steps":["trace[1097197408] 'process raft request' (duration: 169.120611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:47:55.160676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.970931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-10-26T00:47:55.1624Z","caller":"traceutil/trace.go:171","msg":"trace[1132642312] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:329; }","duration":"144.735797ms","start":"2026-10-26T00:47:55.017639Z","end":"2026-10-26T00:47:55.162375Z","steps":["trace[1132642312] 'agreement among raft nodes before linearized reading' (duration: 142.865399ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:47:55.171313Z","caller":"traceutil/trace.go:171","msg":"trace[1982361832] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:337; }","duration":"142.425023ms","start":"2026-10-26T00:47:55.01768Z","end":"2026-10-26T00:47:55.160105Z","steps":["trace[1982361832] 'read index received' (duration: 120.988734ms)","trace[1982361832] 'applied index is now lower than readState.Index' (duration: 21.434739ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:47:55.202263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.114024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-10-26T00:47:55.202368Z","caller":"traceutil/trace.go:171","msg":"trace[266099287] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:330; }","duration":"135.254727ms","start":"2026-10-26T00:47:55.067099Z","end":"2026-10-26T00:47:55.202354Z","steps":["trace[266099287] 'agreement among raft nodes before linearized reading' (duration: 135.077104ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:48:35.873811Z","caller":"traceutil/trace.go:171","msg":"trace[1888901705] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"648.821378ms","start":"2026-10-26T00:48:35.224957Z","end":"2026-10-26T00:48:35.873779Z","steps":["trace[1888901705] 'process raft request' (duration: 624.4237ms)","trace[1888901705] 'compare' (duration: 24.180885ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:48:35.875194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:35.224938Z","time spent":"648.98511ms","remote":"127.0.0.1:48936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:48:35.875481Z","caller":"traceutil/trace.go:171","msg":"trace[915844660] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"641.768336ms","start":"2026-10-26T00:48:35.233691Z","end":"2026-10-26T00:48:35.875459Z","steps":["trace[915844660] 'process raft request' (duration: 641.694825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:35.875574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:35.233667Z","time spent":"641.861858ms","remote":"127.0.0.1:48936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:48:35.87581Z","caller":"traceutil/trace.go:171","msg":"trace[1469450764] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"645.951431ms","start":"2026-10-26T00:48:35.229842Z","end":"2026-10-26T00:48:35.875793Z","steps":["trace[1469450764] 'process raft request' (duration: 645.412563ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:35.875921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:35.229827Z","time spent":"646.025062ms","remote":"127.0.0.1:48936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:48:42.398457Z","caller":"traceutil/trace.go:171","msg":"trace[866810619] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"317.026503ms","start":"2026-10-26T00:48:42.0814Z","end":"2026-10-26T00:48:42.398426Z","steps":["trace[866810619] 'process raft request' (duration: 316.856871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:42.39863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:42.081367Z","time spent":"317.189706ms","remote":"127.0.0.1:49084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:48:42.975932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.866202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:48:42.976043Z","caller":"traceutil/trace.go:171","msg":"trace[639577209] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"470.686768ms","start":"2026-10-26T00:48:42.505339Z","end":"2026-10-26T00:48:42.976025Z","steps":["trace[639577209] 'read index received' (duration: 303.696623ms)","trace[639577209] 'applied index is now lower than readState.Index' (duration: 166.988275ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:48:42.97613Z","caller":"traceutil/trace.go:171","msg":"trace[1747075174] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"882.206845ms","start":"2026-10-26T00:48:42.093883Z","end":"2026-10-26T00:48:42.97609Z","steps":["trace[1747075174] 'process raft request' (duration: 716.005099ms)","trace[1747075174] 'compare' (duration: 164.690216ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:48:42.979199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:42.093864Z","time spent":"885.046672ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:48:42.980626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.264296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:48:42.980722Z","caller":"traceutil/trace.go:171","msg":"trace[86510173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"475.390628ms","start":"2026-10-26T00:48:42.505304Z","end":"2026-10-26T00:48:42.980695Z","steps":["trace[86510173] 'agreement among raft nodes before linearized reading' (duration: 470.808389ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:42.98078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:42.505288Z","time spent":"475.478638ms","remote":"127.0.0.1:48884","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:48:43.471211Z","caller":"traceutil/trace.go:171","msg":"trace[63837976] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"482.206842ms","start":"2026-10-26T00:48:42.988953Z","end":"2026-10-26T00:48:43.47116Z","steps":["trace[63837976] 'process raft request' (duration: 391.376518ms)","trace[63837976] 'compare' (duration: 90.54085ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:48:43.47143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:42.988914Z","time spent":"482.392895ms","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:48:43.471497Z","caller":"traceutil/trace.go:171","msg":"trace[44821498] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"417.472782ms","start":"2026-10-26T00:48:43.054003Z","end":"2026-10-26T00:48:43.471476Z","steps":["trace[44821498] 'process raft request' (duration: 417.060157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:43.471664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:43.05396Z","time spent":"417.628733ms","remote":"127.0.0.1:49120","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:48:43.501085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.326896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-26T00:48:43.501147Z","caller":"traceutil/trace.go:171","msg":"trace[1343733678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"419.452548ms","start":"2026-10-26T00:48:43.081678Z","end":"2026-10-26T00:48:43.501131Z","steps":["trace[1343733678] 'agreement among raft nodes before linearized reading' (duration: 419.268485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:43.501178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:43.081655Z","time spent":"419.515988ms","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T00:48:43.500817Z","caller":"traceutil/trace.go:171","msg":"trace[2051741351] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"419.046783ms","start":"2026-10-26T00:48:43.08174Z","end":"2026-10-26T00:48:43.500787Z","steps":["trace[2051741351] 'read index received' (duration: 298.640359ms)","trace[2051741351] 'applied index is now lower than readState.Index' (duration: 120.404534ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:48:43.501852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.396556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-26T00:48:43.501917Z","caller":"traceutil/trace.go:171","msg":"trace[575463423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"284.501868ms","start":"2026-10-26T00:48:43.217395Z","end":"2026-10-26T00:48:43.501897Z","steps":["trace[575463423] 'agreement among raft nodes before linearized reading' (duration: 284.328385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:43.501931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.873501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-10-26T00:48:43.502018Z","caller":"traceutil/trace.go:171","msg":"trace[1672162921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"416.006842ms","start":"2026-10-26T00:48:43.085994Z","end":"2026-10-26T00:48:43.502001Z","steps":["trace[1672162921] 'agreement among raft nodes before linearized reading' (duration: 415.657517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:43.502329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:43.085976Z","time spent":"416.337878ms","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T00:48:44.017871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15780509146122665549,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T00:48:44.374144Z","caller":"traceutil/trace.go:171","msg":"trace[2105090892] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"865.497065ms","start":"2026-10-26T00:48:43.50862Z","end":"2026-10-26T00:48:44.374117Z","steps":["trace[2105090892] 'process raft request' (duration: 865.273621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:44.374393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:43.508599Z","time spent":"865.640948ms","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:48:44.931425Z","caller":"traceutil/trace.go:171","msg":"trace[289933802] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.422278796s","start":"2026-10-26T00:48:43.509122Z","end":"2026-10-26T00:48:44.931401Z","steps":["trace[289933802] 'process raft request' (duration: 1.413504063s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:44.93161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:43.509084Z","time spent":"1.422432968s","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:48:44.931818Z","caller":"traceutil/trace.go:171","msg":"trace[2121557035] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.421473895s","start":"2026-10-26T00:48:43.510312Z","end":"2026-10-26T00:48:44.931786Z","steps":["trace[2121557035] 'process raft request' (duration: 1.42103501s)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:48:44.931872Z","caller":"traceutil/trace.go:171","msg":"trace[1973137684] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:655; }","duration":"1.414509186s","start":"2026-10-26T00:48:43.517322Z","end":"2026-10-26T00:48:44.931832Z","steps":["trace[1973137684] 'read index received' (duration: 857.428511ms)","trace[1973137684] 'applied index is now lower than readState.Index' (duration: 557.079415ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:48:44.931968Z","caller":"traceutil/trace.go:171","msg":"trace[496135643] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.253643814s","start":"2026-10-26T00:48:43.678306Z","end":"2026-10-26T00:48:44.93195Z","steps":["trace[496135643] 'process raft request' (duration: 1.253319281s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:44.932006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:43.510292Z","time spent":"1.421617827s","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:48:44.932083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:43.678282Z","time spent":"1.253759286s","remote":"127.0.0.1:49148","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:48:44.932131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.169673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:48:44.932131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.414788829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:48:44.93217Z","caller":"traceutil/trace.go:171","msg":"trace[855874300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"427.243954ms","start":"2026-10-26T00:48:44.504914Z","end":"2026-10-26T00:48:44.932158Z","steps":["trace[855874300] 'agreement among raft nodes before linearized reading' (duration: 427.186193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:44.932228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:44.504869Z","time spent":"427.353146ms","remote":"127.0.0.1:48886","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:48:44.932245Z","caller":"traceutil/trace.go:171","msg":"trace[820456320] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:633; }","duration":"1.41487904s","start":"2026-10-26T00:48:43.517298Z","end":"2026-10-26T00:48:44.932177Z","steps":["trace[820456320] 'agreement among raft nodes before linearized reading' (duration: 1.414759719s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:44.932302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:48:43.517264Z","time spent":"1.415025063s","remote":"127.0.0.1:49036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-10-26T00:48:46.59269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.095849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T00:48:46.592788Z","caller":"traceutil/trace.go:171","msg":"trace[1909343437] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:650; }","duration":"153.249192ms","start":"2026-10-26T00:48:46.43952Z","end":"2026-10-26T00:48:46.592769Z","steps":["trace[1909343437] 'count revisions from in-memory index tree' (duration: 152.991948ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:48:46.592787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.061563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-26T00:48:46.593065Z","caller":"traceutil/trace.go:171","msg":"trace[795793037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"213.383587ms","start":"2026-10-26T00:48:46.379664Z","end":"2026-10-26T00:48:46.593048Z","steps":["trace[795793037] 'range keys from in-memory index tree' (duration: 212.88695ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:48:57.129709Z","caller":"traceutil/trace.go:171","msg":"trace[861614419] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"131.940073ms","start":"2026-10-26T00:48:56.997745Z","end":"2026-10-26T00:48:57.129685Z","steps":["trace[861614419] 'process raft request' (duration: 131.807332ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:48:57.132025Z","caller":"traceutil/trace.go:171","msg":"trace[685292049] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"133.052717ms","start":"2026-10-26T00:48:56.998938Z","end":"2026-10-26T00:48:57.131991Z","steps":["trace[685292049] 'process raft request' (duration: 132.952936ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:49:00.778311Z","caller":"traceutil/trace.go:171","msg":"trace[17836822] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"273.147453ms","start":"2026-10-26T00:49:00.505145Z","end":"2026-10-26T00:49:00.778293Z","steps":["trace[17836822] 'read index received' (duration: 272.976561ms)","trace[17836822] 'applied index is now lower than readState.Index' (duration: 170.102µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:49:00.778429Z","caller":"traceutil/trace.go:171","msg":"trace[1943056983] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"593.083957ms","start":"2026-10-26T00:49:00.18531Z","end":"2026-10-26T00:49:00.778394Z","steps":["trace[1943056983] 'process raft request' (duration: 592.866284ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:49:00.778563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.393686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-26T00:49:00.778592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:49:00.185282Z","time spent":"593.225249ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:49:00.778628Z","caller":"traceutil/trace.go:171","msg":"trace[386707561] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:788; }","duration":"273.497367ms","start":"2026-10-26T00:49:00.505116Z","end":"2026-10-26T00:49:00.778614Z","steps":["trace[386707561] 'agreement among raft nodes before linearized reading' (duration: 273.364636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:49:00.778746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.257901ms","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:49:00.778867Z","caller":"traceutil/trace.go:171","msg":"trace[711254255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"104.399853ms","start":"2026-10-26T00:49:00.674431Z","end":"2026-10-26T00:49:00.778831Z","steps":["trace[711254255] 'agreement among raft nodes before linearized reading' (duration: 104.055289ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:49:25.38201Z","caller":"traceutil/trace.go:171","msg":"trace[1407711536] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"101.396029ms","start":"2026-10-26T00:49:25.280591Z","end":"2026-10-26T00:49:25.381987Z","steps":["trace[1407711536] 'process raft request' (duration: 101.206417ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:49:25.412756Z","caller":"traceutil/trace.go:171","msg":"trace[858273896] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"131.896633ms","start":"2026-10-26T00:49:25.280837Z","end":"2026-10-26T00:49:25.412733Z","steps":["trace[858273896] 'process raft request' (duration: 114.990261ms)","trace[858273896] 'compare' (duration: 16.793511ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:49:27.040702Z","caller":"traceutil/trace.go:171","msg":"trace[1917459706] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"117.168606ms","start":"2026-10-26T00:49:26.923511Z","end":"2026-10-26T00:49:27.040679Z","steps":["trace[1917459706] 'process raft request' (duration: 116.817192ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:49:28.387704Z","caller":"traceutil/trace.go:171","msg":"trace[394020846] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"157.560023ms","start":"2026-10-26T00:49:28.230116Z","end":"2026-10-26T00:49:28.387676Z","steps":["trace[394020846] 'process raft request' (duration: 157.401311ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:49:29.718694Z","caller":"traceutil/trace.go:171","msg":"trace[160976692] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"213.227742ms","start":"2026-10-26T00:49:29.505437Z","end":"2026-10-26T00:49:29.718665Z","steps":["trace[160976692] 'process raft request' (duration: 198.093522ms)","trace[160976692] 'compare' (duration: 15.008719ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:49:54.078435Z","caller":"traceutil/trace.go:171","msg":"trace[664470870] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"148.684185ms","start":"2026-10-26T00:49:53.929727Z","end":"2026-10-26T00:49:54.078411Z","steps":["trace[664470870] 'process raft request' (duration: 148.510819ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:49:55.911268Z","caller":"traceutil/trace.go:171","msg":"trace[1777150636] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"158.369323ms","start":"2026-10-26T00:49:55.752859Z","end":"2026-10-26T00:49:55.911228Z","steps":["trace[1777150636] 'process raft request' (duration: 87.85876ms)","trace[1777150636] 'compare' (duration: 70.258014ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:49:56.200034Z","caller":"traceutil/trace.go:171","msg":"trace[765825533] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"101.100044ms","start":"2026-10-26T00:49:56.098906Z","end":"2026-10-26T00:49:56.200006Z","steps":["trace[765825533] 'process raft request' (duration: 94.811451ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:51:04.721046Z","caller":"traceutil/trace.go:171","msg":"trace[1290432674] linearizableReadLoop","detail":"{readStateIndex:1424; appliedIndex:1423; }","duration":"217.726063ms","start":"2026-10-26T00:51:04.5033Z","end":"2026-10-26T00:51:04.721026Z","steps":["trace[1290432674] 'read index received' (duration: 217.574079ms)","trace[1290432674] 'applied index is now lower than readState.Index' (duration: 150.884µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:51:04.721386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.065721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:51:04.721526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.098129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-26T00:51:04.721402Z","caller":"traceutil/trace.go:171","msg":"trace[276288690] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"470.797111ms","start":"2026-10-26T00:51:04.250527Z","end":"2026-10-26T00:51:04.721325Z","steps":["trace[276288690] 'process raft request' (duration: 470.366379ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:51:04.721593Z","caller":"traceutil/trace.go:171","msg":"trace[895776716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1368; }","duration":"215.233313ms","start":"2026-10-26T00:51:04.506339Z","end":"2026-10-26T00:51:04.721572Z","steps":["trace[895776716] 'agreement among raft nodes before linearized reading' (duration: 215.022588ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:51:04.721539Z","caller":"traceutil/trace.go:171","msg":"trace[411148703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1368; }","duration":"218.276476ms","start":"2026-10-26T00:51:04.503244Z","end":"2026-10-26T00:51:04.72152Z","steps":["trace[411148703] 'agreement among raft nodes before linearized reading' (duration: 218.054221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:51:04.72177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.567471ms","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:51:04.721815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:51:04.250511Z","time spent":"471.238831ms","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:51:04.721853Z","caller":"traceutil/trace.go:171","msg":"trace[830677332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1368; }","duration":"197.748264ms","start":"2026-10-26T00:51:04.524091Z","end":"2026-10-26T00:51:04.721839Z","steps":["trace[830677332] 'agreement among raft nodes before linearized reading' (duration: 197.296553ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:51:41.263251Z","caller":"traceutil/trace.go:171","msg":"trace[975530132] linearizableReadLoop","detail":"{readStateIndex:1557; appliedIndex:1556; }","duration":"200.509535ms","start":"2026-10-26T00:51:41.062716Z","end":"2026-10-26T00:51:41.263226Z","steps":["trace[975530132] 'read index received' (duration: 200.398592ms)","trace[975530132] 'applied index is now lower than readState.Index' (duration: 109.813µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:51:41.263381Z","caller":"traceutil/trace.go:171","msg":"trace[1647961273] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"205.278837ms","start":"2026-10-26T00:51:41.058086Z","end":"2026-10-26T00:51:41.263365Z","steps":["trace[1647961273] 'process raft request' (duration: 205.015502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:51:41.263777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.014126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-26T00:51:41.263874Z","caller":"traceutil/trace.go:171","msg":"trace[1670589700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1494; }","duration":"201.155729ms","start":"2026-10-26T00:51:41.062698Z","end":"2026-10-26T00:51:41.263854Z","steps":["trace[1670589700] 'agreement among raft nodes before linearized reading' (duration: 200.908374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:51:41.264315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.137193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/\" range_end:\"/registry/pods/envoy-gateway-system0\" limit:500 ","response":"range_response_count:1 size:5486"} {"level":"info","ts":"2026-10-26T00:51:41.264379Z","caller":"traceutil/trace.go:171","msg":"trace[1854401272] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/; range_end:/registry/pods/envoy-gateway-system0; response_count:1; response_revision:1494; }","duration":"186.249904ms","start":"2026-10-26T00:51:41.078114Z","end":"2026-10-26T00:51:41.264364Z","steps":["trace[1854401272] 'agreement among raft nodes before linearized reading' (duration: 185.314005ms)"],"step_count":1}