Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.03.2023 13:40
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 14953680 kB
MemAvailable 30453972 kB
Buffers 0 kB
Cached 16770856 kB
SwapCached 3224 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-03-14 13:37:29
DTS_END                         2023-03-14 13:37:29.4480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59514
TRN_ID                          219927
ATT_ID                          654
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: 9043.15 28723 1085178
customer order: creation 171.25 446 20550
customer order: refuse 15.60 452 1872
order to supplier: creation 46.08 1380 5529
order to supplier: removal 3.53 167332 423
invoice (draft): creation 45.25 3130 5430
invoice (draft): removal 49.42 1044 5930
invoice accept: apply 42.47 28065 5096
invoice accept: cancel 45.58 1302 5470
customer reserve: creation 3890.46 271 466855
customer reserve: removal 4433.66 103 532039
realization accept: apply 83.11 290 9973
realization accept: cancel 82.37 201 9884
payment from customer: creation 61.51 209 7381
payment from customer: removal 5.59 131 671
payment to supplier: creation 51.22 219 6146
payment to supplier: removal 3.30 133 396
service: total inventory turnovers 3.98 4232 478
service: total monetary turnovers 7.70 441 924
service: aggregate perf. data 1.04 15213 125
service: refresh index statistics 0.05 349865 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-03-2023 13:40:43 to 14-03-2023 13:40:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6617
TEST_TIME 2 4827
TEST_TIME 3 4475
TEST_TIME 4 4353
TEST_TIME 5 4811
TEST_TIME 6 4644
TEST_TIME 7 4919
TEST_TIME 8 5228
TEST_TIME 9 5405
TEST_TIME 10 5354
TEST_TIME 11 5432
TEST_TIME 12 5667
TEST_TIME 13 5823
TEST_TIME 14 6028
TEST_TIME 15 6263
TEST_TIME 16 6204
TEST_TIME 17 6603
TEST_TIME 18 6762
TEST_TIME 19 6830
TEST_TIME 20 6762
TEST_TIME 21 7232
TEST_TIME 22 7313
TEST_TIME 23 7317
TEST_TIME 24 7477
TEST_TIME 25 7611
TEST_TIME 26 7752
TEST_TIME 27 7982
TEST_TIME 28 8263
TEST_TIME 29 8399
TEST_TIME 30 8647
TEST_TIME 31 8650
TEST_TIME 32 9010
TEST_TIME 33 9193
TEST_TIME 34 9361
TEST_TIME 35 10183
TEST_TIME 36 10219
TEST_TIME 37 10567
TEST_TIME 38 10574
TEST_TIME 39 10522
TEST_TIME 40 10408
TEST_TIME 41 10360
TEST_TIME 42 10343
TEST_TIME 43 10260
TEST_TIME 44 10252
TEST_TIME 45 10226
TEST_TIME 46 10083
TEST_TIME 47 9982
TEST_TIME 48 9967
TEST_TIME 49 9928
TEST_TIME 50 9865
TEST_TIME 51 9871
TEST_TIME 52 9820
TEST_TIME 53 9811
TEST_TIME 54 9789
TEST_TIME 55 9773
TEST_TIME 56 9800
TEST_TIME 57 9725
TEST_TIME 58 9657
TEST_TIME 59 9679
TEST_TIME 60 9731
TEST_TIME 61 9707
TEST_TIME 62 9689
TEST_TIME 63 9724
TEST_TIME 64 9732
TEST_TIME 65 9755
TEST_TIME 66 9776
TEST_TIME 67 9829
TEST_TIME 68 9805
TEST_TIME 69 9812
TEST_TIME 70 9753
TEST_TIME 71 9759
TEST_TIME 72 9652
TEST_TIME 73 9607
TEST_TIME 74 9521
TEST_TIME 75 9472
TEST_TIME 76 9462
TEST_TIME 77 9481
TEST_TIME 78 9471
TEST_TIME 79 9421
TEST_TIME 80 9374
TEST_TIME 81 9311
TEST_TIME 82 9286
TEST_TIME 83 9298
TEST_TIME 84 9317
TEST_TIME 85 9304
TEST_TIME 86 9339
TEST_TIME 87 9324
TEST_TIME 88 9312
TEST_TIME 89 9333
TEST_TIME 90 9362
TEST_TIME 91 9380
TEST_TIME 92 9382
TEST_TIME 93 9418
TEST_TIME 94 9441
TEST_TIME 95 9438
TEST_TIME 96 9453
TEST_TIME 97 9460
TEST_TIME 98 9406
TEST_TIME 99 9400
TEST_TIME 100 9359
TEST_TIME 101 9323
TEST_TIME 102 9344
TEST_TIME 103 9292
TEST_TIME 104 9243
TEST_TIME 105 9193
TEST_TIME 106 9144
TEST_TIME 107 9190
TEST_TIME 108 9156
TEST_TIME 109 9162
TEST_TIME 110 9159
TEST_TIME 111 9136
TEST_TIME 112 9138
TEST_TIME 113 9162
TEST_TIME 114 9191
TEST_TIME 115 9204
TEST_TIME 116 9176
TEST_TIME 117 9174
TEST_TIME 118 9189
TEST_TIME 119 9153
TEST_TIME 120 9083

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-03-2023 13:40:47 to 14-03-2023 13:40:50.

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 1516802 1516802 0 0.00 0 25355 15 0 0
doc_list_biud 1516790 1516790 0 0.00 0 27820 42 0 0
sp_add_invoice_to_stock 5252 5096 156 2.97 49 191257 28065 0 156
sp_cancel_adding_invoice 5470 5470 0 0.00 3 138055 1302 0 0
sp_cancel_client_order 1872 1872 0 0.00 6 9508 453 0 0
sp_cancel_customer_reserve 532039 532039 0 0.00 1 25841 103 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 3550 132 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 4 2815 133 0 0
sp_cancel_supplier_invoice 5944 5930 14 0.23 2 17939 1044 0 14
sp_cancel_supplier_order 423 423 0 0.00 8 1422213 167332 0 0
sp_cancel_write_off 9884 9884 0 0.00 2 24752 201 0 0
sp_client_order 20550 20550 0 0.00 5 19985 446 0 0
sp_customer_reserve 466855 466855 0 0.00 0 77169 272 0 0
sp_fill_shopping_cart 31499 31499 0 0.00 1 13988 79 0 0
sp_fill_shopping_cart_clo_res 466811 466811 0 0.00 0 16282 18 0 0
sp_get_clo_for_invoice 5048 5048 0 0.00 0 17843 249 0 0
sp_kill_cost_storno 16421 16421 0 0.00 0 17527 88 0 0
sp_kill_qstorno_ret_qs2qd 1080315 1080315 0 0.00 0 25818 45 0 0
sp_kill_qty_storno 570636 570636 0 0.00 0 27818 104 0 0
sp_lock_selected_doc 20893 20893 0 0.00 0 11354 7 0 0
sp_make_cost_storno 28548 28548 0 0.00 0 25345 78 0 0
sp_multiply_rows_for_pdistr 28544 28544 0 0.00 0 17447 51 0 0
sp_multiply_rows_for_qdistr 20549 20549 0 0.00 0 12194 167 0 0
sp_pay_from_customer 7381 7381 0 0.00 6 17967 210 0 0
sp_pay_to_supplier 6146 6146 0 0.00 6 25366 219 0 0
sp_payment_common 13525 13525 0 0.00 2 17517 68 0 0
sp_qd_handle_on_cancel_clo 1872 1872 0 0.00 0 9490 384 0 0
sp_qd_handle_on_invoice_upd_sts 10517 10517 0 0.00 0 27816 545 0 0
sp_qd_handle_on_reserve_upd_sts 9971 9971 0 0.00 0 21505 61 0 0
sp_reserve_write_off 9973 9973 0 0.00 8 21559 291 0 0
sp_supplier_invoice 5430 5430 0 0.00 9 69448 3131 0 0
sp_supplier_order 5529 5529 0 0.00 6 22730 1380 0 0
srv_aggregate_perf_data 125 125 0 0.00 2486 199541 15214 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 614 146062 32987 0 0
srv_find_qd_qs_mism 1036432 1036432 0 0.00 0 24036 12 0 0
srv_make_invnt_saldo 478 478 0 0.00 43 84792 4233 0 0
srv_make_money_saldo 924 924 0 0.00 1 13308 442 0 0
srv_recalc_idx_stat 6 6 0 0.00 191984 532966 349865 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5104 5104 0 0.00 1 27161 882 0 0
t$perf-norm:sp_cancel_adding_invoice 459 459 0 0.00 1 12823 486 0 0
t$perf-norm:sp_cancel_client_order 1872 1872 0 0.00 0 1560 30 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 776 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 1571 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 579 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 622 39 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 89942 7679 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 937 18 0 0
t$perf-norm:sp_client_order 20550 20550 0 0.00 0 12592 35 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 1054 15 0 0
t$perf-norm:sp_pay_from_customer 7381 7381 0 0.00 0 3842 17 0 0
t$perf-norm:sp_pay_to_supplier 6146 6146 0 0.00 0 2529 17 0 0
t$perf-norm:sp_reserve_write_off 9973 9973 0 0.00 0 11629 24 0 0
t$perf-norm:sp_supplier_invoice 5430 5430 0 0.00 0 10698 33 0 0
t$perf-norm:sp_supplier_order 5529 5529 0 0.00 0 7403 32 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 286 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 162 6 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 6816 28 0 0
t$perf-norm:srv_make_money_saldo 924 924 0 0.00 0 3696 11 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 3 190 38 0 0
v_add_invoice_to_stock 10400 10088 312 3.00 0 8695 30 0 312
v_all_customers 38178 38178 0 0.00 0 129 4 0 0
v_all_suppliers 31174 31174 0 0.00 0 93 4 0 0
v_all_wares 41096 41096 0 0.00 0 153 4 0 0
v_cancel_adding_invoice 918 918 0 0.00 0 1794 17 0 0
v_cancel_client_order 3744 3744 0 0.00 0 2913 11 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 957 13 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 4485 14 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 992 12 0 0
v_cancel_supplier_invoice 782 754 28 3.58 0 987 27 0 28
v_cancel_supplier_order 846 846 0 0.00 0 711 11 0 0
v_cancel_write_off 1966 1966 0 0.00 0 3329 27 0 0
v_max_id_clo_ord 5524 5524 0 0.00 0 187 5 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 2389 19 0 0
v_max_id_ord_sup 5425 5425 0 0.00 0 6095 8 0 0
v_max_non_paid_invoice 1506 1506 0 0.00 0 490 9 0 0
v_max_non_paid_realizn 3698 3698 0 0.00 0 1642 8 0 0
v_min_id_clo_ord 5524 5524 0 0.00 0 2977 9 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 3046 10 0 0
v_min_id_ord_sup 5425 5425 0 0.00 0 5099 10 0 0
v_min_non_paid_invoice 6144 6144 0 0.00 0 17465 40 0 0
v_min_non_paid_realizn 7381 7381 0 0.00 0 16972 37 0 0
v_our_firm 10282 10282 0 0.00 0 60 5 0 0
v_reserve_write_off 19942 19942 0 0.00 0 3127 11 0 0
x_lock_depdocs_on_canc_invoice 11023 11023 0 0.00 0 38942 125 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 10387 401 0 0
x_make_qstorno_1000_1200 5529 5529 0 0.00 2 22723 1214 0 0
x_make_qstorno_1200_2000 5430 5430 0 0.00 4 69429 2917 0 0
x_make_qstorno_2100_3300 466854 466854 0 0.00 0 77167 242 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-03-2023 13:40:52 to 14-03-2023 13:40:56.

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
11:07:30 909 8192000000 8928578416 8985718784 37638672 0.457 8 6 13 0 39179008 308896 1231440
11:08:33 21939 8192000000 9704341648 10000523264 781833616 8.712 100 85 171 0 810944752 7501568 21609568
11:10:02 17539 8192000000 9745508528 10051813376 825200544 9.151 100 85 170 0 856896208 10933600 20762064
11:11:23 7486 8192000000 9786026992 10101084160 858129872 9.481 100 85 164 0 894985840 17291536 19564432
11:12:38 14438 8192000000 9831886256 10159235072 896691424 9.866 100 86 172 0 941733136 25456976 19584736
11:13:58 4041 8192000000 9900281168 10238099456 945077776 10.343 100 83 159 1 1014821984 53462432 16281776
11:15:06 1928 8192000000 9969778992 10317819904 986808992 10.750 100 94 187 0 1083312848 77188336 19315520
11:16:10 2138 8192000000 9974420176 10331291648 1014732304 11.021 100 89 176 0 1101092432 68622416 17737712
11:17:14 1450 8192000000 10028375616 10391326720 1032578464 11.193 100 93 180 2 1140484352 89579792 18326096
11:18:18 1162 8192000000 10027622144 10397720576 1047184448 11.334 100 91 177 1 1141354496 76491968 17678080
11:19:20 1151 8192000000 10030721280 10407800832 1067698432 11.530 100 96 185 0 1144432128 58529440 18204256
11:20:24 1187 8192000000 10040574592 10418958336 1076541584 11.615 100 93 180 1 1161807200 68072352 17193264
11:21:28 1339 8192000000 10050031120 10428186624 1077036624 11.619 100 93 174 3 1170428512 75610736 17781152
11:22:30 1062 8192000000 10041815408 10426175488 1086841312 11.713 100 98 185 2 1167721424 60736912 20143200
11:23:32 981 8192000000 10061958464 10447163392 1087405936 11.718 100 95 187 0 1185120688 78608112 19106640
11:24:34 1242 8192000000 10073577488 10459709440 1091845296 11.760 100 98 182 0 1195294032 84910224 18538512
11:25:36 1424 8192000000 10079353312 10465882112 1096751344 11.807 100 98 180 4 1197001472 81894752 18355376
11:26:38 938 8192000000 10048541744 10438164480 1099637008 11.834 100 84 158 2 1166824720 50082992 17104720
11:27:41 5992 8192000000 10064399552 10467217408 1102686848 11.863 100 51 102 0 1181710752 69087728 9936176
11:28:48 1381 8192000000 10049148240 10444423168 1105152064 11.886 100 83 157 1 1174911488 53058736 16700688
11:29:51 1235 8192000000 10043439024 10425167872 1083432416 11.680 99 96 182 1 1161464880 59328976 18703488
11:30:53 1050 8192000000 10072975248 10469285888 1102123920 11.858 100 84 166 0 1196459264 77879904 16455440
11:31:56 1104 8192000000 10055688784 10437353472 1079855472 11.646 100 97 180 1 1179585472 80847104 18882896
11:32:58 1199 8192000000 10022985248 10406830080 1066879184 11.522 100 85 164 3 1147174912 63575552 16720176
11:34:00 1235 8192000000 10030190608 10410414080 1066974928 11.523 100 92 172 3 1154143280 69024144 18144208
11:35:03 1078 8192000000 9982820080 10362466304 1043095216 11.294 99 87 162 2 1108989728 49536608 16357904
11:36:06 855 8192000000 9991557296 10365501440 1028530192 11.154 100 92 164 1 1116598288 69046320 19021776
11:37:08 1085 8192000000 9987940032 10363965440 1039257104 11.258 100 87 162 1 1111306976 55500592 16549280
11:38:17 22295 8192000000 10007049232 10401456128 1062808272 11.483 100 62 117 0 1137716528 63249568 11658688
11:40:01 4955 8192000000 10051864352 10439766016 1078512048 11.633 100 70 137 0 1167837104 75056992 14268064
11:41:15 2586 8192000000 10085346256 10463121408 1076516368 11.614 99 82 160 0 1201740800 108488736 16735696
11:42:32 8887 8192000000 10108995680 10511507456 1100753552 11.845 100 60 112 1 1234793712 121738912 12301248
11:43:45 9024 8192000000 10148909312 10539880448 1115104160 11.981 100 68 132 2 1269139760 140211232 13824368
11:44:57 1481 8192000000 10183283136 10578661376 1119191760 12.019 100 93 179 1 1290704656 151976656 19536240
11:46:02 5552 8192000000 10216568944 10612350976 1128230288 12.105 100 85 168 1 1332592000 185979440 18382272
11:47:16 1570 8192000000 10219335664 10610544640 1139406448 12.210 100 90 169 1 1333728240 177293104 17028688
11:48:21 2026 8192000000 10261199328 10655698944 1152247792 12.331 100 90 178 1 1377990992 205271552 20471648
11:49:29 1519 8192000000 10303607600 10706731008 1169248128 12.490 100 88 168 1 1412050224 223628448 19173648
11:50:34 1398 8192000000 10324111248 10726760448 1181701888 12.606 100 88 170 0 1438588016 237193232 19692896
11:51:37 1133 8192000000 10366952928 10784366592 1187923776 12.664 100 92 175 0 1476378608 268602880 19851952
11:52:42 768 8192000000 10387006224 10790350848 1189270032 12.677 100 95 184 3 1497156656 286037648 21848976
11:53:46 1400 8192000000 10424739120 10859159552 1199141136 12.768 100 93 183 1 1532854432 312354160 21359136
11:54:52 1057 8192000000 10402274272 10832445440 1187121776 12.657 99 98 183 1 1515327040 307420624 20784640
11:55:56 1017 8192000000 10459560384 10864214016 1196862816 12.747 100 90 174 1 1568195040 351360064 19972160
11:57:01 867 8192000000 10465885488 10892042240 1198319904 12.761 100 88 170 0 1576541088 358687200 19533984
11:58:05 967 8192000000 10490040832 10923991040 1207089184 12.842 100 93 184 0 1596953936 368325424 21539328
11:59:09 7063 8192000000 10485140896 10923573248 1210693392 12.876 100 57 110 1 1608521744 383513312 14315040
12:00:20 5432 8192000000 10533458240 10977673216 1209067776 12.860 100 66 132 0 1629284320 403746800 16469744
12:01:32 605 8192000000 10563143696 10988523520 1203882384 12.812 100 78 153 1 1665947152 443095040 18969728
12:02:37 1056 8192000000 10563796320 10999017472 1194269056 12.723 100 82 164 0 1669653600 455421568 19962976
12:03:42 775 8192000000 10572711712 11034243072 1199518800 12.772 100 93 185 1 1678946768 455689552 23738416
12:04:47 4625 8192000000 10539553280 11037093888 1203563104 12.809 100 68 134 1 1644581312 424410448 16607760
12:05:59 2613 8192000000 10525299264 10971172864 1192791136 12.709 100 68 130 0 1628657584 419916640 15949808
12:07:09 13681 8192000000 10529580400 11010539520 1188458032 12.669 100 47 94 0 1624304224 423065904 12780288
12:08:27 7077 8192000000 10546276384 11009839104 1186501712 12.651 100 48 96 1 1646320192 447723200 12095280
12:10:07 28426 8192000000 10451718864 10938433536 1178216384 12.574 100 30 58 0 1564796288 378869680 7710224
12:11:38 23837 8192000000 10321767040 10871402496 1144626928 12.259 99 24 48 0 1430408256 279140464 6640864
12:13:07 18770 8192000000 10142184976 10631737344 1120669728 12.033 98 35 63 0 1285506848 157282976 7554144
12:14:28 36096 8192000000 10119358560 10568269824 1130969456 12.131 99 24 46 1 1219094208 82545792 5578960
12:16:07 70959 8192000000 10131197136 10563858432 1108247280 11.916 97 28 53 2 1199547216 85229600 6070336
12:18:24 146062 8192000000 10150480496 10661183488 1130214400 12.123 99 24 48 0 1261411408 125867248 5329760
12:22:00 37578 8192000000 10073256704 10527043584 1116646832 11.995 98 38 72 0 1182664912 58367344 7650736
12:23:40 46706 8192000000 10074997616 10512023552 1093973600 11.780 96 37 72 0 1143994624 42232704 7788320
12:25:28 49313 8192000000 10113216896 10582011904 1129711024 12.119 99 26 52 0 1222204032 87134864 5358144
12:27:19 1027 8192000000 10143733424 10566762496 1137017824 12.187 100 79 140 6 1233801616 83142752 13641040
12:28:20 65867 8192000000 10122078048 10605699072 1136527136 12.183 99 21 42 0 1238977920 97957488 4493296
12:30:30 52955 8192000000 10090777280 10586365952 1127635040 12.099 99 14 28 1 1209591696 78888416 3068240
12:32:24 24034 8192000000 10088761552 10538942464 1116615776 11.995 99 27 53 0 1178213648 56342960 5254912
12:33:51 59018 8192000000 10040371472 10518249472 1083421104 11.680 96 11 20 1 1114232064 29116048 1694912
12:35:56 23366 8192000000 10138653872 10572857344 1137304592 12.190 100 53 103 0 1226188304 78180320 10703392
12:37:21 77966 8192000000 10107199216 10573627392 1120844128 12.035 98 10 20 0 1154594400 30959856 2790416
12:39:39 68855 8192000000 10121038960 10611961856 1144428912 12.257 100 16 32 0 1241610208 93546144 3635152
12:42:22 115160 8192000000 10097088560 10554990592 1111990528 11.951 98 19 36 0 1169369712 53676048 3703136
12:45:30 31520 8192000000 10094479600 10544840704 1115611488 11.986 98 36 72 1 1172309408 48871408 7826512
12:47:04 78497 8192000000 10096258416 10542379008 1129046368 12.112 100 29 58 0 1205512496 70323504 6142624
12:49:28 109872 8192000000 10055280880 10505777152 1105440864 11.889 97 27 54 0 1142043408 31442800 5159744
12:52:20 11943 8192000000 10104460336 10541367296 1144367312 12.257 100 57 114 0 1202283488 45994560 11921616
12:53:35 52346 8192000000 10118910448 10580336640 1123952352 12.064 98 33 66 0 1226567184 95955504 6659328
12:55:28 43366 8192000000 10077036784 10541694976 1133378752 12.153 100 27 54 1 1166298032 27124672 5794608
12:57:12 47469 8192000000 10093880912 10569371648 1116704896 11.996 98 14 24 0 1164431712 45125008 2601808
12:59:01 53194 8192000000 10127101728 10587848704 1146178064 12.274 100 31 58 0 1213945264 61883584 5883616
13:00:55 43882 8192000000 10075606768 10519441408 1132668464 12.147 99 30 53 1 1176160240 37507936 5983840
13:02:39 39574 8192000000 10103970416 10565140480 1114536928 11.975 97 35 70 0 1213043280 91328864 7177488
13:04:20 47642 8192000000 10126925520 10576941056 1124055696 12.065 98 28 54 0 1190450400 61296240 5098464
13:06:08 24177 8192000000 10154320432 10608685056 1143196000 12.246 99 40 78 1 1230420368 79483328 7741040
13:07:33 39699 8192000000 10071415664 10506211328 1107837200 11.912 97 37 74 0 1159505776 43537952 8130624
13:09:18 32361 8192000000 10098393104 10529542144 1116992752 11.999 98 35 65 0 1181249232 57652832 6603648
13:10:53 39840 8192000000 10106887264 10593402880 1146534144 12.277 100 31 62 0 1252993408 100182960 6276304
13:12:38 57143 8192000000 10114030144 10605682688 1118936544 12.017 98 25 50 0 1220068416 96249184 4882688
13:14:39 18755 8192000000 10137065664 10596409344 1123282272 12.058 99 45 89 2 1225138944 92857792 8998880
13:15:59 100670 8192000000 10057648960 10528309248 1072198144 11.573 95 32 64 0 1133568896 54765632 6605120
13:18:46 51356 8192000000 10044582112 10554368000 1115883664 11.988 99 16 31 0 1190720160 71690336 3146160
13:21:08 77385 8192000000 10061756768 10524413952 1116453664 11.993 99 17 34 0 1149323424 29322512 3547248
13:23:29 51835 8192000000 10106498736 10566836224 1126964704 12.093 99 21 42 0 1186006160 54600784 4440672
13:25:21 18775 8192000000 10076639136 10514456576 1116176080 11.991 98 35 64 0 1150502592 27074816 7251696
13:26:41 58110 8192000000 10078734208 10536210432 1115228416 11.982 98 29 57 0 1166611840 44769472 6613952
13:28:44 34706 8192000000 10099586080 10545192960 1096301968 11.803 97 35 68 0 1170029408 66756640 6970800
13:30:22 24250 8192000000 10090318576 10528407552 1135336944 12.172 100 37 69 1 1179340544 37325952 6677648
13:31:47 79714 8192000000 10069038912 10520936448 1081501840 11.662 95 25 50 0 1132004992 45581184 4921968
13:34:10 42907 8192000000 10113654768 10548875264 1117754256 12.006 99 41 80 0 1195590160 70109792 7726112
13:35:53 7758 8192000000 10123986048 10548256768 1115719264 11.987 99 64 128 1 1196120144 67499616 12901264
13:37:01 64613 8192000000 9135050752 9429663744 267966320 3.167 25 9 18 0 317529408 47864448 1698640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 14-03-2023 13:40:58 to 14-03-2023 13:41:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-03-2023 13:41:16 to 14-03-2023 13:41:18.

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          223240
MON$OLDEST_ACTIVE               223241
MON$OLDEST_SNAPSHOT             223241
MON$NEXT_TRANSACTION            223241
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                       2070176
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2908 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2908 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2908 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2908 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 ---
:164: 
:165: mscs-test-161.budzdorov.ru    Tue Mar 14 13:38:17 2023
:166:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:167: 
--- end of diff output ---

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

14.03.23 13:41:19. 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-03-14 13:41:21 No severe PSQL-related problems occured
14.03.23 13:41:23. End of report.