{"level":"warn","ts":"2026-04-07T15:20:34.102351Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T15:20:34.102473Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.18: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.18:2380","--initial-cluster=instance=https://199.19.213.18:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.18:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.18: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-04-07T15:20:34.102569Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T15:20:34.102624Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-04-07T15:20:34.10265Z","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-04-07T15:20:34.1036Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"]} {"level":"info","ts":"2026-04-07T15:20:34.10373Z","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.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.18: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-04-07T15:20:34.107817Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.72894ms"} {"level":"info","ts":"2026-04-07T15:20:34.112989Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6d0358acb671c233","cluster-id":"ba212af698de913f"} {"level":"info","ts":"2026-04-07T15:20:34.113052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T15:20:34.113091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 0"} {"level":"info","ts":"2026-04-07T15:20:34.113107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6d0358acb671c233 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T15:20:34.113118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 1"} {"level":"info","ts":"2026-04-07T15:20:34.113168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"warn","ts":"2026-04-07T15:20:34.118935Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T15:20:34.120101Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T15:20:34.121168Z","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-04-07T15:20:34.125166Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6d0358acb671c233","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T15:20:34.126122Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6d0358acb671c233","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T15:20:34.126355Z","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-04-07T15:20:34.126667Z","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-04-07T15:20:34.126683Z","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-04-07T15:20:34.127826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"info","ts":"2026-04-07T15:20:34.128209Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","added-peer-id":"6d0358acb671c233","added-peer-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-04-07T15:20:34.130598Z","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-04-07T15:20:34.130836Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6d0358acb671c233","initial-advertise-peer-urls":["https://199.19.213.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T15:20:34.130866Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T15:20:34.130991Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-04-07T15:20:34.131008Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-04-07T15:20:34.913514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T15:20:34.913573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T15:20:34.913595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgPreVoteResp from 6d0358acb671c233 at term 1"} {"level":"info","ts":"2026-04-07T15:20:34.913626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became candidate at term 2"} {"level":"info","ts":"2026-04-07T15:20:34.913637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgVoteResp from 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-04-07T15:20:34.913653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became leader at term 2"} {"level":"info","ts":"2026-04-07T15:20:34.913667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6d0358acb671c233 elected leader 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-04-07T15:20:34.917544Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6d0358acb671c233","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.18:2379]}","request-path":"/0/members/6d0358acb671c233/attributes","cluster-id":"ba212af698de913f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T15:20:34.917716Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:20:34.917857Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T15:20:34.917871Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T15:20:34.917788Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:20:34.917822Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:20:34.919039Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:20:34.919152Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:20:34.919187Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:20:34.920034Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T15:20:34.924408Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.18:2379"} {"level":"warn","ts":"2026-04-07T15:20:35.150302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.623469ms","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-04-07T15:20:35.150375Z","caller":"traceutil/trace.go:171","msg":"trace[1318921030] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"126.738022ms","start":"2026-04-07T15:20:35.023624Z","end":"2026-04-07T15:20:35.150362Z","steps":["trace[1318921030] 'count revisions from in-memory index tree' (duration: 126.557736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:35.150393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.678095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:20:35.150416Z","caller":"traceutil/trace.go:171","msg":"trace[528479784] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"113.759547ms","start":"2026-04-07T15:20:35.036649Z","end":"2026-04-07T15:20:35.150409Z","steps":["trace[528479784] 'range keys from in-memory index tree' (duration: 113.629253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:35.150492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.367679ms","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-04-07T15:20:35.150513Z","caller":"traceutil/trace.go:171","msg":"trace[1772581208] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"127.409629ms","start":"2026-04-07T15:20:35.023099Z","end":"2026-04-07T15:20:35.150508Z","steps":["trace[1772581208] 'range keys from in-memory index tree' (duration: 127.252766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:35.150302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.184029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:20:35.150584Z","caller":"traceutil/trace.go:171","msg":"trace[1462547373] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"102.524147ms","start":"2026-04-07T15:20:35.048054Z","end":"2026-04-07T15:20:35.150578Z","steps":["trace[1462547373] 'range keys from in-memory index tree' (duration: 102.153018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:35.15057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.755396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:20:35.150717Z","caller":"traceutil/trace.go:171","msg":"trace[1345808835] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"113.968102ms","start":"2026-04-07T15:20:35.036718Z","end":"2026-04-07T15:20:35.150686Z","steps":["trace[1345808835] 'count revisions from in-memory index tree' (duration: 113.677025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:35.150875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.843886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:20:35.150911Z","caller":"traceutil/trace.go:171","msg":"trace[1644516081] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"102.898837ms","start":"2026-04-07T15:20:35.048003Z","end":"2026-04-07T15:20:35.150902Z","steps":["trace[1644516081] 'count revisions from in-memory index tree' (duration: 102.731622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:40.042335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.824632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:20:40.04252Z","caller":"traceutil/trace.go:171","msg":"trace[58176559] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:277; }","duration":"345.13151ms","start":"2026-04-07T15:20:39.697329Z","end":"2026-04-07T15:20:40.042461Z","steps":["trace[58176559] 'read index received' (duration: 174.533208ms)","trace[58176559] 'applied index is now lower than readState.Index' (duration: 170.596732ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:20:40.042554Z","caller":"traceutil/trace.go:171","msg":"trace[2014699239] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"345.638653ms","start":"2026-04-07T15:20:39.696867Z","end":"2026-04-07T15:20:40.042506Z","steps":["trace[2014699239] 'process raft request' (duration: 174.977419ms)","trace[2014699239] 'compare' (duration: 169.675218ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:20:40.042663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.323445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:20:40.042905Z","caller":"traceutil/trace.go:171","msg":"trace[1465022599] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:273; }","duration":"345.566831ms","start":"2026-04-07T15:20:39.697317Z","end":"2026-04-07T15:20:40.042884Z","steps":["trace[1465022599] 'agreement among raft nodes before linearized reading' (duration: 345.259123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:40.04295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:39.697312Z","time spent":"345.629732ms","remote":"127.0.0.1:33012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-04-07T15:20:40.043345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:39.69685Z","time spent":"345.967621ms","remote":"127.0.0.1:32908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:20:40.108814Z","caller":"traceutil/trace.go:171","msg":"trace[688865316] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"410.923258ms","start":"2026-04-07T15:20:39.697853Z","end":"2026-04-07T15:20:40.108776Z","steps":["trace[688865316] 'process raft request' (duration: 410.664582ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:40.108835Z","caller":"traceutil/trace.go:171","msg":"trace[1286351210] transaction","detail":"{read_only:false; number_of_response:0; response_revision:274; }","duration":"345.412036ms","start":"2026-04-07T15:20:39.763392Z","end":"2026-04-07T15:20:40.108804Z","steps":["trace[1286351210] 'process raft request' (duration: 345.291323ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:40.108935Z","caller":"traceutil/trace.go:171","msg":"trace[2050433542] transaction","detail":"{read_only:false; number_of_response:0; response_revision:274; }","duration":"327.995634ms","start":"2026-04-07T15:20:39.780917Z","end":"2026-04-07T15:20:40.108912Z","steps":["trace[2050433542] 'process raft request' (duration: 327.815299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:40.10898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:39.697846Z","time spent":"411.056902ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:20:40.109025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:39.780901Z","time spent":"328.081947ms","remote":"127.0.0.1:33002","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:20:40.109027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:39.763362Z","time spent":"345.57834ms","remote":"127.0.0.1:32994","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:20:40.109722Z","caller":"traceutil/trace.go:171","msg":"trace[1978707693] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"328.458806ms","start":"2026-04-07T15:20:39.781252Z","end":"2026-04-07T15:20:40.10971Z","steps":["trace[1978707693] 'process raft request' (duration: 328.345473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:40.109793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:39.781246Z","time spent":"328.517138ms","remote":"127.0.0.1:33002","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:20:40.109878Z","caller":"traceutil/trace.go:171","msg":"trace[1054235821] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"328.440926ms","start":"2026-04-07T15:20:39.781419Z","end":"2026-04-07T15:20:40.10986Z","steps":["trace[1054235821] 'process raft request' (duration: 328.22208ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:40.109939Z","caller":"traceutil/trace.go:171","msg":"trace[1058175710] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"328.795985ms","start":"2026-04-07T15:20:39.781133Z","end":"2026-04-07T15:20:40.109929Z","steps":["trace[1058175710] 'process raft request' (duration: 327.623685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:40.109945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:39.78139Z","time spent":"328.523938ms","remote":"127.0.0.1:33002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:20:40.109986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:39.781121Z","time spent":"328.842826ms","remote":"127.0.0.1:33002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:20:40.110133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.728195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-04-07T15:20:40.110165Z","caller":"traceutil/trace.go:171","msg":"trace[1750494378] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:276; }","duration":"412.770766ms","start":"2026-04-07T15:20:39.697384Z","end":"2026-04-07T15:20:40.110155Z","steps":["trace[1750494378] 'agreement among raft nodes before linearized reading' (duration: 412.669924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:40.110193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:39.697381Z","time spent":"412.805338ms","remote":"127.0.0.1:32994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-07T15:20:40.110305Z","caller":"traceutil/trace.go:171","msg":"trace[1645508007] transaction","detail":"{read_only:false; number_of_response:0; response_revision:276; }","duration":"327.722907ms","start":"2026-04-07T15:20:39.782573Z","end":"2026-04-07T15:20:40.110296Z","steps":["trace[1645508007] 'process raft request' (duration: 327.107671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:40.110528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:39.782567Z","time spent":"327.763698ms","remote":"127.0.0.1:33002","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:20:40.484918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.120863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:20:40.485053Z","caller":"traceutil/trace.go:171","msg":"trace[249734729] linearizableReadLoop","detail":"{readStateIndex:286; appliedIndex:285; }","duration":"375.320566ms","start":"2026-04-07T15:20:40.109709Z","end":"2026-04-07T15:20:40.48503Z","steps":["trace[249734729] 'read index received' (duration: 252.141472ms)","trace[249734729] 'applied index is now lower than readState.Index' (duration: 123.177504ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:20:40.485108Z","caller":"traceutil/trace.go:171","msg":"trace[1471753969] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"439.79677ms","start":"2026-04-07T15:20:40.045254Z","end":"2026-04-07T15:20:40.485051Z","steps":["trace[1471753969] 'process raft request' (duration: 316.439471ms)","trace[1471753969] 'compare' (duration: 122.075316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:20:40.485295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:40.045224Z","time spent":"439.987095ms","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1196,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:20:40.485365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.675328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-07T15:20:40.485537Z","caller":"traceutil/trace.go:171","msg":"trace[1984448661] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:277; }","duration":"439.888212ms","start":"2026-04-07T15:20:40.045598Z","end":"2026-04-07T15:20:40.485487Z","steps":["trace[1984448661] 'agreement among raft nodes before linearized reading' (duration: 439.529383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:40.485619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:40.045588Z","time spent":"439.994846ms","remote":"127.0.0.1:32954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T15:20:40.48657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.370888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-04-07T15:20:40.48656Z","caller":"traceutil/trace.go:171","msg":"trace[1666845806] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"372.693338ms","start":"2026-04-07T15:20:40.113808Z","end":"2026-04-07T15:20:40.486502Z","steps":["trace[1666845806] 'process raft request' (duration: 372.518962ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:40.486625Z","caller":"traceutil/trace.go:171","msg":"trace[416120989] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:279; }","duration":"375.42591ms","start":"2026-04-07T15:20:40.111184Z","end":"2026-04-07T15:20:40.48661Z","steps":["trace[416120989] 'agreement among raft nodes before linearized reading' (duration: 375.311887ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:40.486686Z","caller":"traceutil/trace.go:171","msg":"trace[1471199159] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"372.339648ms","start":"2026-04-07T15:20:40.114317Z","end":"2026-04-07T15:20:40.486656Z","steps":["trace[1471199159] 'process raft request' (duration: 372.127302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:40.486716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:40.111119Z","time spent":"375.586014ms","remote":"127.0.0.1:33002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2943,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-07T15:20:40.486749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:40.11378Z","time spent":"372.880913ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:20:40.486783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:40.114311Z","time spent":"372.437301ms","remote":"127.0.0.1:33076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:20:40.486648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.67116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"warn","ts":"2026-04-07T15:20:40.487073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.190514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"info","ts":"2026-04-07T15:20:40.487121Z","caller":"traceutil/trace.go:171","msg":"trace[997357658] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:279; }","duration":"375.253066ms","start":"2026-04-07T15:20:40.111855Z","end":"2026-04-07T15:20:40.487108Z","steps":["trace[997357658] 'agreement among raft nodes before linearized reading' (duration: 375.149643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:40.487168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.883882ms","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":"warn","ts":"2026-04-07T15:20:40.487189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:40.111841Z","time spent":"375.338128ms","remote":"127.0.0.1:33002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5915,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-04-07T15:20:40.487225Z","caller":"traceutil/trace.go:171","msg":"trace[990520692] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:279; }","duration":"376.008215ms","start":"2026-04-07T15:20:40.1112Z","end":"2026-04-07T15:20:40.487209Z","steps":["trace[990520692] 'agreement among raft nodes before linearized reading' (duration: 375.870102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:40.48736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:40.111074Z","time spent":"376.260582ms","remote":"127.0.0.1:33002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5536,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-04-07T15:20:40.486932Z","caller":"traceutil/trace.go:171","msg":"trace[1819623339] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:279; }","duration":"375.016159ms","start":"2026-04-07T15:20:40.111898Z","end":"2026-04-07T15:20:40.486914Z","steps":["trace[1819623339] 'agreement among raft nodes before linearized reading' (duration: 374.629829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:40.4884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:20:40.111836Z","time spent":"376.517378ms","remote":"127.0.0.1:32994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-07T15:21:04.004875Z","caller":"traceutil/trace.go:171","msg":"trace[546418189] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"100.683307ms","start":"2026-04-07T15:21:03.904164Z","end":"2026-04-07T15:21:04.004847Z","steps":["trace[546418189] 'process raft request' (duration: 97.253169ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:04.257254Z","caller":"traceutil/trace.go:171","msg":"trace[628018568] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"162.234704ms","start":"2026-04-07T15:21:04.094983Z","end":"2026-04-07T15:21:04.257217Z","steps":["trace[628018568] 'process raft request' (duration: 144.969151ms)","trace[628018568] 'compare' (duration: 10.334575ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:04.292444Z","caller":"traceutil/trace.go:171","msg":"trace[1787751280] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"195.946769ms","start":"2026-04-07T15:21:04.096459Z","end":"2026-04-07T15:21:04.292406Z","steps":["trace[1787751280] 'process raft request' (duration: 195.58569ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:04.292651Z","caller":"traceutil/trace.go:171","msg":"trace[1459815329] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"194.441721ms","start":"2026-04-07T15:21:04.098178Z","end":"2026-04-07T15:21:04.29262Z","steps":["trace[1459815329] 'process raft request' (duration: 194.169683ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:04.292846Z","caller":"traceutil/trace.go:171","msg":"trace[378270110] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"138.069715ms","start":"2026-04-07T15:21:04.154741Z","end":"2026-04-07T15:21:04.292811Z","steps":["trace[378270110] 'process raft request' (duration: 137.994593ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:04.292968Z","caller":"traceutil/trace.go:171","msg":"trace[274697962] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"181.554481ms","start":"2026-04-07T15:21:04.111397Z","end":"2026-04-07T15:21:04.292951Z","steps":["trace[274697962] 'process raft request' (duration: 181.13634ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:09.621438Z","caller":"traceutil/trace.go:171","msg":"trace[420773263] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"227.143829ms","start":"2026-04-07T15:21:09.394275Z","end":"2026-04-07T15:21:09.621418Z","steps":["trace[420773263] 'process raft request' (duration: 226.739169ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:10.36283Z","caller":"traceutil/trace.go:171","msg":"trace[1870049093] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"137.834215ms","start":"2026-04-07T15:21:10.22497Z","end":"2026-04-07T15:21:10.362804Z","steps":["trace[1870049093] 'process raft request' (duration: 137.483406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:10.971361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.612897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:10.971489Z","caller":"traceutil/trace.go:171","msg":"trace[545777535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"311.792782ms","start":"2026-04-07T15:21:10.659676Z","end":"2026-04-07T15:21:10.971469Z","steps":["trace[545777535] 'range keys from in-memory index tree' (duration: 311.510204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:10.971538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:10.659654Z","time spent":"311.874933ms","remote":"127.0.0.1:32830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T15:21:10.972193Z","caller":"traceutil/trace.go:171","msg":"trace[2027050073] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"188.857116ms","start":"2026-04-07T15:21:10.783308Z","end":"2026-04-07T15:21:10.972165Z","steps":["trace[2027050073] 'read index received' (duration: 179.928119ms)","trace[2027050073] 'applied index is now lower than readState.Index' (duration: 8.927657ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:10.97224Z","caller":"traceutil/trace.go:171","msg":"trace[792991812] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"216.048189ms","start":"2026-04-07T15:21:10.756161Z","end":"2026-04-07T15:21:10.972209Z","steps":["trace[792991812] 'process raft request' (duration: 207.362808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:10.972351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.039171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lj75p\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-07T15:21:10.972439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.626594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T15:21:10.972537Z","caller":"traceutil/trace.go:171","msg":"trace[642881728] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lj75p; range_end:; response_count:1; response_revision:615; }","duration":"189.228706ms","start":"2026-04-07T15:21:10.78328Z","end":"2026-04-07T15:21:10.972508Z","steps":["trace[642881728] 'agreement among raft nodes before linearized reading' (duration: 188.98568ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:10.972871Z","caller":"traceutil/trace.go:171","msg":"trace[2058083585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"156.85791ms","start":"2026-04-07T15:21:10.815742Z","end":"2026-04-07T15:21:10.9726Z","steps":["trace[2058083585] 'agreement among raft nodes before linearized reading' (duration: 156.573333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:26.822974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.062456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:21:26.823069Z","caller":"traceutil/trace.go:171","msg":"trace[1772311919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"162.21405ms","start":"2026-04-07T15:21:26.660834Z","end":"2026-04-07T15:21:26.823048Z","steps":["trace[1772311919] 'range keys from in-memory index tree' (duration: 161.959814ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:57.128387Z","caller":"traceutil/trace.go:171","msg":"trace[867599036] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"113.357509ms","start":"2026-04-07T15:21:57.015004Z","end":"2026-04-07T15:21:57.128362Z","steps":["trace[867599036] 'process raft request' (duration: 113.207145ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:22:19.338135Z","caller":"traceutil/trace.go:171","msg":"trace[1659687385] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"136.552753ms","start":"2026-04-07T15:22:19.201534Z","end":"2026-04-07T15:22:19.338087Z","steps":["trace[1659687385] 'process raft request' (duration: 136.290507ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:22:42.869056Z","caller":"traceutil/trace.go:171","msg":"trace[705496948] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"181.627777ms","start":"2026-04-07T15:22:42.687398Z","end":"2026-04-07T15:22:42.869026Z","steps":["trace[705496948] 'read index received' (duration: 181.398382ms)","trace[705496948] 'applied index is now lower than readState.Index' (duration: 228.155µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:22:42.869211Z","caller":"traceutil/trace.go:171","msg":"trace[822547918] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"186.976594ms","start":"2026-04-07T15:22:42.682221Z","end":"2026-04-07T15:22:42.869197Z","steps":["trace[822547918] 'process raft request' (duration: 186.553214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:22:42.869407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.991355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:22:42.869443Z","caller":"traceutil/trace.go:171","msg":"trace[677036146] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1843; }","duration":"182.081458ms","start":"2026-04-07T15:22:42.687352Z","end":"2026-04-07T15:22:42.869433Z","steps":["trace[677036146] 'agreement among raft nodes before linearized reading' (duration: 181.996776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:22:42.869911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.04433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-07T15:22:42.869993Z","caller":"traceutil/trace.go:171","msg":"trace[1033861228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1843; }","duration":"167.170323ms","start":"2026-04-07T15:22:42.702806Z","end":"2026-04-07T15:22:42.869976Z","steps":["trace[1033861228] 'agreement among raft nodes before linearized reading' (duration: 166.623651ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:23:13.627207Z","caller":"traceutil/trace.go:171","msg":"trace[1597552178] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"100.484146ms","start":"2026-04-07T15:23:13.526683Z","end":"2026-04-07T15:23:13.627167Z","steps":["trace[1597552178] 'process raft request' (duration: 98.500919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:23:16.523017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.158151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:23:16.523133Z","caller":"traceutil/trace.go:171","msg":"trace[50382021] linearizableReadLoop","detail":"{readStateIndex:2161; appliedIndex:2160; }","duration":"137.901815ms","start":"2026-04-07T15:23:16.385215Z","end":"2026-04-07T15:23:16.523116Z","steps":["trace[50382021] 'read index received' (duration: 32.461978ms)","trace[50382021] 'applied index is now lower than readState.Index' (duration: 105.438367ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:23:16.523216Z","caller":"traceutil/trace.go:171","msg":"trace[1411293605] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"220.271876ms","start":"2026-04-07T15:23:16.30293Z","end":"2026-04-07T15:23:16.523202Z","steps":["trace[1411293605] 'process raft request' (duration: 114.840018ms)","trace[1411293605] 'compare' (duration: 105.016918ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:23:16.523612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.400238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"info","ts":"2026-04-07T15:23:16.523654Z","caller":"traceutil/trace.go:171","msg":"trace[1184144654] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2084; }","duration":"138.47612ms","start":"2026-04-07T15:23:16.385166Z","end":"2026-04-07T15:23:16.523642Z","steps":["trace[1184144654] 'agreement among raft nodes before linearized reading' (duration: 138.316806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:23:17.393434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.222045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:23:17.393539Z","caller":"traceutil/trace.go:171","msg":"trace[1358386204] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"225.696019ms","start":"2026-04-07T15:23:17.167826Z","end":"2026-04-07T15:23:17.393522Z","steps":["trace[1358386204] 'process raft request' (duration: 98.323271ms)","trace[1358386204] 'compare' (duration: 127.088692ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:24:38.045176Z","caller":"traceutil/trace.go:171","msg":"trace[920385982] transaction","detail":"{read_only:false; response_revision:2604; number_of_response:1; }","duration":"117.197321ms","start":"2026-04-07T15:24:37.927952Z","end":"2026-04-07T15:24:38.045149Z","steps":["trace[920385982] 'process raft request' (duration: 117.046078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:25:12.435141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.268161ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:25:12.435293Z","caller":"traceutil/trace.go:171","msg":"trace[424749428] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2809; }","duration":"171.428125ms","start":"2026-04-07T15:25:12.263842Z","end":"2026-04-07T15:25:12.43527Z","steps":["trace[424749428] 'range keys from in-memory index tree' (duration: 171.25169ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:25:12.641169Z","caller":"traceutil/trace.go:171","msg":"trace[1908391928] transaction","detail":"{read_only:false; response_revision:2811; number_of_response:1; }","duration":"162.363111ms","start":"2026-04-07T15:25:12.478781Z","end":"2026-04-07T15:25:12.641144Z","steps":["trace[1908391928] 'process raft request' (duration: 162.211267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:25:12.971559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.723345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:25:12.971863Z","caller":"traceutil/trace.go:171","msg":"trace[333402980] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"492.77392ms","start":"2026-04-07T15:25:12.479011Z","end":"2026-04-07T15:25:12.971785Z","steps":["trace[333402980] 'process raft request' (duration: 280.333649ms)","trace[333402980] 'compare' (duration: 211.626033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:25:12.972087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:25:12.47899Z","time spent":"492.991985ms","remote":"127.0.0.1:33076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:28:50.551213Z","caller":"traceutil/trace.go:171","msg":"trace[931986374] linearizableReadLoop","detail":"{readStateIndex:4826; appliedIndex:4825; }","duration":"151.211973ms","start":"2026-04-07T15:28:50.399962Z","end":"2026-04-07T15:28:50.551174Z","steps":["trace[931986374] 'read index received' (duration: 133.292928ms)","trace[931986374] 'applied index is now lower than readState.Index' (duration: 17.917455ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:28:50.551302Z","caller":"traceutil/trace.go:171","msg":"trace[1862831200] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"153.186088ms","start":"2026-04-07T15:28:50.398073Z","end":"2026-04-07T15:28:50.55126Z","steps":["trace[1862831200] 'process raft request' (duration: 135.312044ms)","trace[1862831200] 'compare' (duration: 17.337372ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:28:50.551482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.490019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-154aa5a4-3128-4087-ba9e-a5e3dc725903\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-07T15:28:50.551622Z","caller":"traceutil/trace.go:171","msg":"trace[465276502] range","detail":"{range_begin:/registry/persistentvolumes/pvc-154aa5a4-3128-4087-ba9e-a5e3dc725903; range_end:; response_count:1; response_revision:4649; }","duration":"151.652912ms","start":"2026-04-07T15:28:50.399944Z","end":"2026-04-07T15:28:50.551597Z","steps":["trace[465276502] 'agreement among raft nodes before linearized reading' (duration: 151.357036ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:28:55.693012Z","caller":"traceutil/trace.go:171","msg":"trace[97509532] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"133.716507ms","start":"2026-04-07T15:28:55.559263Z","end":"2026-04-07T15:28:55.69298Z","steps":["trace[97509532] 'process raft request' (duration: 133.463881ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:28:55.887751Z","caller":"traceutil/trace.go:171","msg":"trace[1523933244] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"186.760233ms","start":"2026-04-07T15:28:55.70095Z","end":"2026-04-07T15:28:55.887711Z","steps":["trace[1523933244] 'process raft request' (duration: 132.770005ms)","trace[1523933244] 'compare' (duration: 53.870896ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:28:56.211001Z","caller":"traceutil/trace.go:171","msg":"trace[1542686057] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"141.972793ms","start":"2026-04-07T15:28:56.069007Z","end":"2026-04-07T15:28:56.21098Z","steps":["trace[1542686057] 'process raft request' (duration: 141.8515ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:28:56.308061Z","caller":"traceutil/trace.go:171","msg":"trace[367490806] linearizableReadLoop","detail":"{readStateIndex:4938; appliedIndex:4936; }","duration":"111.019274ms","start":"2026-04-07T15:28:56.19702Z","end":"2026-04-07T15:28:56.308039Z","steps":["trace[367490806] 'read index received' (duration: 13.892373ms)","trace[367490806] 'applied index is now lower than readState.Index' (duration: 97.125731ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:28:56.308248Z","caller":"traceutil/trace.go:171","msg":"trace[1969353301] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"234.208943ms","start":"2026-04-07T15:28:56.073975Z","end":"2026-04-07T15:28:56.308184Z","steps":["trace[1969353301] 'process raft request' (duration: 233.900297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:28:56.308341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.28593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T15:28:56.30838Z","caller":"traceutil/trace.go:171","msg":"trace[187824138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4760; }","duration":"111.397212ms","start":"2026-04-07T15:28:56.196971Z","end":"2026-04-07T15:28:56.308368Z","steps":["trace[187824138] 'agreement among raft nodes before linearized reading' (duration: 111.184038ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:28:56.436096Z","caller":"traceutil/trace.go:171","msg":"trace[975277929] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"115.783591ms","start":"2026-04-07T15:28:56.320272Z","end":"2026-04-07T15:28:56.436056Z","steps":["trace[975277929] 'process raft request' (duration: 64.591837ms)","trace[975277929] 'compare' (duration: 51.029491ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:29:08.273924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.291016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.18\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-07T15:29:08.274021Z","caller":"traceutil/trace.go:171","msg":"trace[65348818] range","detail":"{range_begin:/registry/masterleases/199.19.213.18; range_end:; response_count:1; response_revision:4935; }","duration":"315.453269ms","start":"2026-04-07T15:29:07.958547Z","end":"2026-04-07T15:29:08.274Z","steps":["trace[65348818] 'range keys from in-memory index tree' (duration: 315.086751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:08.274065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:29:07.958522Z","time spent":"315.532971ms","remote":"127.0.0.1:32876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.18\" "} {"level":"info","ts":"2026-04-07T15:29:08.278844Z","caller":"traceutil/trace.go:171","msg":"trace[944681557] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"308.001402ms","start":"2026-04-07T15:29:07.970809Z","end":"2026-04-07T15:29:08.27881Z","steps":["trace[944681557] 'process raft request' (duration: 307.761907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:08.279242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:29:07.970787Z","time spent":"308.233458ms","remote":"127.0.0.1:33076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:29:08.279292Z","caller":"traceutil/trace.go:171","msg":"trace[1884744139] linearizableReadLoop","detail":"{readStateIndex:5120; appliedIndex:5120; }","duration":"300.097444ms","start":"2026-04-07T15:29:07.979164Z","end":"2026-04-07T15:29:08.279261Z","steps":["trace[1884744139] 'read index received' (duration: 300.084804ms)","trace[1884744139] 'applied index is now lower than readState.Index' (duration: 9.93µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:29:08.279544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.3598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T15:29:08.279599Z","caller":"traceutil/trace.go:171","msg":"trace[983499071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4936; }","duration":"300.482042ms","start":"2026-04-07T15:29:07.979102Z","end":"2026-04-07T15:29:08.279584Z","steps":["trace[983499071] 'agreement among raft nodes before linearized reading' (duration: 300.297438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:08.279697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:29:07.979074Z","time spent":"300.565654ms","remote":"127.0.0.1:33076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T15:29:08.293455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.986655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-04-07T15:29:08.293534Z","caller":"traceutil/trace.go:171","msg":"trace[1673024030] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4936; }","duration":"105.115289ms","start":"2026-04-07T15:29:08.188399Z","end":"2026-04-07T15:29:08.293514Z","steps":["trace[1673024030] 'agreement among raft nodes before linearized reading' (duration: 104.921335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:29:08.293268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.187758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:29:08.293966Z","caller":"traceutil/trace.go:171","msg":"trace[823356421] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:4936; }","duration":"175.937975ms","start":"2026-04-07T15:29:08.118017Z","end":"2026-04-07T15:29:08.293955Z","steps":["trace[823356421] 'agreement among raft nodes before linearized reading' (duration: 175.162018ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:30:34.945097Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2929} {"level":"info","ts":"2026-04-07T15:30:35.015694Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2929,"took":"69.078628ms","hash":407563205,"current-db-size-bytes":53882880,"current-db-size":"54 MB","current-db-size-in-use-bytes":29044736,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T15:30:35.015751Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":407563205,"revision":2929,"compact-revision":-1} {"level":"info","ts":"2026-04-07T15:33:05.738471Z","caller":"traceutil/trace.go:171","msg":"trace[1046901228] transaction","detail":"{read_only:false; response_revision:6683; number_of_response:1; }","duration":"106.922712ms","start":"2026-04-07T15:33:05.630657Z","end":"2026-04-07T15:33:05.73758Z","steps":["trace[1046901228] 'process raft request' (duration: 106.728158ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:35:34.952167Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5603} {"level":"info","ts":"2026-04-07T15:35:35.028892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5603,"took":"74.667818ms","hash":3733313421,"current-db-size-bytes":53882880,"current-db-size":"54 MB","current-db-size-in-use-bytes":18124800,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-07T15:35:35.028956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3733313421,"revision":5603,"compact-revision":2929}