{"level":"warn","ts":"2026-10-04T05:36:14.841807Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T05:36:14.84199Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.73:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.73:2380","--initial-cluster=instance=https://162.253.55.73:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.73:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.73:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-04T05:36:14.842128Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T05:36:14.842172Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.73:2380"]} {"level":"info","ts":"2026-10-04T05:36:14.842213Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-04T05:36:14.843072Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.73:2379"]} {"level":"info","ts":"2026-10-04T05:36:14.843246Z","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.73:2380"],"listen-peer-urls":["https://162.253.55.73:2380"],"advertise-client-urls":["https://162.253.55.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.73:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-04T05:36:14.883925Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"39.876695ms"} {"level":"info","ts":"2026-10-04T05:36:14.916192Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4c43c9f84c6588d4","cluster-id":"49c90045dbd991f3"} {"level":"info","ts":"2026-10-04T05:36:14.916297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T05:36:14.916335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became follower at term 0"} {"level":"info","ts":"2026-10-04T05:36:14.91635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4c43c9f84c6588d4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T05:36:14.916364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became follower at term 1"} {"level":"info","ts":"2026-10-04T05:36:14.916416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 switched to configuration voters=(5495458038592932052)"} {"level":"warn","ts":"2026-10-04T05:36:14.923395Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T05:36:14.924899Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T05:36:14.925998Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-04T05:36:14.929802Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4c43c9f84c6588d4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T05:36:14.94145Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T05:36:14.941542Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T05:36:14.94175Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4c43c9f84c6588d4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T05:36:14.941555Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T05:36:14.942658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 switched to configuration voters=(5495458038592932052)"} {"level":"info","ts":"2026-10-04T05:36:14.942933Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49c90045dbd991f3","local-member-id":"4c43c9f84c6588d4","added-peer-id":"4c43c9f84c6588d4","added-peer-peer-urls":["https://162.253.55.73:2380"]} {"level":"info","ts":"2026-10-04T05:36:14.943622Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-04T05:36:14.943766Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.73:2380"} {"level":"info","ts":"2026-10-04T05:36:14.943824Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.73:2380"} {"level":"info","ts":"2026-10-04T05:36:14.944025Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4c43c9f84c6588d4","initial-advertise-peer-urls":["https://162.253.55.73:2380"],"listen-peer-urls":["https://162.253.55.73:2380"],"advertise-client-urls":["https://162.253.55.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T05:36:14.944099Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T05:36:15.217484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T05:36:15.217627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T05:36:15.217735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 received MsgPreVoteResp from 4c43c9f84c6588d4 at term 1"} {"level":"info","ts":"2026-10-04T05:36:15.217809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became candidate at term 2"} {"level":"info","ts":"2026-10-04T05:36:15.21783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 received MsgVoteResp from 4c43c9f84c6588d4 at term 2"} {"level":"info","ts":"2026-10-04T05:36:15.217881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became leader at term 2"} {"level":"info","ts":"2026-10-04T05:36:15.218004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4c43c9f84c6588d4 elected leader 4c43c9f84c6588d4 at term 2"} {"level":"info","ts":"2026-10-04T05:36:15.219098Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:36:15.220491Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49c90045dbd991f3","local-member-id":"4c43c9f84c6588d4","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:36:15.220578Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:36:15.220608Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:36:15.220653Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4c43c9f84c6588d4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.73:2379]}","request-path":"/0/members/4c43c9f84c6588d4/attributes","cluster-id":"49c90045dbd991f3","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T05:36:15.220817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T05:36:15.22089Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T05:36:15.224034Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.73:2379"} {"level":"info","ts":"2026-10-04T05:36:15.224948Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T05:36:15.225006Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T05:36:15.22576Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-04T05:36:18.523915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.883651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:36:18.524139Z","caller":"traceutil/trace.go:171","msg":"trace[146455385] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"334.406713ms","start":"2026-10-04T05:36:18.189703Z","end":"2026-10-04T05:36:18.52411Z","steps":["trace[146455385] 'process raft request' (duration: 14.709026ms)","trace[146455385] 'compare' (duration: 317.247626ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:36:18.524431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:36:18.189671Z","time spent":"334.7052ms","remote":"127.0.0.1:41442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":678,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T05:36:18.708109Z","caller":"traceutil/trace.go:171","msg":"trace[2089543261] linearizableReadLoop","detail":"{readStateIndex:180; appliedIndex:179; }","duration":"180.325498ms","start":"2026-10-04T05:36:18.527728Z","end":"2026-10-04T05:36:18.708054Z","steps":["trace[2089543261] 'read index received' (duration: 180.086272ms)","trace[2089543261] 'applied index is now lower than readState.Index' (duration: 237.576µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:36:18.708142Z","caller":"traceutil/trace.go:171","msg":"trace[1796999955] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"189.839166ms","start":"2026-10-04T05:36:18.518259Z","end":"2026-10-04T05:36:18.708098Z","steps":["trace[1796999955] 'process raft request' (duration: 189.62215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:36:18.708447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.668467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:36:18.708547Z","caller":"traceutil/trace.go:171","msg":"trace[1505451111] range","detail":"{range_begin:/registry/clusterroles/system:controller:ttl-after-finished-controller; range_end:; response_count:0; response_revision:175; }","duration":"180.856942ms","start":"2026-10-04T05:36:18.52766Z","end":"2026-10-04T05:36:18.708517Z","steps":["trace[1505451111] 'agreement among raft nodes before linearized reading' (duration: 180.563104ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:36:18.859424Z","caller":"traceutil/trace.go:171","msg":"trace[1860801153] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"146.477107ms","start":"2026-10-04T05:36:18.712906Z","end":"2026-10-04T05:36:18.859383Z","steps":["trace[1860801153] 'process raft request' (duration: 99.725463ms)","trace[1860801153] 'compare' (duration: 46.524068ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:36:52.841975Z","caller":"traceutil/trace.go:171","msg":"trace[308451692] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"134.463398ms","start":"2026-10-04T05:36:52.707471Z","end":"2026-10-04T05:36:52.841934Z","steps":["trace[308451692] 'process raft request' (duration: 93.769477ms)","trace[308451692] 'compare' (duration: 40.422156ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:36:53.056283Z","caller":"traceutil/trace.go:171","msg":"trace[707554042] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"186.128202ms","start":"2026-10-04T05:36:52.870127Z","end":"2026-10-04T05:36:53.056255Z","steps":["trace[707554042] 'process raft request' (duration: 182.556931ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:36:53.057388Z","caller":"traceutil/trace.go:171","msg":"trace[2070497327] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"181.724224ms","start":"2026-10-04T05:36:52.875633Z","end":"2026-10-04T05:36:53.057357Z","steps":["trace[2070497327] 'process raft request' (duration: 181.486959ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:36:53.057623Z","caller":"traceutil/trace.go:171","msg":"trace[1998985289] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"180.557211ms","start":"2026-10-04T05:36:52.87704Z","end":"2026-10-04T05:36:53.057597Z","steps":["trace[1998985289] 'process raft request' (duration: 180.253714ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:36:53.057762Z","caller":"traceutil/trace.go:171","msg":"trace[1868858861] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"177.684014ms","start":"2026-10-04T05:36:52.88006Z","end":"2026-10-04T05:36:53.057744Z","steps":["trace[1868858861] 'process raft request' (duration: 177.429079ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:36:55.391899Z","caller":"traceutil/trace.go:171","msg":"trace[498553140] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"122.888239ms","start":"2026-10-04T05:36:55.268949Z","end":"2026-10-04T05:36:55.391837Z","steps":["trace[498553140] 'process raft request' (duration: 116.365693ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:36:55.392138Z","caller":"traceutil/trace.go:171","msg":"trace[1862259741] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"115.662959ms","start":"2026-10-04T05:36:55.276419Z","end":"2026-10-04T05:36:55.392082Z","steps":["trace[1862259741] 'process raft request' (duration: 115.364284ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:36:55.392277Z","caller":"traceutil/trace.go:171","msg":"trace[1013148451] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"117.422014ms","start":"2026-10-04T05:36:55.274842Z","end":"2026-10-04T05:36:55.392264Z","steps":["trace[1013148451] 'process raft request' (duration: 116.812252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:36:56.373165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.695946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-j8jw5\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-10-04T05:36:56.373282Z","caller":"traceutil/trace.go:171","msg":"trace[1723948362] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"173.48836ms","start":"2026-10-04T05:36:56.199762Z","end":"2026-10-04T05:36:56.37325Z","steps":["trace[1723948362] 'process raft request' (duration: 162.57233ms)","trace[1723948362] 'compare' (duration: 10.529273ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:36:56.373302Z","caller":"traceutil/trace.go:171","msg":"trace[828762620] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-j8jw5; range_end:; response_count:1; response_revision:630; }","duration":"184.933931ms","start":"2026-10-04T05:36:56.188343Z","end":"2026-10-04T05:36:56.373277Z","steps":["trace[828762620] 'range keys from in-memory index tree' (duration: 184.35805ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:36:56.37466Z","caller":"traceutil/trace.go:171","msg":"trace[378429029] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"165.442636ms","start":"2026-10-04T05:36:56.209188Z","end":"2026-10-04T05:36:56.374631Z","steps":["trace[378429029] 'process raft request' (duration: 164.744722ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:36:56.374846Z","caller":"traceutil/trace.go:171","msg":"trace[380302091] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"139.215531ms","start":"2026-10-04T05:36:56.235618Z","end":"2026-10-04T05:36:56.374833Z","steps":["trace[380302091] 'process raft request' (duration: 138.530008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:37:03.698221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.406405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-04T05:37:03.698342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.610797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-04T05:37:03.698447Z","caller":"traceutil/trace.go:171","msg":"trace[1877188589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:703; }","duration":"256.79897ms","start":"2026-10-04T05:37:03.441625Z","end":"2026-10-04T05:37:03.698424Z","steps":["trace[1877188589] 'range keys from in-memory index tree' (duration: 256.405903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:37:03.698504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.432321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-04T05:37:03.698545Z","caller":"traceutil/trace.go:171","msg":"trace[243784371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:703; }","duration":"244.500463ms","start":"2026-10-04T05:37:03.454035Z","end":"2026-10-04T05:37:03.698535Z","steps":["trace[243784371] 'range keys from in-memory index tree' (duration: 244.305759ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:37:03.698382Z","caller":"traceutil/trace.go:171","msg":"trace[231117220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:703; }","duration":"242.611228ms","start":"2026-10-04T05:37:03.455731Z","end":"2026-10-04T05:37:03.698343Z","steps":["trace[231117220] 'range keys from in-memory index tree' (duration: 242.152229ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:37:03.69834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.166606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-04T05:37:03.698733Z","caller":"traceutil/trace.go:171","msg":"trace[703827225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:703; }","duration":"388.673335ms","start":"2026-10-04T05:37:03.310048Z","end":"2026-10-04T05:37:03.698722Z","steps":["trace[703827225] 'range keys from in-memory index tree' (duration: 387.938642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:37:03.698787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:37:03.310016Z","time spent":"388.745407ms","remote":"127.0.0.1:41346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T05:37:19.591418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.529136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-75pxc\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-10-04T05:37:19.591664Z","caller":"traceutil/trace.go:171","msg":"trace[998250057] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-75pxc; range_end:; response_count:1; response_revision:817; }","duration":"273.834721ms","start":"2026-10-04T05:37:19.317806Z","end":"2026-10-04T05:37:19.59164Z","steps":["trace[998250057] 'range keys from in-memory index tree' (duration: 273.2034ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:37:19.591667Z","caller":"traceutil/trace.go:171","msg":"trace[1976535385] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:848; }","duration":"239.450331ms","start":"2026-10-04T05:37:19.352171Z","end":"2026-10-04T05:37:19.591621Z","steps":["trace[1976535385] 'read index received' (duration: 227.410324ms)","trace[1976535385] 'applied index is now lower than readState.Index' (duration: 12.038577ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:37:19.591889Z","caller":"traceutil/trace.go:171","msg":"trace[1070531334] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"271.861907ms","start":"2026-10-04T05:37:19.319991Z","end":"2026-10-04T05:37:19.591853Z","steps":["trace[1070531334] 'process raft request' (duration: 259.650627ms)","trace[1070531334] 'compare' (duration: 11.483878ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:37:19.591913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.750266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.73\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-04T05:37:19.591983Z","caller":"traceutil/trace.go:171","msg":"trace[1139800752] range","detail":"{range_begin:/registry/masterleases/162.253.55.73; range_end:; response_count:1; response_revision:818; }","duration":"239.877228ms","start":"2026-10-04T05:37:19.352092Z","end":"2026-10-04T05:37:19.59197Z","steps":["trace[1139800752] 'agreement among raft nodes before linearized reading' (duration: 239.639194ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:37:23.562018Z","caller":"traceutil/trace.go:171","msg":"trace[2127452653] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"111.065195ms","start":"2026-10-04T05:37:23.450905Z","end":"2026-10-04T05:37:23.56197Z","steps":["trace[2127452653] 'process raft request' (duration: 109.54984ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:38:42.416186Z","caller":"traceutil/trace.go:171","msg":"trace[402041328] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"262.734723ms","start":"2026-10-04T05:38:42.153416Z","end":"2026-10-04T05:38:42.416151Z","steps":["trace[402041328] 'process raft request' (duration: 262.53715ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:38:42.429274Z","caller":"traceutil/trace.go:171","msg":"trace[254962640] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"174.855741ms","start":"2026-10-04T05:38:42.254386Z","end":"2026-10-04T05:38:42.429241Z","steps":["trace[254962640] 'process raft request' (duration: 174.627828ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:38:42.429432Z","caller":"traceutil/trace.go:171","msg":"trace[1931555009] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1976; }","duration":"135.610923ms","start":"2026-10-04T05:38:42.293761Z","end":"2026-10-04T05:38:42.429372Z","steps":["trace[1931555009] 'read index received' (duration: 122.225041ms)","trace[1931555009] 'applied index is now lower than readState.Index' (duration: 13.382522ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:38:42.430111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.890637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:38:42.43019Z","caller":"traceutil/trace.go:171","msg":"trace[465092337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1903; }","duration":"136.447525ms","start":"2026-10-04T05:38:42.293696Z","end":"2026-10-04T05:38:42.430144Z","steps":["trace[465092337] 'agreement among raft nodes before linearized reading' (duration: 135.906238ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:38:42.660336Z","caller":"traceutil/trace.go:171","msg":"trace[819828430] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"224.407199ms","start":"2026-10-04T05:38:42.435901Z","end":"2026-10-04T05:38:42.660308Z","steps":["trace[819828430] 'process raft request' (duration: 126.486459ms)","trace[819828430] 'compare' (duration: 97.640046ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:38:42.660957Z","caller":"traceutil/trace.go:171","msg":"trace[1096252435] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"224.582721ms","start":"2026-10-04T05:38:42.436332Z","end":"2026-10-04T05:38:42.660914Z","steps":["trace[1096252435] 'process raft request' (duration: 224.337417ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:38:42.661337Z","caller":"traceutil/trace.go:171","msg":"trace[1898952816] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"223.733359ms","start":"2026-10-04T05:38:42.437577Z","end":"2026-10-04T05:38:42.66131Z","steps":["trace[1898952816] 'process raft request' (duration: 223.259132ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:38:42.661333Z","caller":"traceutil/trace.go:171","msg":"trace[65564122] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"225.141888ms","start":"2026-10-04T05:38:42.436143Z","end":"2026-10-04T05:38:42.661285Z","steps":["trace[65564122] 'process raft request' (duration: 224.118555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:38:42.939048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.642314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6bf4e1f8-827d-4674-87c7-31d832ff494e\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-04T05:38:42.939158Z","caller":"traceutil/trace.go:171","msg":"trace[1473112878] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6bf4e1f8-827d-4674-87c7-31d832ff494e; range_end:; response_count:1; response_revision:1908; }","duration":"146.824147ms","start":"2026-10-04T05:38:42.792314Z","end":"2026-10-04T05:38:42.939138Z","steps":["trace[1473112878] 'range keys from in-memory index tree' (duration: 146.437333ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:40:23.401276Z","caller":"traceutil/trace.go:171","msg":"trace[77249911] linearizableReadLoop","detail":"{readStateIndex:2704; appliedIndex:2703; }","duration":"186.067939ms","start":"2026-10-04T05:40:23.215184Z","end":"2026-10-04T05:40:23.401252Z","steps":["trace[77249911] 'read index received' (duration: 185.852394ms)","trace[77249911] 'applied index is now lower than readState.Index' (duration: 214.675µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:40:23.401563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.425103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-10-04T05:40:23.401561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.891025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T05:40:23.401588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.375737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-10-04T05:40:23.401656Z","caller":"traceutil/trace.go:171","msg":"trace[909224683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2604; }","duration":"112.034798ms","start":"2026-10-04T05:40:23.2896Z","end":"2026-10-04T05:40:23.401634Z","steps":["trace[909224683] 'agreement among raft nodes before linearized reading' (duration: 111.827663ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:40:23.401667Z","caller":"traceutil/trace.go:171","msg":"trace[525753030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2604; }","duration":"186.512492ms","start":"2026-10-04T05:40:23.215137Z","end":"2026-10-04T05:40:23.401649Z","steps":["trace[525753030] 'agreement among raft nodes before linearized reading' (duration: 186.213754ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:40:23.401678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.367895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-04T05:40:23.40171Z","caller":"traceutil/trace.go:171","msg":"trace[479139794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2604; }","duration":"168.415136ms","start":"2026-10-04T05:40:23.233284Z","end":"2026-10-04T05:40:23.401699Z","steps":["trace[479139794] 'agreement among raft nodes before linearized reading' (duration: 168.336774ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:40:23.401604Z","caller":"traceutil/trace.go:171","msg":"trace[1969688701] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2604; }","duration":"185.504904ms","start":"2026-10-04T05:40:23.216088Z","end":"2026-10-04T05:40:23.401593Z","steps":["trace[1969688701] 'agreement among raft nodes before linearized reading' (duration: 185.319589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:40:23.401714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.566326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-04T05:40:23.401796Z","caller":"traceutil/trace.go:171","msg":"trace[1410427287] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2604; }","duration":"114.69026ms","start":"2026-10-04T05:40:23.287084Z","end":"2026-10-04T05:40:23.401774Z","steps":["trace[1410427287] 'agreement among raft nodes before linearized reading' (duration: 114.423702ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:40:23.401383Z","caller":"traceutil/trace.go:171","msg":"trace[1447555155] transaction","detail":"{read_only:false; response_revision:2604; number_of_response:1; }","duration":"204.620505ms","start":"2026-10-04T05:40:23.19672Z","end":"2026-10-04T05:40:23.40134Z","steps":["trace[1447555155] 'process raft request' (duration: 204.380749ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:41:01.105392Z","caller":"traceutil/trace.go:171","msg":"trace[1204725177] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"125.529047ms","start":"2026-10-04T05:41:00.979832Z","end":"2026-10-04T05:41:01.105361Z","steps":["trace[1204725177] 'process raft request' (duration: 124.881692ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:51.359742Z","caller":"traceutil/trace.go:171","msg":"trace[406819117] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5145; }","duration":"102.686193ms","start":"2026-10-04T05:45:51.257042Z","end":"2026-10-04T05:45:51.359728Z","steps":["trace[406819117] 'read index received' (duration: 102.563551ms)","trace[406819117] 'applied index is now lower than readState.Index' (duration: 122.062µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:45:51.359999Z","caller":"traceutil/trace.go:171","msg":"trace[1001158715] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"163.795361ms","start":"2026-10-04T05:45:51.196196Z","end":"2026-10-04T05:45:51.359991Z","steps":["trace[1001158715] 'process raft request' (duration: 163.447406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:45:51.360169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.124739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-04T05:45:51.360186Z","caller":"traceutil/trace.go:171","msg":"trace[1142177039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4953; }","duration":"103.18816ms","start":"2026-10-04T05:45:51.256993Z","end":"2026-10-04T05:45:51.360181Z","steps":["trace[1142177039] 'agreement among raft nodes before linearized reading' (duration: 103.111069ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:51.483616Z","caller":"traceutil/trace.go:171","msg":"trace[1157499887] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"115.929557ms","start":"2026-10-04T05:45:51.367672Z","end":"2026-10-04T05:45:51.483602Z","steps":["trace[1157499887] 'process raft request' (duration: 115.656594ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:51.491752Z","caller":"traceutil/trace.go:171","msg":"trace[2022613988] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"123.925692ms","start":"2026-10-04T05:45:51.367817Z","end":"2026-10-04T05:45:51.491743Z","steps":["trace[2022613988] 'process raft request' (duration: 123.876041ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:51.491853Z","caller":"traceutil/trace.go:171","msg":"trace[1345851571] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"124.107935ms","start":"2026-10-04T05:45:51.367739Z","end":"2026-10-04T05:45:51.491847Z","steps":["trace[1345851571] 'process raft request' (duration: 123.912542ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:51.49199Z","caller":"traceutil/trace.go:171","msg":"trace[1396811220] linearizableReadLoop","detail":"{readStateIndex:5150; appliedIndex:5147; }","duration":"113.624413ms","start":"2026-10-04T05:45:51.378359Z","end":"2026-10-04T05:45:51.491984Z","steps":["trace[1396811220] 'read index received' (duration: 105.594648ms)","trace[1396811220] 'applied index is now lower than readState.Index' (duration: 8.029445ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:45:51.492061Z","caller":"traceutil/trace.go:171","msg":"trace[1365181434] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"124.187085ms","start":"2026-10-04T05:45:51.367839Z","end":"2026-10-04T05:45:51.492026Z","steps":["trace[1365181434] 'process raft request' (duration: 123.878471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:45:51.49211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.737515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-04T05:45:51.492438Z","caller":"traceutil/trace.go:171","msg":"trace[1976158334] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4957; }","duration":"114.086379ms","start":"2026-10-04T05:45:51.378336Z","end":"2026-10-04T05:45:51.492423Z","steps":["trace[1976158334] 'agreement among raft nodes before linearized reading' (duration: 113.686102ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:53.44834Z","caller":"traceutil/trace.go:171","msg":"trace[1968400159] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"157.324016ms","start":"2026-10-04T05:45:53.290986Z","end":"2026-10-04T05:45:53.44831Z","steps":["trace[1968400159] 'process raft request' (duration: 157.130404ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:53.508795Z","caller":"traceutil/trace.go:171","msg":"trace[1408230443] linearizableReadLoop","detail":"{readStateIndex:5213; appliedIndex:5211; }","duration":"124.675871ms","start":"2026-10-04T05:45:53.384096Z","end":"2026-10-04T05:45:53.508772Z","steps":["trace[1408230443] 'read index received' (duration: 63.88337ms)","trace[1408230443] 'applied index is now lower than readState.Index' (duration: 60.789361ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:45:53.508898Z","caller":"traceutil/trace.go:171","msg":"trace[186952359] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"141.870959ms","start":"2026-10-04T05:45:53.367016Z","end":"2026-10-04T05:45:53.508887Z","steps":["trace[186952359] 'process raft request' (duration: 141.628695ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:45:53.509033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.885094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:45:53.509144Z","caller":"traceutil/trace.go:171","msg":"trace[831870457] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:5017; }","duration":"125.073958ms","start":"2026-10-04T05:45:53.384044Z","end":"2026-10-04T05:45:53.509118Z","steps":["trace[831870457] 'agreement among raft nodes before linearized reading' (duration: 124.852815ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:58.481288Z","caller":"traceutil/trace.go:171","msg":"trace[1079403011] linearizableReadLoop","detail":"{readStateIndex:5316; appliedIndex:5315; }","duration":"260.707461ms","start":"2026-10-04T05:45:58.220555Z","end":"2026-10-04T05:45:58.481262Z","steps":["trace[1079403011] 'read index received' (duration: 260.527868ms)","trace[1079403011] 'applied index is now lower than readState.Index' (duration: 178.383µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:45:58.48136Z","caller":"traceutil/trace.go:171","msg":"trace[1188724039] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"345.166556ms","start":"2026-10-04T05:45:58.13616Z","end":"2026-10-04T05:45:58.481327Z","steps":["trace[1188724039] 'process raft request' (duration: 344.929222ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:45:58.481452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.876353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9e994a5-abb1-4459-b27e-7c52995e4a5f\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-10-04T05:45:58.481517Z","caller":"traceutil/trace.go:171","msg":"trace[419834279] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9e994a5-abb1-4459-b27e-7c52995e4a5f; range_end:; response_count:1; response_revision:5118; }","duration":"261.005186ms","start":"2026-10-04T05:45:58.220499Z","end":"2026-10-04T05:45:58.481504Z","steps":["trace[419834279] 'agreement among raft nodes before linearized reading' (duration: 260.883993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:45:58.481826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:45:58.136132Z","time spent":"345.299008ms","remote":"127.0.0.1:41260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3747,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:45:58.498823Z","caller":"traceutil/trace.go:171","msg":"trace[1575977499] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"193.354ms","start":"2026-10-04T05:45:58.305439Z","end":"2026-10-04T05:45:58.498793Z","steps":["trace[1575977499] 'process raft request' (duration: 193.141987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:45:58.49902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.611915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:45:58.499071Z","caller":"traceutil/trace.go:171","msg":"trace[1952238822] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"191.687184ms","start":"2026-10-04T05:45:58.307343Z","end":"2026-10-04T05:45:58.49903Z","steps":["trace[1952238822] 'process raft request' (duration: 191.403959ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:58.4991Z","caller":"traceutil/trace.go:171","msg":"trace[1578953480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5120; }","duration":"206.741756ms","start":"2026-10-04T05:45:58.292345Z","end":"2026-10-04T05:45:58.499086Z","steps":["trace[1578953480] 'agreement among raft nodes before linearized reading' (duration: 206.600163ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:46:11.151445Z","caller":"traceutil/trace.go:171","msg":"trace[1768762174] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"146.682895ms","start":"2026-10-04T05:46:11.004524Z","end":"2026-10-04T05:46:11.151207Z","steps":["trace[1768762174] 'process raft request' (duration: 146.461122ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:46:11.544838Z","caller":"traceutil/trace.go:171","msg":"trace[1497176186] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"277.252193ms","start":"2026-10-04T05:46:11.26755Z","end":"2026-10-04T05:46:11.544802Z","steps":["trace[1497176186] 'process raft request' (duration: 276.985829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:46:11.545217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.805629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-04T05:46:11.545285Z","caller":"traceutil/trace.go:171","msg":"trace[168586315] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5281; }","duration":"269.899229ms","start":"2026-10-04T05:46:11.275368Z","end":"2026-10-04T05:46:11.545267Z","steps":["trace[168586315] 'agreement among raft nodes before linearized reading' (duration: 269.743337ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:46:11.544709Z","caller":"traceutil/trace.go:171","msg":"trace[196387517] linearizableReadLoop","detail":"{readStateIndex:5485; appliedIndex:5484; }","duration":"269.298991ms","start":"2026-10-04T05:46:11.275391Z","end":"2026-10-04T05:46:11.54469Z","steps":["trace[196387517] 'read index received' (duration: 269.085558ms)","trace[196387517] 'applied index is now lower than readState.Index' (duration: 212.503µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:46:11.545863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.884954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:46:11.545925Z","caller":"traceutil/trace.go:171","msg":"trace[892713043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5281; }","duration":"253.987534ms","start":"2026-10-04T05:46:11.291927Z","end":"2026-10-04T05:46:11.545914Z","steps":["trace[892713043] 'agreement among raft nodes before linearized reading' (duration: 253.902813ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:46:11.732859Z","caller":"traceutil/trace.go:171","msg":"trace[281071238] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"162.290255ms","start":"2026-10-04T05:46:11.570538Z","end":"2026-10-04T05:46:11.732828Z","steps":["trace[281071238] 'process raft request' (duration: 69.746612ms)","trace[281071238] 'compare' (duration: 92.319559ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:46:11.733247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.639881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:10559"} {"level":"info","ts":"2026-10-04T05:46:11.733359Z","caller":"traceutil/trace.go:171","msg":"trace[1229092841] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5286; }","duration":"179.786503ms","start":"2026-10-04T05:46:11.553547Z","end":"2026-10-04T05:46:11.733333Z","steps":["trace[1229092841] 'agreement among raft nodes before linearized reading' (duration: 179.4857ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:46:11.733354Z","caller":"traceutil/trace.go:171","msg":"trace[1788377401] linearizableReadLoop","detail":"{readStateIndex:5490; appliedIndex:5489; }","duration":"118.393111ms","start":"2026-10-04T05:46:11.614386Z","end":"2026-10-04T05:46:11.732779Z","steps":["trace[1788377401] 'read index received' (duration: 25.870008ms)","trace[1788377401] 'applied index is now lower than readState.Index' (duration: 92.521273ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:46:11.733831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.242858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-04T05:46:11.733878Z","caller":"traceutil/trace.go:171","msg":"trace[1159155527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5286; }","duration":"118.35117ms","start":"2026-10-04T05:46:11.615513Z","end":"2026-10-04T05:46:11.733864Z","steps":["trace[1159155527] 'agreement among raft nodes before linearized reading' (duration: 118.153537ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:46:15.253262Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2886} {"level":"info","ts":"2026-10-04T05:46:15.337635Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2886,"took":"83.113686ms","hash":4264854316,"current-db-size-bytes":51793920,"current-db-size":"52 MB","current-db-size-in-use-bytes":28012544,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-04T05:46:15.337691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4264854316,"revision":2886,"compact-revision":-1} {"level":"info","ts":"2026-10-04T05:51:15.262111Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5330} {"level":"info","ts":"2026-10-04T05:51:15.348225Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5330,"took":"84.092246ms","hash":2629513349,"current-db-size-bytes":51793920,"current-db-size":"52 MB","current-db-size-in-use-bytes":18956288,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-04T05:51:15.348298Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2629513349,"revision":5330,"compact-revision":2886}