Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.02.2023 07:48
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15149820 kB
MemAvailable 30448660 kB
Buffers 0 kB
Cached 16583340 kB
SwapCached 3620 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-02 07:46:53
DTS_END                         2023-02-02 07:46:53.5600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33702
TRN_ID                          222567
ATT_ID                          647
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9178.43 20983 1101412
customer order: creation 175.23 450 21027
customer order: refuse 14.73 410 1767
order to supplier: creation 46.02 1371 5522
order to supplier: removal 3.32 168820 398
invoice (draft): creation 46.79 3070 5615
invoice (draft): removal 50.04 1034 6005
invoice accept: apply 42.08 26591 5049
invoice accept: cancel 45.43 1117 5451
customer reserve: creation 4009.94 249 481193
customer reserve: removal 4440.08 97 532809
realization accept: apply 84.00 266 10080
realization accept: cancel 89.43 159 10732
payment from customer: creation 60.22 162 7226
payment from customer: removal 5.62 110 674
payment to supplier: creation 49.21 191 5905
payment to supplier: removal 3.35 104 402
service: total inventory turnovers 4.25 3382 510
service: total monetary turnovers 7.75 328 930
service: aggregate perf. data 0.95 16661 114
service: refresh index statistics 0.03 195082 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-02-2023 07:49:19 to 02-02-2023 07:49:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5269
TEST_TIME 2 5172
TEST_TIME 3 4856
TEST_TIME 4 4815
TEST_TIME 5 5152
TEST_TIME 6 5238
TEST_TIME 7 5358
TEST_TIME 8 5655
TEST_TIME 9 5877
TEST_TIME 10 6159
TEST_TIME 11 6195
TEST_TIME 12 6352
TEST_TIME 13 6256
TEST_TIME 14 6230
TEST_TIME 15 6821
TEST_TIME 16 6955
TEST_TIME 17 6948
TEST_TIME 18 7004
TEST_TIME 19 7124
TEST_TIME 20 7301
TEST_TIME 21 7391
TEST_TIME 22 7491
TEST_TIME 23 7453
TEST_TIME 24 7906
TEST_TIME 25 7884
TEST_TIME 26 7970
TEST_TIME 27 8047
TEST_TIME 28 8248
TEST_TIME 29 8729
TEST_TIME 30 9357
TEST_TIME 31 10346
TEST_TIME 32 10356
TEST_TIME 33 10649
TEST_TIME 34 10784
TEST_TIME 35 10785
TEST_TIME 36 10669
TEST_TIME 37 10573
TEST_TIME 38 10442
TEST_TIME 39 10380
TEST_TIME 40 10455
TEST_TIME 41 10424
TEST_TIME 42 10372
TEST_TIME 43 10380
TEST_TIME 44 10235
TEST_TIME 45 10020
TEST_TIME 46 9880
TEST_TIME 47 9954
TEST_TIME 48 9788
TEST_TIME 49 9721
TEST_TIME 50 9706
TEST_TIME 51 9675
TEST_TIME 52 9747
TEST_TIME 53 9754
TEST_TIME 54 9667
TEST_TIME 55 9587
TEST_TIME 56 9563
TEST_TIME 57 9482
TEST_TIME 58 9477
TEST_TIME 59 9445
TEST_TIME 60 9476
TEST_TIME 61 9504
TEST_TIME 62 9556
TEST_TIME 63 9503
TEST_TIME 64 9520
TEST_TIME 65 9527
TEST_TIME 66 9531
TEST_TIME 67 9532
TEST_TIME 68 9600
TEST_TIME 69 9523
TEST_TIME 70 9602
TEST_TIME 71 9684
TEST_TIME 72 9640
TEST_TIME 73 9694
TEST_TIME 74 9723
TEST_TIME 75 9661
TEST_TIME 76 9642
TEST_TIME 77 9550
TEST_TIME 78 9484
TEST_TIME 79 9467
TEST_TIME 80 9419
TEST_TIME 81 9404
TEST_TIME 82 9337
TEST_TIME 83 9305
TEST_TIME 84 9276
TEST_TIME 85 9278
TEST_TIME 86 9252
TEST_TIME 87 9267
TEST_TIME 88 9255
TEST_TIME 89 9301
TEST_TIME 90 9348
TEST_TIME 91 9326
TEST_TIME 92 9346
TEST_TIME 93 9345
TEST_TIME 94 9371
TEST_TIME 95 9438
TEST_TIME 96 9442
TEST_TIME 97 9448
TEST_TIME 98 9382
TEST_TIME 99 9355
TEST_TIME 100 9380
TEST_TIME 101 9344
TEST_TIME 102 9312
TEST_TIME 103 9274
TEST_TIME 104 9228
TEST_TIME 105 9162
TEST_TIME 106 9108
TEST_TIME 107 9160
TEST_TIME 108 9137
TEST_TIME 109 9172
TEST_TIME 110 9148
TEST_TIME 111 9180
TEST_TIME 112 9162
TEST_TIME 113 9153
TEST_TIME 114 9156
TEST_TIME 115 9247
TEST_TIME 116 9229
TEST_TIME 117 9248
TEST_TIME 118 9203
TEST_TIME 119 9193
TEST_TIME 120 9196

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-02-2023 07:49:23 to 02-02-2023 07:49:27.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1542170 1542170 0 0.00 0 20356 14 0 0
doc_list_biud 1542170 1542170 0 0.00 0 84004 39 0 0
sp_add_invoice_to_stock 5136 5049 87 1.69 73 243129 26592 0 87
sp_cancel_adding_invoice 5451 5451 0 0.00 7 109815 1117 0 0
sp_cancel_client_order 1767 1767 0 0.00 6 11264 410 0 0
sp_cancel_customer_reserve 532809 532809 0 0.00 1 56935 97 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 4 6636 111 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 4 4086 105 0 0
sp_cancel_supplier_invoice 6009 6005 4 0.06 3 84019 1034 0 4
sp_cancel_supplier_order 398 398 0 0.00 12 1515926 168820 0 0
sp_cancel_write_off 10732 10732 0 0.00 1 8120 160 0 0
sp_client_order 21027 21027 0 0.00 5 38690 450 0 0
sp_customer_reserve 481193 481193 0 0.00 0 51595 249 0 0
sp_fill_shopping_cart 32157 32157 0 0.00 1 23443 77 0 0
sp_fill_shopping_cart_clo_res 481153 481153 0 0.00 0 18661 17 0 0
sp_get_clo_for_invoice 5008 5008 0 0.00 0 16706 250 0 0
sp_kill_cost_storno 17259 17259 0 0.00 0 20345 72 0 0
sp_kill_qstorno_ret_qs2qd 1082753 1082753 0 0.00 0 84002 43 0 0
sp_kill_qty_storno 572248 572248 0 0.00 0 84003 97 0 0
sp_lock_selected_doc 20776 20776 0 0.00 0 5723 8 0 0
sp_make_cost_storno 28217 28217 0 0.00 0 8856 73 0 0
sp_multiply_rows_for_pdistr 28217 28217 0 0.00 0 11017 38 0 0
sp_multiply_rows_for_qdistr 21027 21027 0 0.00 0 11243 171 0 0
sp_pay_from_customer 7226 7226 0 0.00 5 12950 162 0 0
sp_pay_to_supplier 5905 5905 0 0.00 6 13163 191 0 0
sp_payment_common 13131 13131 0 0.00 2 9495 41 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 1 11244 333 0 0
sp_qd_handle_on_invoice_upd_sts 10457 10457 0 0.00 0 18422 488 0 0
sp_qd_handle_on_reserve_upd_sts 10080 10080 0 0.00 0 10694 59 0 0
sp_reserve_write_off 10080 10080 0 0.00 8 12656 267 0 0
sp_supplier_invoice 5615 5615 0 0.00 9 98009 3070 0 0
sp_supplier_order 5522 5522 0 0.00 8 90559 1371 0 0
srv_aggregate_perf_data 114 114 0 0.00 2722 152576 16661 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 906 167104 32094 0 0
srv_find_qd_qs_mism 1050129 1050129 0 0.00 0 18119 12 0 0
srv_make_invnt_saldo 510 510 0 0.00 61 46509 3382 0 0
srv_make_money_saldo 930 930 0 0.00 1 11696 328 0 0
srv_recalc_idx_stat 3 3 0 0.00 160634 231137 195082 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5049 5049 0 0.00 1 10693 831 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 1 6217 344 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 4076 25 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 714 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 9038 28 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 4634 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 358 358 0 0.00 0 11057 65 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 88922 7318 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 3625 26 0 0
t$perf-norm:sp_client_order 21027 21027 0 0.00 0 15115 37 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 4080 15 0 0
t$perf-norm:sp_pay_from_customer 7226 7226 0 0.00 0 8580 19 0 0
t$perf-norm:sp_pay_to_supplier 5905 5905 0 0.00 0 10912 21 0 0
t$perf-norm:sp_reserve_write_off 10080 10080 0 0.00 0 20727 24 0 0
t$perf-norm:sp_supplier_invoice 5615 5615 0 0.00 0 12513 32 0 0
t$perf-norm:sp_supplier_order 5522 5522 0 0.00 0 3854 29 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 920 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 765 19 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 5666 31 0 0
t$perf-norm:srv_make_money_saldo 930 930 0 0.00 0 1429 5 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 6 30 15 0 0
v_add_invoice_to_stock 10186 10012 174 1.70 0 12394 28 0 174
v_all_customers 38720 38720 0 0.00 0 159 3 0 0
v_all_suppliers 31098 31098 0 0.00 0 168 4 0 0
v_all_wares 42054 42054 0 0.00 0 165 4 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 6852 26 0 0
v_cancel_client_order 3534 3534 0 0.00 0 10638 15 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 4250 14 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 5177 20 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 4046 20 0 0
v_cancel_supplier_invoice 724 716 8 1.10 0 6329 34 0 8
v_cancel_supplier_order 796 796 0 0.00 0 3948 17 0 0
v_cancel_write_off 2056 2056 0 0.00 0 2626 23 0 0
v_max_id_clo_ord 5522 5522 0 0.00 0 386 5 0 0
v_max_id_clo_res 979 979 0 0.00 0 2074 17 0 0
v_max_id_ord_sup 5607 5607 0 0.00 0 6341 9 0 0
v_max_non_paid_invoice 1485 1485 0 0.00 0 367 8 0 0
v_max_non_paid_realizn 3516 3516 0 0.00 0 759 7 0 0
v_min_id_clo_ord 5522 5522 0 0.00 0 2277 8 0 0
v_min_id_clo_res 979 979 0 0.00 0 2820 14 0 0
v_min_id_ord_sup 5607 5607 0 0.00 0 6680 7 0 0
v_min_non_paid_invoice 5905 5905 0 0.00 0 4566 15 0 0
v_min_non_paid_realizn 7226 7226 0 0.00 0 4430 14 0 0
v_our_firm 10754 10754 0 0.00 0 61 4 0 0
v_reserve_write_off 20160 20160 0 0.00 0 12010 13 0 0
x_lock_depdocs_on_canc_invoice 11098 11098 0 0.00 0 14566 123 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 20701 468 0 0
x_make_qstorno_1000_1200 5522 5522 0 0.00 3 90538 1222 0 0
x_make_qstorno_1200_2000 5615 5615 0 0.00 4 97965 2850 0 0
x_make_qstorno_2100_3300 481193 481193 0 0.00 0 51593 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-02-2023 07:49:28 to 02-02-2023 07:49:32.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:16:57 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:17:58 25497 8192000000 9705207872 10001145856 781287664 8.706 100 91 180 1 812332304 8079168 22965472
05:19:32 18619 8192000000 9757986032 10065567744 829934784 9.199 100 86 172 0 863479824 12735120 20809920
05:20:55 12340 8192000000 9794732640 10109923328 858052448 9.481 100 89 177 0 900583408 21919536 20611424
05:22:11 7983 8192000000 9857711968 10185609216 901598240 9.914 100 87 174 0 971955728 51437168 18920320
05:23:24 1661 8192000000 9916099936 10254430208 947086768 10.363 100 91 177 1 1031307216 64518864 19701584
05:24:28 2740 8192000000 9964053728 10314993664 986568384 10.748 100 93 180 1 1080932912 76730304 17634224
05:25:32 1273 8192000000 10001819968 10365337600 1012499376 11.000 100 92 178 2 1120698528 90655376 17543776
05:26:35 1018 8192000000 10001897744 10369511424 1040344944 11.268 100 96 186 1 1122882736 63792704 18745088
05:27:37 1748 8192000000 10042155440 10420776960 1055989904 11.418 100 95 186 1 1164005248 89685040 18330304
05:28:40 996 8192000000 10047911040 10427703296 1068090480 11.534 100 93 181 0 1168176944 81697936 18388528
05:29:42 1169 8192000000 10029036448 10412396544 1074455552 11.595 100 87 169 0 1154813296 62630448 17727296
05:30:45 1320 8192000000 10049870480 10440544256 1077058992 11.619 100 73 139 3 1172874064 80815024 15000048
05:31:47 1097 8192000000 10030186256 10412503040 1081428432 11.661 100 97 190 1 1151154368 50662768 19063168
05:32:50 1362 8192000000 10043597328 10434039808 1091545856 11.757 100 83 162 0 1167396976 59215280 16635840
05:33:53 1167 8192000000 10044661200 10430910464 1091558240 11.757 100 84 166 3 1162232880 54488592 16186048
05:34:55 980 8192000000 10046043376 10432946176 1095927056 11.799 100 95 169 2 1165880768 51661616 18292096
05:35:58 1396 8192000000 10061939968 10452533248 1098092000 11.820 100 87 168 0 1187902704 72719104 17091600
05:36:59 1839 8192000000 10050292000 10444505088 1091865008 11.760 100 79 143 1 1178956720 72847488 14244224
05:38:02 1179 8192000000 10043959232 10431037440 1093614592 11.777 100 94 177 1 1157558080 46689296 17254192
05:39:04 1085 8192000000 10001929328 10377924608 1046483904 11.327 98 95 178 2 1130304640 64796336 19024400
05:40:08 872 8192000000 10054909232 10444087296 1081851056 11.665 100 86 170 1 1178395392 79315504 17228832
05:41:10 1805 8192000000 10022686880 10401558528 1069560864 11.548 100 88 170 2 1154154240 67422512 17170864
05:42:12 1324 8192000000 10001254272 10376351744 1048144960 11.343 100 90 164 5 1121904768 56702720 17057088
05:43:15 1060 8192000000 10008104752 10382503936 1054491920 11.404 100 90 172 0 1128779136 56773968 17513248
05:44:17 1369 8192000000 10015452608 10392223744 1044503824 11.308 100 98 179 1 1140282256 76897808 18880624
05:45:19 1346 8192000000 10000130432 10375589888 1037207648 11.238 99 91 173 2 1122456208 66150528 19098032
05:46:21 2767 8192000000 9996654368 10372890624 1049493168 11.356 100 84 149 3 1112243872 48485088 14265616
05:47:28 1174 8192000000 10029462400 10408181760 1067879008 11.532 100 88 164 2 1150340096 66017120 16443968
05:48:37 15327 8192000000 10047433936 10430894080 1088095152 11.725 100 70 128 3 1165182240 65618704 11468384
05:50:00 7029 8192000000 10080280096 10463072256 1109255696 11.925 100 88 167 4 1195343248 69580608 16506944
05:51:17 6979 8192000000 10123821728 10523037696 1116024464 11.989 100 68 136 0 1253526384 122911344 14590576
05:52:37 16560 8192000000 10144980784 10548633600 1124181168 12.066 100 79 155 0 1256578080 115938272 16458640
05:53:59 1032 8192000000 10197272160 10589962240 1135221536 12.171 100 83 151 1 1310057008 158988976 15846496
05:55:04 1239 8192000000 10226698832 10620993536 1148222208 12.293 100 88 171 0 1340637888 173073952 19341728
05:56:08 1389 8192000000 10274416240 10674601984 1166817168 12.467 100 93 173 0 1392136352 205753440 19565744
05:57:12 1148 8192000000 10287850944 10691780608 1169969968 12.497 100 93 184 1 1401825536 210759200 21096368
05:58:18 1833 8192000000 10317400336 10730000384 1178443712 12.576 100 89 172 3 1430200208 232280592 19475904
05:59:24 1956 8192000000 10373429472 10779635712 1189401264 12.678 100 85 165 0 1485226928 276584512 19241152
06:00:30 1315 8192000000 10396936656 10825048064 1204219392 12.815 100 98 191 0 1504685424 277952896 22513136
06:01:35 1239 8192000000 10414490128 10856890368 1210529632 12.874 100 95 184 1 1525161568 292170544 22461392
06:02:40 1412 8192000000 10455363648 10861109248 1216348816 12.928 100 95 181 4 1556478496 320282032 19847648
06:03:46 1085 8192000000 10509900272 10928390144 1214939920 12.915 100 93 181 0 1616239568 379930512 21369136
06:04:50 1728 8192000000 10548846656 10969878528 1216742304 12.932 100 85 168 1 1654505328 417359952 20403072
06:05:57 1193 8192000000 10562001680 10985914368 1220996144 12.971 100 98 191 2 1670535712 426444448 23095120
06:07:00 1128 8192000000 10593244800 11009740800 1217686240 12.940 100 98 195 0 1698334720 457458016 23190464
06:08:06 903 8192000000 10581490352 11020738560 1212696096 12.894 100 73 144 0 1688316096 458053632 17566368
06:09:23 1037 8192000000 10596436736 11038334976 1216665008 12.931 100 90 174 1 1702835248 465368512 20801728
06:10:27 8407 8192000000 10596011680 11027591168 1212548736 12.893 100 56 112 0 1706680208 479889024 14242448
06:11:36 5167 8192000000 10583004272 11014156288 1188729008 12.672 100 51 98 0 1684745696 483192992 12823696
06:12:47 9863 8192000000 10628120688 11066130432 1192308480 12.705 100 57 112 1 1731420512 524378032 14734000
06:14:19 7091 8192000000 10611138400 11069452288 1162648928 12.428 98 51 102 0 1700217840 524239744 13329168
06:15:27 6836 8192000000 10526787328 11047428096 1176452304 12.557 100 41 82 0 1676386432 489163968 10770160
06:16:53 23144 8192000000 10264340848 10861588480 1167610896 12.474 99 36 70 0 1473465056 297283616 8570544
06:18:22 30338 8192000000 10184990784 10636812288 1169301456 12.490 100 26 50 0 1299086512 124639872 5145184
06:20:17 71754 8192000000 10133618160 10621227008 1146435424 12.276 100 15 28 0 1219640512 70539120 2665968
06:22:35 29630 8192000000 10100083472 10537512960 1144632928 12.259 99 57 104 1 1197449264 41664720 11151616
06:24:05 41422 8192000000 10109323472 10559811584 1133323712 12.153 98 16 32 0 1199901792 63493472 3084608
06:26:04 128209 8192000000 10075473072 10521812992 1082461520 11.671 95 14 28 0 1114264784 28944288 2858976
06:29:14 167103 8192000000 10103305872 10533900288 1060778240 11.464 92 28 52 1 1115477840 48640416 6059184
06:33:01 94838 8192000000 10059012624 10530041856 1101933840 11.856 99 10 18 0 1142330528 38545200 1851488
06:35:37 9867 8192000000 10090149632 10511216640 1111295456 11.945 99 48 94 1 1165497440 44335904 9866080
06:36:51 51628 8192000000 10115828416 10553106432 1122052976 12.046 99 39 78 0 1210852640 80096176 8703488
06:38:44 47757 8192000000 10078507488 10571603968 1111808480 11.950 99 19 37 1 1151340768 35024624 4507664
06:40:37 51460 8192000000 10090463792 10544422912 1119312656 12.020 99 16 32 0 1187136672 64343728 3480288
06:42:30 19819 8192000000 10113470064 10570514432 1132199984 12.142 100 14 26 0 1211231136 76434256 2596896
06:44:17 7867 8192000000 10095547520 10562531328 1127111424 12.094 100 23 42 0 1190835472 58712256 5011792
06:45:25 56826 8192000000 10100697184 10636410880 1133366144 12.153 100 8 16 0 1231375728 96221504 1788080
06:47:24 33648 8192000000 10097486000 10564149248 1119188224 12.019 98 28 55 1 1190910752 65069056 6653472
06:49:26 11069 8192000000 10067639920 10485080064 1124922128 12.073 99 70 132 0 1162143936 23437568 13784240
06:50:37 19532 8192000000 10091366544 10526019584 1126423760 12.088 99 34 68 0 1171494144 37987536 7082848
06:51:57 17678 8192000000 10124605808 10557698048 1137761776 12.194 100 54 104 0 1232825104 84537360 10525968
06:53:15 30049 8192000000 10118911968 10557886464 1120547232 12.032 98 28 56 0 1205109536 78738624 5823680
06:54:46 39456 8192000000 10125545584 10560745472 1142119856 12.235 100 34 67 0 1215796272 67158400 6518016
06:56:30 45012 8192000000 10136483744 10587435008 1124839440 12.073 98 30 57 1 1202663120 71827120 5996560
06:58:16 41088 8192000000 10148744080 10592772096 1127052288 12.094 98 46 92 1 1207137056 70028432 10056336
07:00:00 137735 8192000000 10081062784 10538823680 1113936368 11.970 98 30 60 0 1176905344 56559168 6409808
07:03:18 42082 8192000000 10084287168 10543976448 1141625280 12.231 100 22 44 0 1182233472 36212832 4395360
07:05:03 58292 8192000000 10088875264 10565132288 1136766928 12.185 99 10 20 0 1182048496 43179456 2102112
07:07:01 72682 8192000000 10086123872 10554093568 1141121248 12.226 100 19 38 0 1189659280 44236768 4301264
07:09:39 43187 8192000000 10089216048 10556248064 1141634816 12.231 100 30 60 0 1207865360 59663744 6566800
07:11:30 41802 8192000000 10098094400 10545881088 1132106064 12.141 99 27 54 0 1187323008 49333216 5883728
07:13:16 38248 8192000000 10115435136 10573438976 1110123232 11.934 97 23 46 0 1196408528 80895152 5390144
07:15:01 41363 8192000000 10114400960 10605305856 1115144512 11.981 98 41 82 0 1231886912 108419040 8323360
07:16:44 30167 8192000000 10092116816 10521825280 1113769424 11.968 98 33 65 0 1179378864 59434320 6175120
07:18:16 20712 8192000000 10119421680 10551418880 1134674800 12.165 100 51 98 1 1204402208 59894496 9832912
07:19:39 21443 8192000000 10126999312 10559758336 1116780480 11.997 98 38 72 2 1225876144 100794000 8301664
07:21:02 38855 8192000000 10094447744 10536407040 1093031120 11.771 97 28 53 0 1150757120 52631728 5094272
07:22:45 18608 8192000000 10088938128 10533830656 1115316720 11.983 99 31 62 0 1193375456 71832464 6226272
07:24:05 148981 8192000000 10090484656 10585825280 1133308464 12.153 100 21 42 0 1240112256 102217648 4586144
07:27:34 13720 8192000000 10056392848 10506186752 1123525840 12.060 100 27 45 1 1154257568 26503968 4227760
07:29:34 43457 8192000000 10072324448 10538946560 1113001120 11.961 98 13 24 0 1157484896 42059040 2424736
07:31:17 55853 8192000000 10144745776 10602315776 1136447376 12.182 99 16 32 0 1217451552 77559712 3444464
07:33:40 68463 8192000000 10136334032 10585915392 1134674176 12.165 99 15 30 1 1220933104 82924960 3333968
07:35:49 24349 8192000000 10097745520 10544807936 1127680720 12.099 99 33 66 0 1191951712 56911712 7359280
07:37:14 36554 8192000000 10123242704 10592624640 1127913632 12.102 99 31 61 0 1228797904 94523472 6360800
07:38:52 81764 8192000000 10162371712 10622316544 1135184640 12.170 99 33 66 0 1273345968 131364128 6797200
07:41:15 26198 8192000000 10125700976 10564775936 1113047296 11.961 98 35 70 0 1210532848 90498736 6986816
07:42:43 3174 8192000000 10101239712 10524418048 1130002032 12.121 100 59 113 0 1190907808 49831104 11074672
07:43:46 11990 8192000000 10104511664 10530009088 1132073760 12.141 100 50 100 0 1202047088 60118368 9854960
07:44:59 48333 8192000000 10152948480 10616586240 1135343904 12.172 100 50 100 1 1253057264 107070944 10642416
07:46:48 33322 8192000000 9867074832 10332581888 947133648 10.363 84 2 4 0 982856912 35652144 71120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-02-2023 07:49:34 to 02-02-2023 07:49:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 02-02-2023 07:49:51 to 02-02-2023 07:49:54.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          221963
MON$OLDEST_ACTIVE               222712
MON$OLDEST_SNAPSHOT             222712
MON$NEXT_TRANSACTION            222712
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2053856
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2895 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2895 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2895 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2895 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Feb  2 07:47:45 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Feb  2 07:47:45 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

02.02.23 07:49:55. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-02 07:49:57 No severe PSQL-related problems occured
02.02.23 07:49:59. End of report.