{"level":"warn","ts":"2026-03-17T21:50:19.67195Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:50:19.672025Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.73:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.73:2380","--initial-cluster=instance=https://199.19.213.73:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.73:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.73: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-03-17T21:50:19.67208Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:50:19.672087Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-03-17T21:50:19.67211Z","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-03-17T21:50:19.672683Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"]} {"level":"info","ts":"2026-03-17T21:50:19.672753Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.73: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-03-17T21:50:19.678991Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.068287ms"} {"level":"info","ts":"2026-03-17T21:50:19.685609Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"34a8f480fa9ab6cb","cluster-id":"4820536600e0938e"} {"level":"info","ts":"2026-03-17T21:50:19.685687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=()"} {"level":"info","ts":"2026-03-17T21:50:19.685705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 0"} {"level":"info","ts":"2026-03-17T21:50:19.685719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 34a8f480fa9ab6cb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-17T21:50:19.685731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 1"} {"level":"info","ts":"2026-03-17T21:50:19.685777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"warn","ts":"2026-03-17T21:50:19.69597Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-17T21:50:19.700112Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-17T21:50:19.701159Z","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-03-17T21:50:19.703719Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"34a8f480fa9ab6cb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-17T21:50:19.703862Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"34a8f480fa9ab6cb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-17T21:50:19.703879Z","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-03-17T21:50:19.707093Z","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-03-17T21:50:19.707383Z","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-03-17T21:50:19.707957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"info","ts":"2026-03-17T21:50:19.708187Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","added-peer-id":"34a8f480fa9ab6cb","added-peer-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-03-17T21:50:19.710985Z","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-03-17T21:50:19.711347Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"34a8f480fa9ab6cb","initial-advertise-peer-urls":["https://199.19.213.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-17T21:50:19.711379Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-17T21:50:19.711093Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-03-17T21:50:19.711444Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-03-17T21:50:20.086144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb is starting a new election at term 1"} {"level":"info","ts":"2026-03-17T21:50:20.086221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became pre-candidate at term 1"} {"level":"info","ts":"2026-03-17T21:50:20.086246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgPreVoteResp from 34a8f480fa9ab6cb at term 1"} {"level":"info","ts":"2026-03-17T21:50:20.08626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became candidate at term 2"} {"level":"info","ts":"2026-03-17T21:50:20.086267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgVoteResp from 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-03-17T21:50:20.086278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became leader at term 2"} {"level":"info","ts":"2026-03-17T21:50:20.08629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 34a8f480fa9ab6cb elected leader 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-03-17T21:50:20.087445Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:50:20.088183Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"34a8f480fa9ab6cb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.73:2379]}","request-path":"/0/members/34a8f480fa9ab6cb/attributes","cluster-id":"4820536600e0938e","publish-timeout":"7s"} {"level":"info","ts":"2026-03-17T21:50:20.088195Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:50:20.08847Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:50:20.088679Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-17T21:50:20.088718Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:50:20.088738Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-17T21:50:20.088781Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:50:20.088805Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:50:20.092034Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-17T21:50:20.092279Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.73:2379"} {"level":"info","ts":"2026-03-17T21:50:21.610814Z","caller":"traceutil/trace.go:171","msg":"trace[378217282] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"305.323423ms","start":"2026-03-17T21:50:21.305472Z","end":"2026-03-17T21:50:21.610795Z","steps":["trace[378217282] 'process raft request' (duration: 305.1219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:21.611575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:21.305463Z","time spent":"305.557266ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:50:21.617847Z","caller":"traceutil/trace.go:171","msg":"trace[1741506800] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"310.76213ms","start":"2026-03-17T21:50:21.307066Z","end":"2026-03-17T21:50:21.617828Z","steps":["trace[1741506800] 'process raft request' (duration: 310.665549ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:21.617964Z","caller":"traceutil/trace.go:171","msg":"trace[956541701] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"311.438779ms","start":"2026-03-17T21:50:21.306509Z","end":"2026-03-17T21:50:21.617948Z","steps":["trace[956541701] 'read index received' (duration: 304.196269ms)","trace[956541701] 'applied index is now lower than readState.Index' (duration: 7.24156ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:50:21.617892Z","caller":"traceutil/trace.go:171","msg":"trace[34853730] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"312.248909ms","start":"2026-03-17T21:50:21.305619Z","end":"2026-03-17T21:50:21.617868Z","steps":["trace[34853730] 'process raft request' (duration: 312.033086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:21.618081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:21.307057Z","time spent":"310.909352ms","remote":"127.0.0.1:54928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:50:21.618278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:21.305607Z","time spent":"312.528412ms","remote":"127.0.0.1:54724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:50:21.6182Z","caller":"traceutil/trace.go:171","msg":"trace[361067785] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"232.269207ms","start":"2026-03-17T21:50:21.385916Z","end":"2026-03-17T21:50:21.618185Z","steps":["trace[361067785] 'process raft request' (duration: 231.845272ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:21.61871Z","caller":"traceutil/trace.go:171","msg":"trace[1293699137] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"222.261724ms","start":"2026-03-17T21:50:21.396434Z","end":"2026-03-17T21:50:21.618696Z","steps":["trace[1293699137] 'process raft request' (duration: 221.480614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:21.618941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.712203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-17T21:50:21.61905Z","caller":"traceutil/trace.go:171","msg":"trace[1644698765] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"182.885795ms","start":"2026-03-17T21:50:21.436147Z","end":"2026-03-17T21:50:21.619033Z","steps":["trace[1644698765] 'agreement among raft nodes before linearized reading' (duration: 182.584111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:21.619164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.346523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-17T21:50:21.619328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.491632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-17T21:50:21.61923Z","caller":"traceutil/trace.go:171","msg":"trace[420713891] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"234.545465ms","start":"2026-03-17T21:50:21.384668Z","end":"2026-03-17T21:50:21.619213Z","steps":["trace[420713891] 'agreement among raft nodes before linearized reading' (duration: 234.357683ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:21.619381Z","caller":"traceutil/trace.go:171","msg":"trace[7112836] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"166.568873ms","start":"2026-03-17T21:50:21.452797Z","end":"2026-03-17T21:50:21.619366Z","steps":["trace[7112836] 'agreement among raft nodes before linearized reading' (duration: 166.489262ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:21.61899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.499489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-17T21:50:21.619583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.746791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-17T21:50:21.619597Z","caller":"traceutil/trace.go:171","msg":"trace[1983181526] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"266.134297ms","start":"2026-03-17T21:50:21.353448Z","end":"2026-03-17T21:50:21.619582Z","steps":["trace[1983181526] 'agreement among raft nodes before linearized reading' (duration: 265.253636ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:21.619614Z","caller":"traceutil/trace.go:171","msg":"trace[835618842] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"235.803451ms","start":"2026-03-17T21:50:21.3838Z","end":"2026-03-17T21:50:21.619604Z","steps":["trace[835618842] 'agreement among raft nodes before linearized reading' (duration: 235.75505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:21.619686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.517332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.73\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-17T21:50:21.619766Z","caller":"traceutil/trace.go:171","msg":"trace[1436278748] range","detail":"{range_begin:/registry/masterleases/199.19.213.73; range_end:; response_count:0; response_revision:11; }","duration":"237.645784ms","start":"2026-03-17T21:50:21.382096Z","end":"2026-03-17T21:50:21.619742Z","steps":["trace[1436278748] 'agreement among raft nodes before linearized reading' (duration: 237.473092ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:21.619762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.244761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-03-17T21:50:21.61979Z","caller":"traceutil/trace.go:171","msg":"trace[1745497385] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"233.748395ms","start":"2026-03-17T21:50:21.38603Z","end":"2026-03-17T21:50:21.619778Z","steps":["trace[1745497385] 'process raft request' (duration: 231.757491ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:21.61981Z","caller":"traceutil/trace.go:171","msg":"trace[570447011] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"313.314531ms","start":"2026-03-17T21:50:21.306483Z","end":"2026-03-17T21:50:21.619798Z","steps":["trace[570447011] 'agreement among raft nodes before linearized reading' (duration: 313.22579ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:21.619795Z","caller":"traceutil/trace.go:171","msg":"trace[1007046057] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"232.611141ms","start":"2026-03-17T21:50:21.387167Z","end":"2026-03-17T21:50:21.619778Z","steps":["trace[1007046057] 'process raft request' (duration: 230.668337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:21.619848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:21.30647Z","time spent":"313.365992ms","remote":"127.0.0.1:54856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3192,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-17T21:50:21.619935Z","caller":"traceutil/trace.go:171","msg":"trace[2140894475] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"233.675235ms","start":"2026-03-17T21:50:21.386246Z","end":"2026-03-17T21:50:21.619921Z","steps":["trace[2140894475] 'process raft request' (duration: 231.568019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:21.619946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.158419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-17T21:50:21.620083Z","caller":"traceutil/trace.go:171","msg":"trace[1963631632] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"232.811784ms","start":"2026-03-17T21:50:21.387252Z","end":"2026-03-17T21:50:21.620064Z","steps":["trace[1963631632] 'process raft request' (duration: 230.605086ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:21.620093Z","caller":"traceutil/trace.go:171","msg":"trace[453585539] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"167.231552ms","start":"2026-03-17T21:50:21.45284Z","end":"2026-03-17T21:50:21.620072Z","steps":["trace[453585539] 'agreement among raft nodes before linearized reading' (duration: 166.147258ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:21.618513Z","caller":"traceutil/trace.go:171","msg":"trace[693356084] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"230.938881ms","start":"2026-03-17T21:50:21.387542Z","end":"2026-03-17T21:50:21.618481Z","steps":["trace[693356084] 'process raft request' (duration: 230.341154ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:24.23557Z","caller":"traceutil/trace.go:171","msg":"trace[958708843] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"271.35069ms","start":"2026-03-17T21:50:23.964192Z","end":"2026-03-17T21:50:24.235543Z","steps":["trace[958708843] 'process raft request' (duration: 271.181868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:25.100043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.416238ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-17T21:50:25.100334Z","caller":"traceutil/trace.go:171","msg":"trace[1276551393] linearizableReadLoop","detail":"{readStateIndex:215; appliedIndex:214; }","duration":"860.469501ms","start":"2026-03-17T21:50:24.239836Z","end":"2026-03-17T21:50:25.100305Z","steps":["trace[1276551393] 'read index received' (duration: 159.638728ms)","trace[1276551393] 'applied index is now lower than readState.Index' (duration: 700.828413ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:50:25.100823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.895586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-c9xmmz\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:50:25.100948Z","caller":"traceutil/trace.go:171","msg":"trace[1519654676] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-c9xmmz; range_end:; response_count:0; response_revision:209; }","duration":"861.123059ms","start":"2026-03-17T21:50:24.239797Z","end":"2026-03-17T21:50:25.10092Z","steps":["trace[1519654676] 'agreement among raft nodes before linearized reading' (duration: 860.835805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:25.101041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:24.239779Z","time spent":"861.245731ms","remote":"127.0.0.1:54774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-c9xmmz\" "} {"level":"warn","ts":"2026-03-17T21:50:25.101001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.154286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:50:25.101191Z","caller":"traceutil/trace.go:171","msg":"trace[742142087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:209; }","duration":"465.388778ms","start":"2026-03-17T21:50:24.635769Z","end":"2026-03-17T21:50:25.101158Z","steps":["trace[742142087] 'agreement among raft nodes before linearized reading' (duration: 465.124385ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:25.101172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.835711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T21:50:25.101258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:24.635756Z","time spent":"465.489189ms","remote":"127.0.0.1:54930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-17T21:50:25.101329Z","caller":"traceutil/trace.go:171","msg":"trace[985786717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:209; }","duration":"669.043023ms","start":"2026-03-17T21:50:24.432251Z","end":"2026-03-17T21:50:25.101294Z","steps":["trace[985786717] 'agreement among raft nodes before linearized reading' (duration: 668.697129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:25.101405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:24.43224Z","time spent":"669.154564ms","remote":"127.0.0.1:54930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-17T21:50:25.101513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.510537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:50:25.101676Z","caller":"traceutil/trace.go:171","msg":"trace[1384405539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:209; }","duration":"731.261017ms","start":"2026-03-17T21:50:24.370368Z","end":"2026-03-17T21:50:25.101629Z","steps":["trace[1384405539] 'agreement among raft nodes before linearized reading' (duration: 730.488938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:25.101817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:24.370358Z","time spent":"731.439059ms","remote":"127.0.0.1:54930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-17T21:50:25.100262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:24.166223Z","time spent":"934.033001ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-17T21:50:25.556223Z","caller":"traceutil/trace.go:171","msg":"trace[1552932716] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"453.069365ms","start":"2026-03-17T21:50:25.103119Z","end":"2026-03-17T21:50:25.556188Z","steps":["trace[1552932716] 'process raft request' (duration: 448.385239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:25.556419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:25.103098Z","time spent":"453.229656ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T21:50:25.570355Z","caller":"traceutil/trace.go:171","msg":"trace[1749437451] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"464.025083ms","start":"2026-03-17T21:50:25.106311Z","end":"2026-03-17T21:50:25.570336Z","steps":["trace[1749437451] 'process raft request' (duration: 463.979583ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:25.570489Z","caller":"traceutil/trace.go:171","msg":"trace[891397954] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"464.252606ms","start":"2026-03-17T21:50:25.106224Z","end":"2026-03-17T21:50:25.570477Z","steps":["trace[891397954] 'process raft request' (duration: 464.004483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:25.570646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:25.106215Z","time spent":"464.328487ms","remote":"127.0.0.1:54930","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-03-17T21:50:25.570698Z","caller":"traceutil/trace.go:171","msg":"trace[1077651214] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"464.420618ms","start":"2026-03-17T21:50:25.106236Z","end":"2026-03-17T21:50:25.570656Z","steps":["trace[1077651214] 'process raft request' (duration: 464.027844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:25.57066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:25.106304Z","time spent":"464.184645ms","remote":"127.0.0.1:54930","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-03-17T21:50:25.570984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:25.106221Z","time spent":"464.655741ms","remote":"127.0.0.1:54930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T21:50:25.570347Z","caller":"traceutil/trace.go:171","msg":"trace[281311676] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"464.265026ms","start":"2026-03-17T21:50:25.10604Z","end":"2026-03-17T21:50:25.570305Z","steps":["trace[281311676] 'process raft request' (duration: 464.066534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:25.57112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:25.106025Z","time spent":"465.063265ms","remote":"127.0.0.1:54774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} 2026/03/17 21:50:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-03-17T21:50:26.027325Z","caller":"traceutil/trace.go:171","msg":"trace[1175870204] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:255; }","duration":"111.739808ms","start":"2026-03-17T21:50:25.915565Z","end":"2026-03-17T21:50:26.027305Z","steps":["trace[1175870204] 'read index received' (duration: 28.270024ms)","trace[1175870204] 'applied index is now lower than readState.Index' (duration: 83.468314ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:50:26.027588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.000331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:50:26.027626Z","caller":"traceutil/trace.go:171","msg":"trace[720423408] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:250; }","duration":"112.070221ms","start":"2026-03-17T21:50:25.915545Z","end":"2026-03-17T21:50:26.027616Z","steps":["trace[720423408] 'agreement among raft nodes before linearized reading' (duration: 111.99093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:26.281819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.764713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:50:26.282058Z","caller":"traceutil/trace.go:171","msg":"trace[497255804] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:259; }","duration":"178.684418ms","start":"2026-03-17T21:50:26.103356Z","end":"2026-03-17T21:50:26.28204Z","steps":["trace[497255804] 'read index received' (duration: 32.615951ms)","trace[497255804] 'applied index is now lower than readState.Index' (duration: 146.067097ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:50:26.282043Z","caller":"traceutil/trace.go:171","msg":"trace[1944071093] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"200.904618ms","start":"2026-03-17T21:50:26.081102Z","end":"2026-03-17T21:50:26.282007Z","steps":["trace[1944071093] 'process raft request' (duration: 54.855511ms)","trace[1944071093] 'compare' (duration: 145.601451ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:50:26.282148Z","caller":"traceutil/trace.go:171","msg":"trace[561856415] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"178.90457ms","start":"2026-03-17T21:50:26.103234Z","end":"2026-03-17T21:50:26.282139Z","steps":["trace[561856415] 'process raft request' (duration: 178.693138ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:26.282404Z","caller":"traceutil/trace.go:171","msg":"trace[1349778125] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"177.766837ms","start":"2026-03-17T21:50:26.104623Z","end":"2026-03-17T21:50:26.28239Z","steps":["trace[1349778125] 'process raft request' (duration: 177.362392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:26.282516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.150353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-17T21:50:26.282551Z","caller":"traceutil/trace.go:171","msg":"trace[284948438] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:256; }","duration":"179.230534ms","start":"2026-03-17T21:50:26.103308Z","end":"2026-03-17T21:50:26.282538Z","steps":["trace[284948438] 'agreement among raft nodes before linearized reading' (duration: 179.088042ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:26.354055Z","caller":"traceutil/trace.go:171","msg":"trace[1173409049] transaction","detail":"{read_only:false; number_of_response:0; response_revision:256; }","duration":"138.533458ms","start":"2026-03-17T21:50:26.215499Z","end":"2026-03-17T21:50:26.354032Z","steps":["trace[1173409049] 'process raft request' (duration: 138.363956ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:26.354208Z","caller":"traceutil/trace.go:171","msg":"trace[1403367058] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"137.712029ms","start":"2026-03-17T21:50:26.216464Z","end":"2026-03-17T21:50:26.354176Z","steps":["trace[1403367058] 'process raft request' (duration: 137.621968ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:26.354203Z","caller":"traceutil/trace.go:171","msg":"trace[1933129964] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"137.964302ms","start":"2026-03-17T21:50:26.216197Z","end":"2026-03-17T21:50:26.354162Z","steps":["trace[1933129964] 'process raft request' (duration: 137.855071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:26.35431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.639208ms","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-03-17T21:50:26.354345Z","caller":"traceutil/trace.go:171","msg":"trace[1520174446] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:260; }","duration":"250.69956ms","start":"2026-03-17T21:50:26.103637Z","end":"2026-03-17T21:50:26.354337Z","steps":["trace[1520174446] 'agreement among raft nodes before linearized reading' (duration: 250.537998ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:26.354394Z","caller":"traceutil/trace.go:171","msg":"trace[648862553] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"138.516639ms","start":"2026-03-17T21:50:26.215866Z","end":"2026-03-17T21:50:26.354382Z","steps":["trace[648862553] 'process raft request' (duration: 138.150114ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:26.354892Z","caller":"traceutil/trace.go:171","msg":"trace[1830303585] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"139.149126ms","start":"2026-03-17T21:50:26.215716Z","end":"2026-03-17T21:50:26.354865Z","steps":["trace[1830303585] 'process raft request' (duration: 138.229605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:26.524706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.573673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:50:26.524804Z","caller":"traceutil/trace.go:171","msg":"trace[2002300479] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"143.258684ms","start":"2026-03-17T21:50:26.381526Z","end":"2026-03-17T21:50:26.524785Z","steps":["trace[2002300479] 'process raft request' (duration: 14.535959ms)","trace[2002300479] 'compare' (duration: 128.480972ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:50:26.547827Z","caller":"traceutil/trace.go:171","msg":"trace[247951697] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"166.179032ms","start":"2026-03-17T21:50:26.381618Z","end":"2026-03-17T21:50:26.547797Z","steps":["trace[247951697] 'process raft request' (duration: 166.0047ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:26.547902Z","caller":"traceutil/trace.go:171","msg":"trace[1463320172] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"159.518944ms","start":"2026-03-17T21:50:26.388362Z","end":"2026-03-17T21:50:26.547881Z","steps":["trace[1463320172] 'process raft request' (duration: 159.457393ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:26.548026Z","caller":"traceutil/trace.go:171","msg":"trace[241203438] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"161.582088ms","start":"2026-03-17T21:50:26.386421Z","end":"2026-03-17T21:50:26.548004Z","steps":["trace[241203438] 'process raft request' (duration: 161.354535ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:26.548203Z","caller":"traceutil/trace.go:171","msg":"trace[1282444391] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"164.30833ms","start":"2026-03-17T21:50:26.383882Z","end":"2026-03-17T21:50:26.548191Z","steps":["trace[1282444391] 'process raft request' (duration: 163.846625ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:28.9905Z","caller":"traceutil/trace.go:171","msg":"trace[1044528106] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"261.997076ms","start":"2026-03-17T21:50:28.728476Z","end":"2026-03-17T21:50:28.990473Z","steps":["trace[1044528106] 'process raft request' (duration: 261.824014ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:47.804345Z","caller":"traceutil/trace.go:171","msg":"trace[1072139635] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"102.528514ms","start":"2026-03-17T21:50:47.701789Z","end":"2026-03-17T21:50:47.804317Z","steps":["trace[1072139635] 'process raft request' (duration: 102.432463ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:47.804341Z","caller":"traceutil/trace.go:171","msg":"trace[2123840742] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"102.677005ms","start":"2026-03-17T21:50:47.701627Z","end":"2026-03-17T21:50:47.804304Z","steps":["trace[2123840742] 'process raft request' (duration: 102.437263ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:48.174244Z","caller":"traceutil/trace.go:171","msg":"trace[1400814893] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"200.804075ms","start":"2026-03-17T21:50:47.973399Z","end":"2026-03-17T21:50:48.174203Z","steps":["trace[1400814893] 'process raft request' (duration: 125.649751ms)","trace[1400814893] 'compare' (duration: 74.682449ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:50:48.174281Z","caller":"traceutil/trace.go:171","msg":"trace[1839106473] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"200.394231ms","start":"2026-03-17T21:50:47.973831Z","end":"2026-03-17T21:50:48.174225Z","steps":["trace[1839106473] 'process raft request' (duration: 200.070418ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:48.611097Z","caller":"traceutil/trace.go:171","msg":"trace[19153824] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"163.571855ms","start":"2026-03-17T21:50:48.447493Z","end":"2026-03-17T21:50:48.611065Z","steps":["trace[19153824] 'process raft request' (duration: 111.761936ms)","trace[19153824] 'compare' (duration: 51.667987ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:50:48.631522Z","caller":"traceutil/trace.go:171","msg":"trace[1678009850] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"165.354383ms","start":"2026-03-17T21:50:48.466145Z","end":"2026-03-17T21:50:48.6315Z","steps":["trace[1678009850] 'process raft request' (duration: 165.0731ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:48.811756Z","caller":"traceutil/trace.go:171","msg":"trace[960383754] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"118.38932ms","start":"2026-03-17T21:50:48.693319Z","end":"2026-03-17T21:50:48.811709Z","steps":["trace[960383754] 'process raft request' (duration: 106.448565ms)","trace[960383754] 'compare' (duration: 11.691333ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:50:48.812015Z","caller":"traceutil/trace.go:171","msg":"trace[435081573] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:479; }","duration":"117.792124ms","start":"2026-03-17T21:50:48.694205Z","end":"2026-03-17T21:50:48.811997Z","steps":["trace[435081573] 'read index received' (duration: 14.888083ms)","trace[435081573] 'applied index is now lower than readState.Index' (duration: 102.902481ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:50:48.812138Z","caller":"traceutil/trace.go:171","msg":"trace[685512616] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"118.669693ms","start":"2026-03-17T21:50:48.693451Z","end":"2026-03-17T21:50:48.81212Z","steps":["trace[685512616] 'process raft request' (duration: 118.172828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:48.812196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.961435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-03-17T21:50:48.812278Z","caller":"traceutil/trace.go:171","msg":"trace[292108791] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:470; }","duration":"118.101317ms","start":"2026-03-17T21:50:48.694155Z","end":"2026-03-17T21:50:48.812257Z","steps":["trace[292108791] 'agreement among raft nodes before linearized reading' (duration: 117.922576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:48.812464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.427685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-03-17T21:50:48.812084Z","caller":"traceutil/trace.go:171","msg":"trace[1953334104] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"118.41022ms","start":"2026-03-17T21:50:48.69364Z","end":"2026-03-17T21:50:48.812051Z","steps":["trace[1953334104] 'process raft request' (duration: 118.046547ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:48.812539Z","caller":"traceutil/trace.go:171","msg":"trace[641477788] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:470; }","duration":"106.539286ms","start":"2026-03-17T21:50:48.705979Z","end":"2026-03-17T21:50:48.812518Z","steps":["trace[641477788] 'agreement among raft nodes before linearized reading' (duration: 106.138692ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:50:50.860621Z","caller":"traceutil/trace.go:171","msg":"trace[1273143872] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"362.517306ms","start":"2026-03-17T21:50:50.498076Z","end":"2026-03-17T21:50:50.860594Z","steps":["trace[1273143872] 'process raft request' (duration: 362.366914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:50.860781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:50.498049Z","time spent":"362.655388ms","remote":"127.0.0.1:54856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4566,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:50:53.480306Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794148958677279,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-17T21:50:53.5632Z","caller":"traceutil/trace.go:171","msg":"trace[2044137234] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"583.88956ms","start":"2026-03-17T21:50:52.979284Z","end":"2026-03-17T21:50:53.563174Z","steps":["trace[2044137234] 'read index received' (duration: 583.713278ms)","trace[2044137234] 'applied index is now lower than readState.Index' (duration: 174.952µs)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:50:53.563215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:52.931305Z","time spent":"631.9028ms","remote":"127.0.0.1:54702","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-17T21:50:53.563318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.017341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:50:53.56335Z","caller":"traceutil/trace.go:171","msg":"trace[510628689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"584.104131ms","start":"2026-03-17T21:50:52.979235Z","end":"2026-03-17T21:50:53.563339Z","steps":["trace[510628689] 'agreement among raft nodes before linearized reading' (duration: 584.027081ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:50:53.563389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:52.979216Z","time spent":"584.164793ms","remote":"127.0.0.1:54658","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-03-17T21:51:00.415508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171794148958677368,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-17T21:51:00.712269Z","caller":"traceutil/trace.go:171","msg":"trace[1080967590] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"797.57382ms","start":"2026-03-17T21:50:59.914673Z","end":"2026-03-17T21:51:00.712247Z","steps":["trace[1080967590] 'read index received' (duration: 797.358468ms)","trace[1080967590] 'applied index is now lower than readState.Index' (duration: 201.252µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:51:00.712324Z","caller":"traceutil/trace.go:171","msg":"trace[914108037] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"815.937776ms","start":"2026-03-17T21:50:59.896351Z","end":"2026-03-17T21:51:00.712289Z","steps":["trace[914108037] 'process raft request' (duration: 815.750284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:00.712457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:59.896335Z","time spent":"816.052486ms","remote":"127.0.0.1:54930","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-03-17T21:51:00.712474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.344342ms","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-03-17T21:51:00.712488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.768184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:51:00.712514Z","caller":"traceutil/trace.go:171","msg":"trace[432797934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"680.420603ms","start":"2026-03-17T21:51:00.032082Z","end":"2026-03-17T21:51:00.712503Z","steps":["trace[432797934] 'agreement among raft nodes before linearized reading' (duration: 680.335332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:00.712551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:51:00.032074Z","time spent":"680.470163ms","remote":"127.0.0.1:54930","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-03-17T21:51:00.712551Z","caller":"traceutil/trace.go:171","msg":"trace[1976018733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"732.865676ms","start":"2026-03-17T21:50:59.979669Z","end":"2026-03-17T21:51:00.712535Z","steps":["trace[1976018733] 'agreement among raft nodes before linearized reading' (duration: 732.758365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:00.712605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:59.979653Z","time spent":"732.943906ms","remote":"127.0.0.1:54658","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-03-17T21:51:00.712867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.195256ms","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-03-17T21:51:00.712933Z","caller":"traceutil/trace.go:171","msg":"trace[2095690967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"798.287956ms","start":"2026-03-17T21:50:59.914634Z","end":"2026-03-17T21:51:00.712922Z","steps":["trace[2095690967] 'agreement among raft nodes before linearized reading' (duration: 797.732391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:00.712946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.428992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-17T21:51:00.712962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:59.914623Z","time spent":"798.332317ms","remote":"127.0.0.1:54930","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-03-17T21:51:00.712993Z","caller":"traceutil/trace.go:171","msg":"trace[1637568613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"783.502673ms","start":"2026-03-17T21:50:59.92948Z","end":"2026-03-17T21:51:00.712983Z","steps":["trace[1637568613] 'agreement among raft nodes before linearized reading' (duration: 782.918888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:00.713033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:50:59.929468Z","time spent":"783.558193ms","remote":"127.0.0.1:54930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-17T21:51:00.981563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.492237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:51:00.981765Z","caller":"traceutil/trace.go:171","msg":"trace[401921555] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"263.7476ms","start":"2026-03-17T21:51:00.718001Z","end":"2026-03-17T21:51:00.981748Z","steps":["trace[401921555] 'process raft request' (duration: 263.713049ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:00.981952Z","caller":"traceutil/trace.go:171","msg":"trace[1916890998] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"264.867209ms","start":"2026-03-17T21:51:00.717073Z","end":"2026-03-17T21:51:00.98194Z","steps":["trace[1916890998] 'process raft request' (duration: 264.589657ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:00.982094Z","caller":"traceutil/trace.go:171","msg":"trace[525381587] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"265.357344ms","start":"2026-03-17T21:51:00.716727Z","end":"2026-03-17T21:51:00.982085Z","steps":["trace[525381587] 'process raft request' (duration: 129.247091ms)","trace[525381587] 'compare' (duration: 135.367276ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:51:02.49645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.367366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:51:02.496512Z","caller":"traceutil/trace.go:171","msg":"trace[677039051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"516.442237ms","start":"2026-03-17T21:51:01.980054Z","end":"2026-03-17T21:51:02.496496Z","steps":["trace[677039051] 'range keys from in-memory index tree' (duration: 516.206105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:02.496541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:51:01.97997Z","time spent":"516.564368ms","remote":"127.0.0.1:54658","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-03-17T21:51:02.524581Z","caller":"traceutil/trace.go:171","msg":"trace[1226022289] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"347.325721ms","start":"2026-03-17T21:51:02.177219Z","end":"2026-03-17T21:51:02.524545Z","steps":["trace[1226022289] 'process raft request' (duration: 347.13958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:02.524794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:51:02.177186Z","time spent":"347.491233ms","remote":"127.0.0.1:54930","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-03-17T21:51:07.535771Z","caller":"traceutil/trace.go:171","msg":"trace[1903792334] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"262.345625ms","start":"2026-03-17T21:51:07.273397Z","end":"2026-03-17T21:51:07.535743Z","steps":["trace[1903792334] 'process raft request' (duration: 251.168147ms)","trace[1903792334] 'compare' (duration: 10.996936ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:51:07.536022Z","caller":"traceutil/trace.go:171","msg":"trace[1383016104] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"209.736977ms","start":"2026-03-17T21:51:07.326229Z","end":"2026-03-17T21:51:07.535966Z","steps":["trace[1383016104] 'process raft request' (duration: 209.459364ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:07.748039Z","caller":"traceutil/trace.go:171","msg":"trace[383584196] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"145.162414ms","start":"2026-03-17T21:51:07.602853Z","end":"2026-03-17T21:51:07.748016Z","steps":["trace[383584196] 'read index received' (duration: 66.973233ms)","trace[383584196] 'applied index is now lower than readState.Index' (duration: 78.188061ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:51:07.74819Z","caller":"traceutil/trace.go:171","msg":"trace[2098164505] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"342.432653ms","start":"2026-03-17T21:51:07.405721Z","end":"2026-03-17T21:51:07.748154Z","steps":["trace[2098164505] 'process raft request' (duration: 264.159221ms)","trace[2098164505] 'compare' (duration: 78.012889ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:51:07.748237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.357966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:51:07.748281Z","caller":"traceutil/trace.go:171","msg":"trace[1429171655] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:597; }","duration":"145.463467ms","start":"2026-03-17T21:51:07.602805Z","end":"2026-03-17T21:51:07.748268Z","steps":["trace[1429171655] 'agreement among raft nodes before linearized reading' (duration: 145.297305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:07.748333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:51:07.405698Z","time spent":"342.571374ms","remote":"127.0.0.1:54718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:51:08.303963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.931352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:51:08.304237Z","caller":"traceutil/trace.go:171","msg":"trace[1299522305] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"324.465794ms","start":"2026-03-17T21:51:07.979747Z","end":"2026-03-17T21:51:08.304213Z","steps":["trace[1299522305] 'read index received' (duration: 33.231079ms)","trace[1299522305] 'applied index is now lower than readState.Index' (duration: 291.232345ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:51:08.304259Z","caller":"traceutil/trace.go:171","msg":"trace[1451177918] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"369.89699ms","start":"2026-03-17T21:51:07.934341Z","end":"2026-03-17T21:51:08.304238Z","steps":["trace[1451177918] 'process raft request' (duration: 78.608154ms)","trace[1451177918] 'compare' (duration: 290.69426ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:51:08.30434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:51:07.934318Z","time spent":"369.98695ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:51:08.304419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.665046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:51:08.304476Z","caller":"traceutil/trace.go:171","msg":"trace[1908411697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"324.763706ms","start":"2026-03-17T21:51:07.979695Z","end":"2026-03-17T21:51:08.304458Z","steps":["trace[1908411697] 'agreement among raft nodes before linearized reading' (duration: 324.622165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:08.304495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.441994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"warn","ts":"2026-03-17T21:51:08.304532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:51:07.979672Z","time spent":"324.848827ms","remote":"127.0.0.1:54658","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-03-17T21:51:08.30454Z","caller":"traceutil/trace.go:171","msg":"trace[1354672250] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:601; }","duration":"301.525254ms","start":"2026-03-17T21:51:08.002999Z","end":"2026-03-17T21:51:08.304524Z","steps":["trace[1354672250] 'agreement among raft nodes before linearized reading' (duration: 301.331632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:08.30458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:51:08.002977Z","time spent":"301.592345ms","remote":"127.0.0.1:54718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-03-17T21:51:08.304694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.345796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-17T21:51:08.304751Z","caller":"traceutil/trace.go:171","msg":"trace[1914073704] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:601; }","duration":"291.423017ms","start":"2026-03-17T21:51:08.013312Z","end":"2026-03-17T21:51:08.304735Z","steps":["trace[1914073704] 'agreement among raft nodes before linearized reading' (duration: 291.264655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:08.304738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.828705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-03-17T21:51:08.304827Z","caller":"traceutil/trace.go:171","msg":"trace[1095779457] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:601; }","duration":"100.974106ms","start":"2026-03-17T21:51:08.20383Z","end":"2026-03-17T21:51:08.304804Z","steps":["trace[1095779457] 'agreement among raft nodes before linearized reading' (duration: 100.676254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:01.936377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.476403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:52:01.936499Z","caller":"traceutil/trace.go:171","msg":"trace[406181845] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"504.77103ms","start":"2026-03-17T21:52:01.431709Z","end":"2026-03-17T21:52:01.93648Z","steps":["trace[406181845] 'process raft request' (duration: 384.116775ms)","trace[406181845] 'compare' (duration: 120.3608ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:52:01.936578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:01.431696Z","time spent":"504.845541ms","remote":"127.0.0.1:54930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:52:01.939397Z","caller":"traceutil/trace.go:171","msg":"trace[417012772] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"462.895779ms","start":"2026-03-17T21:52:01.476481Z","end":"2026-03-17T21:52:01.939377Z","steps":["trace[417012772] 'process raft request' (duration: 462.820898ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:01.939519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:01.476443Z","time spent":"463.011952ms","remote":"127.0.0.1:54930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:52:03.189608Z","caller":"traceutil/trace.go:171","msg":"trace[1840269165] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"116.872066ms","start":"2026-03-17T21:52:03.072715Z","end":"2026-03-17T21:52:03.189587Z","steps":["trace[1840269165] 'read index received' (duration: 70.312083ms)","trace[1840269165] 'applied index is now lower than readState.Index' (duration: 46.559133ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:52:03.18979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.062209ms","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-03-17T21:52:03.189864Z","caller":"traceutil/trace.go:171","msg":"trace[1526202194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:849; }","duration":"117.17003ms","start":"2026-03-17T21:52:03.072676Z","end":"2026-03-17T21:52:03.189846Z","steps":["trace[1526202194] 'agreement among raft nodes before linearized reading' (duration: 116.989447ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:52:03.189644Z","caller":"traceutil/trace.go:171","msg":"trace[1812749195] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"139.516058ms","start":"2026-03-17T21:52:03.050095Z","end":"2026-03-17T21:52:03.189612Z","steps":["trace[1812749195] 'process raft request' (duration: 92.977475ms)","trace[1812749195] 'compare' (duration: 46.368441ms)"],"step_count":2}