Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.02.2023 07:39
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 15124492 kB
MemAvailable 30447180 kB
Buffers 0 kB
Cached 16604112 kB
SwapCached 3352 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-12 07:37:26
DTS_END                         2023-02-12 07:37:26.2040
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/46062
TRN_ID                          220181
ATT_ID                          607
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: 9038.32 24390 1084598
customer order: creation 173.95 502 20874
customer order: refuse 14.47 455 1736
order to supplier: creation 44.13 1497 5295
order to supplier: removal 3.35 186022 402
invoice (draft): creation 47.68 3289 5722
invoice (draft): removal 49.33 1130 5919
invoice accept: apply 42.57 28779 5108
invoice accept: cancel 44.63 1460 5355
customer reserve: creation 3967.88 273 476146
customer reserve: removal 4356.98 113 522838
realization accept: apply 84.12 321 10094
realization accept: cancel 76.88 195 9225
payment from customer: creation 60.33 191 7239
payment from customer: removal 5.67 157 680
payment to supplier: creation 49.78 225 5974
payment to supplier: removal 3.60 153 432
service: total inventory turnovers 4.18 4339 502
service: total monetary turnovers 7.80 411 936
service: aggregate perf. data 0.98 15892 117
service: refresh index statistics 0.03 242396 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-02-2023 07:39:51 to 12-02-2023 07:39:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6554
TEST_TIME 2 6459
TEST_TIME 3 6103
TEST_TIME 4 5698
TEST_TIME 5 6144
TEST_TIME 6 6100
TEST_TIME 7 5937
TEST_TIME 8 6205
TEST_TIME 9 6576
TEST_TIME 10 6315
TEST_TIME 11 6246
TEST_TIME 12 6313
TEST_TIME 13 6291
TEST_TIME 14 6491
TEST_TIME 15 6749
TEST_TIME 16 6897
TEST_TIME 17 6924
TEST_TIME 18 7235
TEST_TIME 19 7349
TEST_TIME 20 7253
TEST_TIME 21 7793
TEST_TIME 22 7844
TEST_TIME 23 7874
TEST_TIME 24 8010
TEST_TIME 25 7901
TEST_TIME 26 7912
TEST_TIME 27 8204
TEST_TIME 28 8379
TEST_TIME 29 8438
TEST_TIME 30 8700
TEST_TIME 31 9155
TEST_TIME 32 9052
TEST_TIME 33 9301
TEST_TIME 34 9525
TEST_TIME 35 9872
TEST_TIME 36 10086
TEST_TIME 37 10910
TEST_TIME 38 10724
TEST_TIME 39 10604
TEST_TIME 40 10430
TEST_TIME 41 10371
TEST_TIME 42 10402
TEST_TIME 43 10348
TEST_TIME 44 10525
TEST_TIME 45 10338
TEST_TIME 46 10191
TEST_TIME 47 10050
TEST_TIME 48 10009
TEST_TIME 49 9897
TEST_TIME 50 9868
TEST_TIME 51 9788
TEST_TIME 52 9717
TEST_TIME 53 9626
TEST_TIME 54 9678
TEST_TIME 55 9649
TEST_TIME 56 9664
TEST_TIME 57 9665
TEST_TIME 58 9574
TEST_TIME 59 9533
TEST_TIME 60 9576
TEST_TIME 61 9511
TEST_TIME 62 9585
TEST_TIME 63 9571
TEST_TIME 64 9515
TEST_TIME 65 9512
TEST_TIME 66 9558
TEST_TIME 67 9668
TEST_TIME 68 9602
TEST_TIME 69 9647
TEST_TIME 70 9589
TEST_TIME 71 9685
TEST_TIME 72 9627
TEST_TIME 73 9655
TEST_TIME 74 9671
TEST_TIME 75 9582
TEST_TIME 76 9488
TEST_TIME 77 9423
TEST_TIME 78 9378
TEST_TIME 79 9412
TEST_TIME 80 9361
TEST_TIME 81 9317
TEST_TIME 82 9261
TEST_TIME 83 9223
TEST_TIME 84 9240
TEST_TIME 85 9236
TEST_TIME 86 9278
TEST_TIME 87 9282
TEST_TIME 88 9309
TEST_TIME 89 9354
TEST_TIME 90 9335
TEST_TIME 91 9394
TEST_TIME 92 9422
TEST_TIME 93 9430
TEST_TIME 94 9393
TEST_TIME 95 9398
TEST_TIME 96 9427
TEST_TIME 97 9452
TEST_TIME 98 9413
TEST_TIME 99 9345
TEST_TIME 100 9346
TEST_TIME 101 9301
TEST_TIME 102 9270
TEST_TIME 103 9233
TEST_TIME 104 9219
TEST_TIME 105 9183
TEST_TIME 106 9149
TEST_TIME 107 9104
TEST_TIME 108 9104
TEST_TIME 109 9125
TEST_TIME 110 9141
TEST_TIME 111 9100
TEST_TIME 112 9116
TEST_TIME 113 9125
TEST_TIME 114 9110
TEST_TIME 115 9145
TEST_TIME 116 9198
TEST_TIME 117 9189
TEST_TIME 118 9179
TEST_TIME 119 9131
TEST_TIME 120 9065

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-02-2023 07:39:55 to 12-02-2023 07:39:58.

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 1521513 1521513 0 0.00 0 22541 16 0 0
doc_list_biud 1521502 1521502 0 0.00 0 29941 45 0 0
sp_add_invoice_to_stock 5249 5108 141 2.68 79 210586 28779 0 141
sp_cancel_adding_invoice 5355 5355 0 0.00 7 117252 1461 0 0
sp_cancel_client_order 1736 1736 0 0.00 8 9503 455 0 0
sp_cancel_customer_reserve 522838 522838 0 0.00 1 27581 113 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 4 6837 158 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 4 6510 154 0 0
sp_cancel_supplier_invoice 5924 5919 5 0.08 3 30028 1131 0 5
sp_cancel_supplier_order 402 402 0 0.00 13 1570212 186023 0 0
sp_cancel_write_off 9225 9225 0 0.00 2 9208 196 0 0
sp_client_order 20874 20874 0 0.00 5 24698 503 0 0
sp_customer_reserve 476146 476146 0 0.00 0 114242 274 0 0
sp_fill_shopping_cart 31876 31876 0 0.00 1 11594 90 0 0
sp_fill_shopping_cart_clo_res 476110 476110 0 0.00 0 13584 18 0 0
sp_get_clo_for_invoice 5063 5063 0 0.00 0 10129 242 0 0
sp_kill_cost_storno 15692 15692 0 0.00 0 7919 84 0 0
sp_kill_qstorno_ret_qs2qd 1061222 1061222 0 0.00 0 29919 49 0 0
sp_kill_qty_storno 560630 560630 0 0.00 0 29930 113 0 0
sp_lock_selected_doc 20899 20899 0 0.00 0 8349 9 0 0
sp_make_cost_storno 28369 28369 0 0.00 0 11928 92 0 0
sp_multiply_rows_for_pdistr 28369 28369 0 0.00 0 8050 41 0 0
sp_multiply_rows_for_qdistr 20873 20873 0 0.00 0 11813 184 0 0
sp_pay_from_customer 7239 7239 0 0.00 5 16887 191 0 0
sp_pay_to_supplier 5974 5974 0 0.00 6 12862 225 0 0
sp_payment_common 13213 13213 0 0.00 2 9921 52 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 0 9196 371 0 0
sp_qd_handle_on_invoice_upd_sts 10417 10417 0 0.00 0 23785 578 0 0
sp_qd_handle_on_reserve_upd_sts 10093 10093 0 0.00 0 25899 77 0 0
sp_reserve_write_off 10094 10094 0 0.00 7 25922 321 0 0
sp_supplier_invoice 5722 5722 0 0.00 11 123811 3289 0 0
sp_supplier_order 5295 5295 0 0.00 7 24234 1498 0 0
srv_aggregate_perf_data 117 117 0 0.00 2381 128322 15893 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 751 122905 30167 0 0
srv_find_qd_qs_mism 1033920 1033920 0 0.00 0 22541 12 0 0
srv_make_invnt_saldo 502 502 0 0.00 31 73860 4339 0 0
srv_make_money_saldo 936 936 0 0.00 1 17238 411 0 0
srv_recalc_idx_stat 4 4 0 0.00 32086 539252 242397 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5116 5116 0 0.00 0 18216 1071 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 2 9394 542 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 1201 30 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 2831 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 1050 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 393 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 955 37 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 88853 8301 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 802 19 0 0
t$perf-norm:sp_client_order 20875 20875 0 0.00 0 20752 41 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 719 15 0 0
t$perf-norm:sp_pay_from_customer 7239 7239 0 0.00 0 8740 22 0 0
t$perf-norm:sp_pay_to_supplier 5974 5974 0 0.00 0 16097 24 0 0
t$perf-norm:sp_reserve_write_off 10094 10094 0 0.00 0 3390 24 0 0
t$perf-norm:sp_supplier_invoice 5722 5722 0 0.00 0 4277 38 0 0
t$perf-norm:sp_supplier_order 5296 5296 0 0.00 0 2271 36 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 310 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 178 9 0 0
t$perf-norm:srv_make_invnt_saldo 502 502 0 0.00 0 1344 22 0 0
t$perf-norm:srv_make_money_saldo 936 936 0 0.00 0 846 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 10 162 75 0 0
v_add_invoice_to_stock 10397 10116 281 2.70 0 9108 32 0 281
v_all_customers 37992 37992 0 0.00 0 127 4 0 0
v_all_suppliers 30854 30854 0 0.00 0 67 5 0 0
v_all_wares 41740 41740 0 0.00 0 181 4 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 734 16 0 0
v_cancel_client_order 3472 3472 0 0.00 0 2560 11 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 2397 15 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 4444 19 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 6466 23 0 0
v_cancel_supplier_invoice 796 786 10 1.25 0 4438 34 0 10
v_cancel_supplier_order 804 804 0 0.00 0 1445 16 0 0
v_cancel_write_off 1960 1960 0 0.00 0 2935 32 0 0
v_max_id_clo_ord 5292 5292 0 0.00 0 310 5 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 2730 20 0 0
v_max_id_ord_sup 5711 5711 0 0.00 0 6314 10 0 0
v_max_non_paid_invoice 1550 1550 0 0.00 0 76 8 0 0
v_max_non_paid_realizn 3835 3835 0 0.00 0 1178 8 0 0
v_min_id_clo_ord 5292 5292 0 0.00 0 5403 13 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 4910 12 0 0
v_min_id_ord_sup 5711 5711 0 0.00 0 7495 16 0 0
v_min_non_paid_invoice 5974 5974 0 0.00 0 9905 25 0 0
v_min_non_paid_realizn 7239 7239 0 0.00 0 4926 18 0 0
v_our_firm 10554 10554 0 0.00 0 70 5 0 0
v_reserve_write_off 20184 20184 0 0.00 0 4812 14 0 0
x_lock_depdocs_on_canc_invoice 10881 10881 0 0.00 0 16212 141 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 5714 384 0 0
x_make_qstorno_1000_1200 5295 5295 0 0.00 3 24218 1316 0 0
x_make_qstorno_1200_2000 5721 5721 0 0.00 5 123786 3045 0 0
x_make_qstorno_2100_3300 476144 476144 0 0.00 0 114240 242 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 12-02-2023 07:39:59 to 12-02-2023 07:40:04.

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:07:30 10 8192000000 8810988480 8827039744 14256528 0.173 5 5 6 0 14583408 185552 141328
05:08:31 32685 8192000000 9721252464 10021126144 794941792 8.845 100 87 175 0 824402976 7860608 21600576
05:10:14 18935 8192000000 9760570992 10071248896 835197136 9.252 100 89 178 0 869487232 13157152 21132944
05:11:40 13984 8192000000 9807990256 10127527936 869510272 9.595 100 87 174 0 914572960 25211952 19850736
05:12:59 10722 8192000000 9850228128 10183852032 913124064 10.028 100 77 150 0 967408672 37996224 16288384
05:14:16 5248 8192000000 9907583264 10248216576 960710480 10.496 100 85 164 0 1022885232 45326816 16847936
05:15:24 1748 8192000000 9960264064 10312278016 988081040 10.763 100 87 161 1 1079730624 75633952 16015632
05:16:28 2540 8192000000 9985557408 10345443328 1015197040 11.026 100 90 172 1 1096571824 64193552 17181232
05:17:34 1462 8192000000 10013327024 10379710464 1032136128 11.189 100 95 180 1 1132371552 82206064 18029360
05:18:37 1115 8192000000 10020487840 10389782528 1050186816 11.362 100 97 185 1 1136280432 67574416 18519200
05:19:40 946 8192000000 10042564880 10417184768 1061499472 11.471 100 95 178 0 1165083568 85730640 17853456
05:20:41 4955 8192000000 10042783200 10419015680 1067044160 11.524 100 93 183 1 1160431632 74054288 19333184
05:21:47 1828 8192000000 10037547680 10422984704 1076726608 11.616 100 84 159 2 1157978064 64416240 16835216
05:22:51 1394 8192000000 10076068144 10457616384 1081950832 11.666 100 97 185 2 1188126400 86486400 19689168
05:23:53 1047 8192000000 10056897200 10441920512 1087244032 11.716 100 99 193 2 1176878560 70470720 19163808
05:24:56 1125 8192000000 10082793456 10466185216 1090174400 11.744 100 90 177 1 1204795760 97216784 17404576
05:25:58 1211 8192000000 10065545472 10454147072 1095341072 11.793 100 92 174 3 1187651520 73584720 18725728
05:27:00 1032 8192000000 10070684976 10462736384 1101961456 11.856 100 84 150 2 1190347056 72091488 16294112
05:28:03 1146 8192000000 10078264592 10466967552 1101270304 11.850 100 96 188 0 1198625584 78189888 19165392
05:29:06 1093 8192000000 10073586032 10463531008 1103603440 11.872 100 97 183 0 1196199296 73575280 19020576
05:30:09 1116 8192000000 10052121040 10445406208 1105189728 11.887 100 89 177 0 1174925008 51443952 18291328
05:31:11 1199 8192000000 10064585792 10449960960 1069038512 11.543 99 92 175 0 1172815840 86263712 17513616
05:32:14 4601 8192000000 10023255408 10413658112 1073568528 11.586 100 67 125 1 1155362480 69149920 12644032
05:33:21 3232 8192000000 10038427344 10433327104 1059607584 11.453 100 70 141 0 1161436752 87277856 14551312
05:34:25 1446 8192000000 9951375168 10324291584 1012404608 10.999 99 66 126 2 1075721120 50482448 12834064
05:35:27 1449 8192000000 9951879904 10320347136 1003638928 10.914 99 80 150 3 1074004496 53178288 17187280
05:36:31 1212 8192000000 9986149760 10360942592 1030171600 11.170 100 87 169 3 1106071504 58883376 17016528
05:37:34 10137 8192000000 9998628480 10373390336 1050508560 11.366 100 84 149 0 1114769232 49287792 14972880
05:38:56 1006 8192000000 10031105056 10410889216 1067147440 11.525 100 82 154 0 1146694816 65264224 14283152
05:40:04 2289 8192000000 10055546128 10438295552 1078074752 11.629 100 83 157 3 1176354528 81906176 16373600
05:41:15 19480 8192000000 10097292992 10487562240 1094391872 11.784 100 72 137 2 1211115472 102023120 14700480
05:42:42 2838 8192000000 10090653248 10483941376 1107646208 11.910 100 82 154 2 1208598384 85558240 15393936
05:43:49 3074 8192000000 10145579232 10538631168 1115299552 11.983 100 82 156 2 1260392128 128928320 16164256
05:44:58 1876 8192000000 10160510656 10567213056 1121421152 12.040 100 90 168 0 1277695424 137356944 18917328
05:46:06 2134 8192000000 10151939472 10577649664 1131113744 12.132 100 80 145 0 1272017136 125306512 15596880
05:47:11 15585 8192000000 10174116400 10577281024 1130299296 12.124 99 67 123 0 1280398544 137216912 12882336
05:48:33 1797 8192000000 10215938208 10631901184 1147323312 12.284 100 75 148 0 1330451664 166861504 16266848
05:49:40 1569 8192000000 10271431264 10676326400 1163680864 12.438 100 94 179 1 1384015008 198364992 21969152
05:50:46 2404 8192000000 10319391968 10719981568 1172972672 12.525 100 86 172 1 1434122864 241528432 19621760
05:51:55 1436 8192000000 10312251856 10736857088 1178835248 12.579 100 91 175 0 1421769904 223530448 19404208
05:53:00 2322 8192000000 10365442624 10766811136 1183405712 12.622 100 89 173 1 1474607248 271668304 19533232
05:54:05 1175 8192000000 10386645248 10804805632 1183208512 12.620 100 96 190 1 1494642624 290210832 21223280
05:55:09 1580 8192000000 10396275344 10824499200 1185529616 12.642 100 83 159 1 1504146144 300690368 17926160
05:56:14 1181 8192000000 10435317520 10854543360 1191205856 12.695 100 87 172 1 1543026464 331563088 20257520
05:57:18 922 8192000000 10414605088 10851094528 1201199904 12.787 100 98 192 0 1519344960 294477360 23667696
05:58:21 867 8192000000 10448602576 10879086592 1202923680 12.803 100 99 189 2 1553260960 329033648 21303632
05:59:25 973 8192000000 10483537008 10894966784 1203063024 12.805 100 92 177 0 1589053424 366256768 19733632
06:00:28 1706 8192000000 10496009664 10901442560 1179205712 12.583 99 95 186 2 1601048624 400225456 21617456
06:01:34 2050 8192000000 10526140720 10959761408 1180643312 12.596 100 84 156 0 1626991056 427740144 18607600
06:02:40 3324 8192000000 10531659184 10942685184 1157196368 12.377 99 92 175 0 1638832912 459864512 21772032
06:03:46 738 8192000000 10564285632 11020951552 1185402768 12.641 100 100 199 2 1669004528 459601056 24000704
06:04:49 951 8192000000 10527895376 10984927232 1168523904 12.483 99 97 191 1 1631326464 437669952 25132608
06:05:52 2905 8192000000 10561834096 11000451072 1178817840 12.579 100 75 150 0 1671795392 474157248 18820304
06:06:58 1072 8192000000 10564690352 11029372928 1180472288 12.595 100 78 155 0 1667872592 468715040 18685264
06:08:03 5969 8192000000 10510472576 11018289152 1177392640 12.566 100 61 122 0 1609015664 415863008 15760016
06:09:17 12036 8192000000 10498630912 10929696768 1147390352 12.285 98 53 103 0 1583424176 422939792 13094032
06:10:41 11144 8192000000 10474077440 10934575104 1161081072 12.413 100 38 76 0 1564776272 394029424 9665776
06:11:53 32549 8192000000 10356374144 10905350144 1150720016 12.316 100 22 44 0 1525111552 368470288 5921248
06:13:27 35266 8192000000 10269689760 10794909696 1128184544 12.104 99 19 38 0 1410343008 277147696 5010768
06:15:03 66176 8192000000 10062765728 10523238400 1111242752 11.944 98 12 23 0 1155830624 42277584 2310288
06:17:19 28789 8192000000 10154096672 10579951616 1160001200 12.403 100 72 132 2 1225313664 49783936 15528528
06:19:00 54913 8192000000 10163324656 10649513984 1156387840 12.369 100 15 31 0 1272391424 112763712 3239872
06:21:06 81838 8192000000 10088985936 10527481856 1132635376 12.146 98 20 38 0 1206313568 68707632 4970560
06:23:32 32177 8192000000 10109336768 10550038528 1147185680 12.283 100 56 105 1 1184717456 25732880 11798896
06:25:13 46767 8192000000 10092138448 10549915648 1125403968 12.078 98 32 64 1 1176409024 44476864 6528192
06:27:02 1096 8192000000 10096995872 10522382336 1140699232 12.222 100 85 164 2 1188636560 30716960 17220368
06:28:05 15214 8192000000 10096302448 10545745920 1138477664 12.201 100 27 54 0 1192780384 48675152 5627568
06:29:29 27833 8192000000 10145678992 10575323136 1113098240 11.962 97 47 92 0 1213627120 89940208 10588672
06:31:02 67039 8192000000 10126379552 10595618816 1141445088 12.229 100 35 69 0 1259575552 110950016 7180448
06:33:10 52682 8192000000 10114846848 10603528192 1131677856 12.137 99 36 69 1 1223879856 84438592 7763408
06:35:27 69452 8192000000 10115157152 10594447360 1147552800 12.287 100 26 48 1 1214801488 60489760 6758928
06:38:21 102402 8192000000 10069414576 10530213888 1075742224 11.607 94 13 25 0 1116524912 38588448 2194240
06:41:05 9201 8192000000 10111379264 10538123264 1129528512 12.117 99 54 101 0 1197992128 58180960 10282656
06:42:15 36890 8192000000 10163449504 10604425216 1114770672 11.978 98 30 58 0 1214383360 93058400 6554288
06:43:54 28901 8192000000 10132865024 10594856960 1125483296 12.079 99 22 44 0 1218834672 88409488 4941888
06:45:31 19375 8192000000 10091238752 10523746304 1116326240 11.992 98 53 96 1 1169042528 42314720 10401568
06:47:04 48752 8192000000 10121524608 10572595200 1134813840 12.167 100 23 46 0 1195721136 56246608 4660688
06:48:53 54069 8192000000 10105772656 10541858816 1126857424 12.092 98 21 40 1 1156593600 25490032 4246144
06:50:47 122904 8192000000 10056027744 10542886912 1062108240 11.477 93 21 42 0 1122980432 55832096 5040096
06:54:24 77930 8192000000 10123295152 10565894144 1119824512 12.025 98 39 78 1 1193382592 65903504 7654576
06:56:49 40726 8192000000 10124871472 10586824704 1147278688 12.284 100 26 52 2 1230226160 76967888 5979584
06:58:33 82349 8192000000 10156621360 10616696832 1144879264 12.261 100 21 40 1 1239060112 89482352 4698496
07:01:45 50917 8192000000 10134652960 10595885056 1035846032 11.225 91 15 30 0 1167276608 127823600 3606976
07:03:43 34995 8192000000 10133259248 10580516864 1118529088 12.013 99 22 44 1 1229070896 105782928 4758880
07:05:20 103589 8192000000 10079751920 10547798016 1076632080 11.615 95 19 38 0 1189459024 108565056 4261888
07:08:04 18462 8192000000 10080511312 10530070528 1116786288 11.997 100 45 89 1 1174372208 48884544 8701376
07:09:26 27134 8192000000 10107696208 10551816192 1129486400 12.117 100 45 86 1 1204028224 65385056 9156768
07:10:58 22172 8192000000 10112976288 10561445888 1139055968 12.207 100 43 83 0 1206465728 58590512 8819248
07:12:21 4606 8192000000 10101287424 10531065856 1137375824 12.191 100 56 106 0 1187286272 39571664 10338784
07:13:29 16941 8192000000 10168720592 10610593792 1140172896 12.217 100 48 92 0 1240650128 91287056 9190176
07:14:46 56141 8192000000 10103317696 10562166784 1140855152 12.224 100 48 94 1 1175004528 23186256 10963120
07:17:26 71013 8192000000 10097349088 10576117760 1131326512 12.134 99 41 83 0 1178985360 39861840 7797008
07:19:40 55721 8192000000 10082468560 10548322304 1121630000 12.042 98 13 26 0 1169528480 44985872 2912608
07:21:46 55660 8192000000 10099453408 10566688768 1140368240 12.219 99 27 52 1 1189623552 43685488 5569824
07:23:42 82444 8192000000 10108866656 10582802432 1139034400 12.206 99 12 24 0 1198066832 56534080 2498352
07:26:05 56497 8192000000 10080713920 10543161344 1063765664 11.493 93 19 39 1 1110760720 42994288 4000768
07:28:15 24867 8192000000 10129092624 10564796416 1134444528 12.163 100 59 113 1 1206690320 59667472 12578320
07:29:48 44889 8192000000 10125933440 10601795584 1138104368 12.198 100 32 65 0 1253096272 107827424 7164480
07:31:35 58969 8192000000 10114130640 10610028544 1122267264 12.048 99 30 60 0 1230816496 101927616 6621616
07:33:34 41928 8192000000 10087014448 10557890560 1090885904 11.751 97 34 68 1 1167516544 69337056 7293584
07:35:19 9225 8192000000 10083137648 10520084480 1106448416 11.899 99 62 119 0 1154922256 35944240 12529600
07:36:28 74182 8192000000 9636544384 10020835328 727492368 8.156 66 42 84 0 767779584 32045680 8241536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-02-2023 07:40:06 to 12-02-2023 07:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 12-02-2023 07:40:23 to 12-02-2023 07:40:26.

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          220468
MON$OLDEST_ACTIVE               220469
MON$OLDEST_SNAPSHOT             220469
MON$NEXT_TRANSACTION            220469
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                       2059040
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 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 ---
:182: 
:183: mscs-test-161.budzdorov.ru    Sun Feb 12 07:37:58 2023
:184:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:185: 
:186: 
:187: mscs-test-161.budzdorov.ru    Sun Feb 12 07:37:58 2023
:188:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:189: 
--- end of diff output ---

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

12.02.23 07:40:27. 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-12 07:40:29 No severe PSQL-related problems occured
12.02.23 07:40:31. End of report.