{"level":"warn","ts":"2026-09-09T01:00:49.076833Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T01:00:49.083694Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.92:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.92:2380","--initial-cluster=instance=https://162.253.55.92:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.92:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.92: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-09-09T01:00:49.084197Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T01:00:49.084223Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.92:2380"]} {"level":"info","ts":"2026-09-09T01:00:49.084438Z","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-09-09T01:00:49.085462Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"]} {"level":"info","ts":"2026-09-09T01:00:49.085784Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.92:2380"],"listen-peer-urls":["https://162.253.55.92:2380"],"advertise-client-urls":["https://162.253.55.92:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.92: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-09-09T01:00:49.091164Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.056211ms"} {"level":"info","ts":"2026-09-09T01:00:49.097559Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39a4f14f6507253d","cluster-id":"22d06cab78b8ace5"} {"level":"info","ts":"2026-09-09T01:00:49.097606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T01:00:49.099374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became follower at term 0"} {"level":"info","ts":"2026-09-09T01:00:49.099437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39a4f14f6507253d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T01:00:49.099452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became follower at term 1"} {"level":"info","ts":"2026-09-09T01:00:49.099538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=(4153710079642117437)"} {"level":"warn","ts":"2026-09-09T01:00:49.106183Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T01:00:49.10764Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T01:00:49.109028Z","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-09-09T01:00:49.111078Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39a4f14f6507253d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T01:00:49.111321Z","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-09-09T01:00:49.111376Z","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-09-09T01:00:49.111412Z","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-09-09T01:00:49.111188Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39a4f14f6507253d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T01:00:49.112879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=(4153710079642117437)"} {"level":"info","ts":"2026-09-09T01:00:49.112994Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"22d06cab78b8ace5","local-member-id":"39a4f14f6507253d","added-peer-id":"39a4f14f6507253d","added-peer-peer-urls":["https://162.253.55.92:2380"]} {"level":"info","ts":"2026-09-09T01:00:49.114722Z","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-09-09T01:00:49.114836Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.92:2380"} {"level":"info","ts":"2026-09-09T01:00:49.114866Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.92:2380"} {"level":"info","ts":"2026-09-09T01:00:49.115042Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39a4f14f6507253d","initial-advertise-peer-urls":["https://162.253.55.92:2380"],"listen-peer-urls":["https://162.253.55.92:2380"],"advertise-client-urls":["https://162.253.55.92:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T01:00:49.115083Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T01:00:49.200407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T01:00:49.200478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T01:00:49.20051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d received MsgPreVoteResp from 39a4f14f6507253d at term 1"} {"level":"info","ts":"2026-09-09T01:00:49.200525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became candidate at term 2"} {"level":"info","ts":"2026-09-09T01:00:49.200532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d received MsgVoteResp from 39a4f14f6507253d at term 2"} {"level":"info","ts":"2026-09-09T01:00:49.200545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became leader at term 2"} {"level":"info","ts":"2026-09-09T01:00:49.200562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39a4f14f6507253d elected leader 39a4f14f6507253d at term 2"} {"level":"info","ts":"2026-09-09T01:00:49.201609Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39a4f14f6507253d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.92:2379]}","request-path":"/0/members/39a4f14f6507253d/attributes","cluster-id":"22d06cab78b8ace5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T01:00:49.201689Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:00:49.201778Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:00:49.202171Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T01:00:49.202229Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T01:00:49.202217Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:00:49.204259Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T01:00:49.205032Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.92:2379"} {"level":"info","ts":"2026-09-09T01:00:49.205243Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"22d06cab78b8ace5","local-member-id":"39a4f14f6507253d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:00:49.20538Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:00:49.205418Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:00:53.338546Z","caller":"traceutil/trace.go:171","msg":"trace[1955976031] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:250; }","duration":"175.78001ms","start":"2026-09-09T01:00:53.162741Z","end":"2026-09-09T01:00:53.338521Z","steps":["trace[1955976031] 'read index received' (duration: 175.577896ms)","trace[1955976031] 'applied index is now lower than readState.Index' (duration: 200.884µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:00:53.338822Z","caller":"traceutil/trace.go:171","msg":"trace[1097613949] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"249.528066ms","start":"2026-09-09T01:00:53.08922Z","end":"2026-09-09T01:00:53.338748Z","steps":["trace[1097613949] 'process raft request' (duration: 249.132028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:53.339126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.364692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:00:53.339183Z","caller":"traceutil/trace.go:171","msg":"trace[1115975325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:245; }","duration":"176.491895ms","start":"2026-09-09T01:00:53.162673Z","end":"2026-09-09T01:00:53.339165Z","steps":["trace[1115975325] 'agreement among raft nodes before linearized reading' (duration: 176.382283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:53.339076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.950187ms","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-09-09T01:00:53.339523Z","caller":"traceutil/trace.go:171","msg":"trace[11970423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:245; }","duration":"150.431868ms","start":"2026-09-09T01:00:53.189064Z","end":"2026-09-09T01:00:53.339496Z","steps":["trace[11970423] 'agreement among raft nodes before linearized reading' (duration: 149.938287ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:53.573141Z","caller":"traceutil/trace.go:171","msg":"trace[1390558874] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"228.856017ms","start":"2026-09-09T01:00:53.344255Z","end":"2026-09-09T01:00:53.573111Z","steps":["trace[1390558874] 'process raft request' (duration: 225.690117ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:53.573263Z","caller":"traceutil/trace.go:171","msg":"trace[719114981] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"228.845578ms","start":"2026-09-09T01:00:53.344392Z","end":"2026-09-09T01:00:53.573238Z","steps":["trace[719114981] 'process raft request' (duration: 228.648592ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:53.573563Z","caller":"traceutil/trace.go:171","msg":"trace[1092321730] linearizableReadLoop","detail":"{readStateIndex:254; appliedIndex:252; }","duration":"187.668263ms","start":"2026-09-09T01:00:53.385885Z","end":"2026-09-09T01:00:53.573553Z","steps":["trace[1092321730] 'read index received' (duration: 184.064253ms)","trace[1092321730] 'applied index is now lower than readState.Index' (duration: 3.60272ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:53.573698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.799016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:00:53.573728Z","caller":"traceutil/trace.go:171","msg":"trace[2033726089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:248; }","duration":"187.840377ms","start":"2026-09-09T01:00:53.38588Z","end":"2026-09-09T01:00:53.57372Z","steps":["trace[2033726089] 'agreement among raft nodes before linearized reading' (duration: 187.737745ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:53.732039Z","caller":"traceutil/trace.go:171","msg":"trace[1830312242] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:254; }","duration":"157.919834ms","start":"2026-09-09T01:00:53.574096Z","end":"2026-09-09T01:00:53.732015Z","steps":["trace[1830312242] 'read index received' (duration: 148.192648ms)","trace[1830312242] 'applied index is now lower than readState.Index' (duration: 9.726286ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:00:53.732228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.13098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-09T01:00:53.732269Z","caller":"traceutil/trace.go:171","msg":"trace[1757290547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:249; }","duration":"346.262952ms","start":"2026-09-09T01:00:53.385996Z","end":"2026-09-09T01:00:53.732259Z","steps":["trace[1757290547] 'agreement among raft nodes before linearized reading' (duration: 346.134029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:53.732323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.053492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-09T01:00:53.732171Z","caller":"traceutil/trace.go:171","msg":"trace[488232927] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"345.736951ms","start":"2026-09-09T01:00:53.386409Z","end":"2026-09-09T01:00:53.732146Z","steps":["trace[488232927] 'process raft request' (duration: 335.964554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:53.732369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:53.385979Z","time spent":"346.378714ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-09T01:00:53.732422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.413001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:00:53.732459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.665874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-jay9lv\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-09-09T01:00:53.732433Z","caller":"traceutil/trace.go:171","msg":"trace[602037175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:249; }","duration":"156.185774ms","start":"2026-09-09T01:00:53.576229Z","end":"2026-09-09T01:00:53.732415Z","steps":["trace[602037175] 'agreement among raft nodes before linearized reading' (duration: 155.98948ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:53.732504Z","caller":"traceutil/trace.go:171","msg":"trace[582188090] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:0; response_revision:249; }","duration":"147.541093ms","start":"2026-09-09T01:00:53.584946Z","end":"2026-09-09T01:00:53.732487Z","steps":["trace[582188090] 'agreement among raft nodes before linearized reading' (duration: 147.340189ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:00:53.732525Z","caller":"traceutil/trace.go:171","msg":"trace[1897475571] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-jay9lv; range_end:; response_count:1; response_revision:249; }","duration":"155.745465ms","start":"2026-09-09T01:00:53.576763Z","end":"2026-09-09T01:00:53.732509Z","steps":["trace[1897475571] 'agreement among raft nodes before linearized reading' (duration: 155.610833ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:00:53.733271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:00:53.386398Z","time spent":"346.036287ms","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T01:01:31.075149Z","caller":"traceutil/trace.go:171","msg":"trace[1960917281] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"108.706669ms","start":"2026-09-09T01:01:30.966406Z","end":"2026-09-09T01:01:31.075112Z","steps":["trace[1960917281] 'process raft request' (duration: 101.923548ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:31.414819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.910441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-09-09T01:01:31.415005Z","caller":"traceutil/trace.go:171","msg":"trace[1130460464] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:614; }","duration":"127.181688ms","start":"2026-09-09T01:01:31.287765Z","end":"2026-09-09T01:01:31.414947Z","steps":["trace[1130460464] 'range keys from in-memory index tree' (duration: 126.530893ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:37.574867Z","caller":"traceutil/trace.go:171","msg":"trace[927925993] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"121.514415ms","start":"2026-09-09T01:01:37.453327Z","end":"2026-09-09T01:01:37.574842Z","steps":["trace[927925993] 'process raft request' (duration: 121.341421ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:37.71221Z","caller":"traceutil/trace.go:171","msg":"trace[507283140] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"255.884625ms","start":"2026-09-09T01:01:37.456293Z","end":"2026-09-09T01:01:37.712178Z","steps":["trace[507283140] 'process raft request' (duration: 238.237447ms)","trace[507283140] 'compare' (duration: 17.524676ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:01:37.907552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.447848ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-09-09T01:01:38.456797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.317843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-09T01:01:38.456896Z","caller":"traceutil/trace.go:171","msg":"trace[1296086320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:658; }","duration":"358.472136ms","start":"2026-09-09T01:01:38.0984Z","end":"2026-09-09T01:01:38.456873Z","steps":["trace[1296086320] 'range keys from in-memory index tree' (duration: 358.114709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:38.456916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.115444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-09T01:01:38.456946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:38.09838Z","time spent":"358.553459ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-09T01:01:38.456984Z","caller":"traceutil/trace.go:171","msg":"trace[344299050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:658; }","duration":"373.201016ms","start":"2026-09-09T01:01:38.083765Z","end":"2026-09-09T01:01:38.456966Z","steps":["trace[344299050] 'range keys from in-memory index tree' (duration: 372.891798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:38.457024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:38.083711Z","time spent":"373.304208ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-09T01:01:38.456976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.457711ms","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-09-09T01:01:38.457092Z","caller":"traceutil/trace.go:171","msg":"trace[87395286] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:658; }","duration":"153.578614ms","start":"2026-09-09T01:01:38.303493Z","end":"2026-09-09T01:01:38.457072Z","steps":["trace[87395286] 'range keys from in-memory index tree' (duration: 153.443851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:38.457267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.026729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:01:38.457303Z","caller":"traceutil/trace.go:171","msg":"trace[69987178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"359.098061ms","start":"2026-09-09T01:01:38.098193Z","end":"2026-09-09T01:01:38.457291Z","steps":["trace[69987178] 'range keys from in-memory index tree' (duration: 358.961838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:01:38.457332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:01:38.098174Z","time spent":"359.152062ms","remote":"127.0.0.1:55538","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-09-09T01:01:38.581219Z","caller":"traceutil/trace.go:171","msg":"trace[762446495] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"118.760259ms","start":"2026-09-09T01:01:38.462429Z","end":"2026-09-09T01:01:38.58119Z","steps":["trace[762446495] 'process raft request' (duration: 118.39608ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:38.583352Z","caller":"traceutil/trace.go:171","msg":"trace[471453387] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"119.721851ms","start":"2026-09-09T01:01:38.4636Z","end":"2026-09-09T01:01:38.583322Z","steps":["trace[471453387] 'process raft request' (duration: 119.552367ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:01:53.155064Z","caller":"traceutil/trace.go:171","msg":"trace[658625030] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"210.524666ms","start":"2026-09-09T01:01:52.944514Z","end":"2026-09-09T01:01:53.155038Z","steps":["trace[658625030] 'process raft request' (duration: 210.133776ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:02:06.103511Z","caller":"traceutil/trace.go:171","msg":"trace[70295227] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"139.151019ms","start":"2026-09-09T01:02:05.964306Z","end":"2026-09-09T01:02:06.103457Z","steps":["trace[70295227] 'process raft request' (duration: 79.838215ms)","trace[70295227] 'compare' (duration: 58.907413ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:02:06.684507Z","caller":"traceutil/trace.go:171","msg":"trace[849979893] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"314.22985ms","start":"2026-09-09T01:02:06.370239Z","end":"2026-09-09T01:02:06.684468Z","steps":["trace[849979893] 'process raft request' (duration: 314.027375ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:02:06.684749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:02:06.370201Z","time spent":"314.408075ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2492,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T01:02:33.383694Z","caller":"traceutil/trace.go:171","msg":"trace[48132103] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"111.370599ms","start":"2026-09-09T01:02:33.272289Z","end":"2026-09-09T01:02:33.38366Z","steps":["trace[48132103] 'process raft request' (duration: 108.285496ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:03:00.361325Z","caller":"traceutil/trace.go:171","msg":"trace[402215502] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"103.357651ms","start":"2026-09-09T01:03:00.257927Z","end":"2026-09-09T01:03:00.361285Z","steps":["trace[402215502] 'process raft request' (duration: 35.699159ms)","trace[402215502] 'compare' (duration: 66.595718ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:03:16.78299Z","caller":"traceutil/trace.go:171","msg":"trace[1460532281] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1972; }","duration":"178.341254ms","start":"2026-09-09T01:03:16.604623Z","end":"2026-09-09T01:03:16.782964Z","steps":["trace[1460532281] 'read index received' (duration: 178.192151ms)","trace[1460532281] 'applied index is now lower than readState.Index' (duration: 148.223µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:03:16.783326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.689813ms","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-09-09T01:03:16.783372Z","caller":"traceutil/trace.go:171","msg":"trace[1722599369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1897; }","duration":"178.788854ms","start":"2026-09-09T01:03:16.60457Z","end":"2026-09-09T01:03:16.783359Z","steps":["trace[1722599369] 'agreement among raft nodes before linearized reading' (duration: 178.510998ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:03:16.783357Z","caller":"traceutil/trace.go:171","msg":"trace[538959479] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"403.847653ms","start":"2026-09-09T01:03:16.379454Z","end":"2026-09-09T01:03:16.783302Z","steps":["trace[538959479] 'process raft request' (duration: 403.330371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:03:16.783818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:03:16.379421Z","time spent":"404.016297ms","remote":"127.0.0.1:55710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10931,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:03:16.873859Z","caller":"traceutil/trace.go:171","msg":"trace[854163803] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"262.21327ms","start":"2026-09-09T01:03:16.611619Z","end":"2026-09-09T01:03:16.873832Z","steps":["trace[854163803] 'process raft request' (duration: 262.061346ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:03:16.930468Z","caller":"traceutil/trace.go:171","msg":"trace[1188018978] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"140.406905ms","start":"2026-09-09T01:03:16.790028Z","end":"2026-09-09T01:03:16.930435Z","steps":["trace[1188018978] 'process raft request' (duration: 132.768519ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:03:16.930893Z","caller":"traceutil/trace.go:171","msg":"trace[216337643] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"138.701526ms","start":"2026-09-09T01:03:16.792163Z","end":"2026-09-09T01:03:16.930865Z","steps":["trace[216337643] 'process raft request' (duration: 138.312336ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:03:16.930916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.674973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-09T01:03:16.930987Z","caller":"traceutil/trace.go:171","msg":"trace[1453111457] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1903; }","duration":"128.787676ms","start":"2026-09-09T01:03:16.802177Z","end":"2026-09-09T01:03:16.930964Z","steps":["trace[1453111457] 'agreement among raft nodes before linearized reading' (duration: 128.363436ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:03:16.93066Z","caller":"traceutil/trace.go:171","msg":"trace[2019657351] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"139.817111ms","start":"2026-09-09T01:03:16.790763Z","end":"2026-09-09T01:03:16.93058Z","steps":["trace[2019657351] 'process raft request' (duration: 139.508104ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:03:16.930652Z","caller":"traceutil/trace.go:171","msg":"trace[808291082] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"139.465554ms","start":"2026-09-09T01:03:16.791154Z","end":"2026-09-09T01:03:16.930619Z","steps":["trace[808291082] 'process raft request' (duration: 139.206388ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:03:16.930878Z","caller":"traceutil/trace.go:171","msg":"trace[1317591195] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"139.484035ms","start":"2026-09-09T01:03:16.791376Z","end":"2026-09-09T01:03:16.93086Z","steps":["trace[1317591195] 'process raft request' (duration: 139.050614ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:03:17.029299Z","caller":"traceutil/trace.go:171","msg":"trace[1551585717] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"147.151422ms","start":"2026-09-09T01:03:16.882123Z","end":"2026-09-09T01:03:17.029274Z","steps":["trace[1551585717] 'process raft request' (duration: 147.019579ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:04:05.821808Z","caller":"traceutil/trace.go:171","msg":"trace[550228291] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"126.346369ms","start":"2026-09-09T01:04:05.695434Z","end":"2026-09-09T01:04:05.821781Z","steps":["trace[550228291] 'process raft request' (duration: 32.451889ms)","trace[550228291] 'compare' (duration: 93.780698ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:04:06.208266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.382155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:04:06.208353Z","caller":"traceutil/trace.go:171","msg":"trace[1381203462] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2219; }","duration":"237.541078ms","start":"2026-09-09T01:04:05.970792Z","end":"2026-09-09T01:04:06.208333Z","steps":["trace[1381203462] 'count revisions from in-memory index tree' (duration: 237.262512ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:04:06.208782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.538793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:04:06.208842Z","caller":"traceutil/trace.go:171","msg":"trace[544370883] linearizableReadLoop","detail":"{readStateIndex:2307; appliedIndex:2306; }","duration":"112.51276ms","start":"2026-09-09T01:04:06.096321Z","end":"2026-09-09T01:04:06.208833Z","steps":["trace[544370883] 'read index received' (duration: 47.411µs)","trace[544370883] 'applied index is now lower than readState.Index' (duration: 112.464509ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:04:06.209014Z","caller":"traceutil/trace.go:171","msg":"trace[70434913] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"168.3595ms","start":"2026-09-09T01:04:06.040645Z","end":"2026-09-09T01:04:06.209004Z","steps":["trace[70434913] 'process raft request' (duration: 19.50441ms)","trace[70434913] 'compare' (duration: 148.303887ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:04:06.20936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.057382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:04:06.209387Z","caller":"traceutil/trace.go:171","msg":"trace[1782683341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2220; }","duration":"113.119964ms","start":"2026-09-09T01:04:06.096261Z","end":"2026-09-09T01:04:06.209381Z","steps":["trace[1782683341] 'agreement among raft nodes before linearized reading' (duration: 113.016371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:04:06.510985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.57783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:04:06.511042Z","caller":"traceutil/trace.go:171","msg":"trace[1750924431] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2220; }","duration":"118.650682ms","start":"2026-09-09T01:04:06.392376Z","end":"2026-09-09T01:04:06.511027Z","steps":["trace[1750924431] 'count revisions from in-memory index tree' (duration: 118.441917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:04:06.510989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.73887ms","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-09-09T01:04:06.511158Z","caller":"traceutil/trace.go:171","msg":"trace[619987738] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2220; }","duration":"191.973426ms","start":"2026-09-09T01:04:06.319179Z","end":"2026-09-09T01:04:06.511152Z","steps":["trace[619987738] 'count revisions from in-memory index tree' (duration: 191.611397ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:04:47.507433Z","caller":"traceutil/trace.go:171","msg":"trace[938457329] transaction","detail":"{read_only:false; response_revision:2548; number_of_response:1; }","duration":"110.087781ms","start":"2026-09-09T01:04:47.397313Z","end":"2026-09-09T01:04:47.507401Z","steps":["trace[938457329] 'process raft request' (duration: 109.854665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:04:48.277858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.960809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:04:48.277936Z","caller":"traceutil/trace.go:171","msg":"trace[1873864915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2553; }","duration":"179.101443ms","start":"2026-09-09T01:04:48.098817Z","end":"2026-09-09T01:04:48.277918Z","steps":["trace[1873864915] 'range keys from in-memory index tree' (duration: 178.874017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:05:19.893514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.144917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-09T01:05:19.893668Z","caller":"traceutil/trace.go:171","msg":"trace[849893003] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2726; }","duration":"115.348973ms","start":"2026-09-09T01:05:19.778299Z","end":"2026-09-09T01:05:19.893648Z","steps":["trace[849893003] 'range keys from in-memory index tree' (duration: 114.973022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:05:20.154192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.604179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-09T01:05:20.154385Z","caller":"traceutil/trace.go:171","msg":"trace[1919313971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2728; }","duration":"125.848665ms","start":"2026-09-09T01:05:20.028511Z","end":"2026-09-09T01:05:20.15436Z","steps":["trace[1919313971] 'range keys from in-memory index tree' (duration: 125.422643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:05:25.347439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.323635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:05:25.347535Z","caller":"traceutil/trace.go:171","msg":"trace[2026947928] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2755; }","duration":"292.463679ms","start":"2026-09-09T01:05:25.055046Z","end":"2026-09-09T01:05:25.34751Z","steps":["trace[2026947928] 'count revisions from in-memory index tree' (duration: 292.257764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:05:25.348447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.882155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-09-09T01:05:25.348507Z","caller":"traceutil/trace.go:171","msg":"trace[1268527003] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2755; }","duration":"249.962798ms","start":"2026-09-09T01:05:25.098529Z","end":"2026-09-09T01:05:25.348492Z","steps":["trace[1268527003] 'range keys from in-memory index tree' (duration: 249.775353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:05:25.348706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.50612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:05:25.34873Z","caller":"traceutil/trace.go:171","msg":"trace[1286908023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2755; }","duration":"251.555501ms","start":"2026-09-09T01:05:25.097168Z","end":"2026-09-09T01:05:25.348723Z","steps":["trace[1286908023] 'range keys from in-memory index tree' (duration: 251.461579ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:50.697781Z","caller":"traceutil/trace.go:171","msg":"trace[1343152832] linearizableReadLoop","detail":"{readStateIndex:4905; appliedIndex:4903; }","duration":"122.156366ms","start":"2026-09-09T01:09:50.575598Z","end":"2026-09-09T01:09:50.697754Z","steps":["trace[1343152832] 'read index received' (duration: 58.249496ms)","trace[1343152832] 'applied index is now lower than readState.Index' (duration: 63.90587ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:50.697868Z","caller":"traceutil/trace.go:171","msg":"trace[1916797037] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"121.708435ms","start":"2026-09-09T01:09:50.576119Z","end":"2026-09-09T01:09:50.697827Z","steps":["trace[1916797037] 'process raft request' (duration: 121.584282ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:50.698027Z","caller":"traceutil/trace.go:171","msg":"trace[1897741222] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"124.983726ms","start":"2026-09-09T01:09:50.573012Z","end":"2026-09-09T01:09:50.697996Z","steps":["trace[1897741222] 'process raft request' (duration: 124.535314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:50.698089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.416573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-09T01:09:50.698143Z","caller":"traceutil/trace.go:171","msg":"trace[2120041195] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4722; }","duration":"122.512986ms","start":"2026-09-09T01:09:50.575612Z","end":"2026-09-09T01:09:50.698125Z","steps":["trace[2120041195] 'agreement among raft nodes before linearized reading' (duration: 122.30006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:50.698182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.559487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:46951"} {"level":"info","ts":"2026-09-09T01:09:50.698247Z","caller":"traceutil/trace.go:171","msg":"trace[1421829370] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4722; }","duration":"122.674029ms","start":"2026-09-09T01:09:50.575555Z","end":"2026-09-09T01:09:50.698228Z","steps":["trace[1421829370] 'agreement among raft nodes before linearized reading' (duration: 122.3356ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:56.837162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.480259ms","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-09-09T01:09:56.837269Z","caller":"traceutil/trace.go:171","msg":"trace[1477379844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4874; }","duration":"294.613473ms","start":"2026-09-09T01:09:56.542627Z","end":"2026-09-09T01:09:56.837241Z","steps":["trace[1477379844] 'range keys from in-memory index tree' (duration: 294.321395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:56.837439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.566337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-09T01:09:56.837444Z","caller":"traceutil/trace.go:171","msg":"trace[1119055205] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"176.424504ms","start":"2026-09-09T01:09:56.660991Z","end":"2026-09-09T01:09:56.837415Z","steps":["trace[1119055205] 'process raft request' (duration: 155.134574ms)","trace[1119055205] 'compare' (duration: 21.148346ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:56.837477Z","caller":"traceutil/trace.go:171","msg":"trace[896192787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4874; }","duration":"283.634079ms","start":"2026-09-09T01:09:56.553832Z","end":"2026-09-09T01:09:56.837466Z","steps":["trace[896192787] 'range keys from in-memory index tree' (duration: 283.413633ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:56.851414Z","caller":"traceutil/trace.go:171","msg":"trace[1864140163] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"117.829571ms","start":"2026-09-09T01:09:56.733536Z","end":"2026-09-09T01:09:56.851366Z","steps":["trace[1864140163] 'process raft request' (duration: 117.657147ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:02.765137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.535751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:10:02.765198Z","caller":"traceutil/trace.go:171","msg":"trace[786646452] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4922; }","duration":"140.635574ms","start":"2026-09-09T01:10:02.624546Z","end":"2026-09-09T01:10:02.765182Z","steps":["trace[786646452] 'count revisions from in-memory index tree' (duration: 140.438849ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:03.493678Z","caller":"traceutil/trace.go:171","msg":"trace[781192152] linearizableReadLoop","detail":"{readStateIndex:5121; appliedIndex:5120; }","duration":"247.839678ms","start":"2026-09-09T01:10:03.245814Z","end":"2026-09-09T01:10:03.493654Z","steps":["trace[781192152] 'read index received' (duration: 247.735805ms)","trace[781192152] 'applied index is now lower than readState.Index' (duration: 102.743µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:03.493808Z","caller":"traceutil/trace.go:171","msg":"trace[227140832] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"257.541708ms","start":"2026-09-09T01:10:03.236235Z","end":"2026-09-09T01:10:03.493777Z","steps":["trace[227140832] 'process raft request' (duration: 257.241091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:03.493907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.073094ms","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-09-09T01:10:03.493948Z","caller":"traceutil/trace.go:171","msg":"trace[1686879245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4929; }","duration":"248.154016ms","start":"2026-09-09T01:10:03.245781Z","end":"2026-09-09T01:10:03.493935Z","steps":["trace[1686879245] 'agreement among raft nodes before linearized reading' (duration: 248.011582ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:03.508583Z","caller":"traceutil/trace.go:171","msg":"trace[460607939] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"254.854455ms","start":"2026-09-09T01:10:03.253711Z","end":"2026-09-09T01:10:03.508566Z","steps":["trace[460607939] 'process raft request' (duration: 254.724872ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:03.50874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.774395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-09T01:10:03.508825Z","caller":"traceutil/trace.go:171","msg":"trace[1160996356] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4930; }","duration":"155.892748ms","start":"2026-09-09T01:10:03.352916Z","end":"2026-09-09T01:10:03.508808Z","steps":["trace[1160996356] 'agreement among raft nodes before linearized reading' (duration: 155.714924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:03.509255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.22565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-09-09T01:10:03.509314Z","caller":"traceutil/trace.go:171","msg":"trace[606615277] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4930; }","duration":"162.311092ms","start":"2026-09-09T01:10:03.346988Z","end":"2026-09-09T01:10:03.509299Z","steps":["trace[606615277] 'agreement among raft nodes before linearized reading' (duration: 161.569273ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:03.709058Z","caller":"traceutil/trace.go:171","msg":"trace[956065066] linearizableReadLoop","detail":"{readStateIndex:5128; appliedIndex:5126; }","duration":"111.913637ms","start":"2026-09-09T01:10:03.597115Z","end":"2026-09-09T01:10:03.709028Z","steps":["trace[956065066] 'read index received' (duration: 92.537503ms)","trace[956065066] 'applied index is now lower than readState.Index' (duration: 19.374474ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:03.709385Z","caller":"traceutil/trace.go:171","msg":"trace[322468589] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"112.231834ms","start":"2026-09-09T01:10:03.597078Z","end":"2026-09-09T01:10:03.70931Z","steps":["trace[322468589] 'process raft request' (duration: 111.662771ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:03.709445Z","caller":"traceutil/trace.go:171","msg":"trace[1603111730] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"111.930787ms","start":"2026-09-09T01:10:03.597505Z","end":"2026-09-09T01:10:03.709435Z","steps":["trace[1603111730] 'process raft request' (duration: 111.306762ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:03.709527Z","caller":"traceutil/trace.go:171","msg":"trace[1313442232] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"110.654287ms","start":"2026-09-09T01:10:03.598854Z","end":"2026-09-09T01:10:03.709508Z","steps":["trace[1313442232] 'process raft request' (duration: 110.066432ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:03.70966Z","caller":"traceutil/trace.go:171","msg":"trace[2138465201] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"112.683355ms","start":"2026-09-09T01:10:03.59697Z","end":"2026-09-09T01:10:03.709653Z","steps":["trace[2138465201] 'process raft request' (duration: 111.608989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:03.709964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.838399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16230"} {"level":"warn","ts":"2026-09-09T01:10:03.709965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.834949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5469db47-f335-448f-9c4a-95f82e90b1ed\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-09-09T01:10:03.709991Z","caller":"traceutil/trace.go:171","msg":"trace[1372011354] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4940; }","duration":"112.910121ms","start":"2026-09-09T01:10:03.597075Z","end":"2026-09-09T01:10:03.709985Z","steps":["trace[1372011354] 'agreement among raft nodes before linearized reading' (duration: 112.818339ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:03.710012Z","caller":"traceutil/trace.go:171","msg":"trace[1751038424] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5469db47-f335-448f-9c4a-95f82e90b1ed; range_end:; response_count:1; response_revision:4940; }","duration":"112.88136ms","start":"2026-09-09T01:10:03.597115Z","end":"2026-09-09T01:10:03.709996Z","steps":["trace[1751038424] 'agreement among raft nodes before linearized reading' (duration: 112.740357ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:03.70955Z","caller":"traceutil/trace.go:171","msg":"trace[204779605] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"111.594099ms","start":"2026-09-09T01:10:03.597948Z","end":"2026-09-09T01:10:03.709542Z","steps":["trace[204779605] 'process raft request' (duration: 110.921623ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:06.488835Z","caller":"traceutil/trace.go:171","msg":"trace[1951909195] linearizableReadLoop","detail":"{readStateIndex:5217; appliedIndex:5216; }","duration":"107.881149ms","start":"2026-09-09T01:10:06.380939Z","end":"2026-09-09T01:10:06.48882Z","steps":["trace[1951909195] 'read index received' (duration: 107.751995ms)","trace[1951909195] 'applied index is now lower than readState.Index' (duration: 128.474µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:10:06.488905Z","caller":"traceutil/trace.go:171","msg":"trace[25643002] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"152.138117ms","start":"2026-09-09T01:10:06.33676Z","end":"2026-09-09T01:10:06.488898Z","steps":["trace[25643002] 'process raft request' (duration: 151.975743ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:10:06.489197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.238498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0ef3a312-b289-458f-9c02-d3278c16a3ed\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-09T01:10:06.489242Z","caller":"traceutil/trace.go:171","msg":"trace[1561547230] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0ef3a312-b289-458f-9c02-d3278c16a3ed; range_end:; response_count:1; response_revision:5025; }","duration":"108.318409ms","start":"2026-09-09T01:10:06.380913Z","end":"2026-09-09T01:10:06.489231Z","steps":["trace[1561547230] 'agreement among raft nodes before linearized reading' (duration: 108.112424ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:10:49.488192Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2883} {"level":"info","ts":"2026-09-09T01:10:49.57419Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2883,"took":"84.05273ms","hash":3210676476,"current-db-size-bytes":52776960,"current-db-size":"53 MB","current-db-size-in-use-bytes":28356608,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T01:10:49.574254Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3210676476,"revision":2883,"compact-revision":-1} {"level":"info","ts":"2026-09-09T01:15:49.493884Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5464} {"level":"info","ts":"2026-09-09T01:15:49.595473Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5464,"took":"99.848448ms","hash":946135973,"current-db-size-bytes":52776960,"current-db-size":"53 MB","current-db-size-in-use-bytes":18780160,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-09T01:15:49.595565Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":946135973,"revision":5464,"compact-revision":2883}