Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.03.2023 13: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 14874760 kB
MemAvailable 30434272 kB
Buffers 0 kB
Cached 16846824 kB
SwapCached 3028 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-07 13:37:27
DTS_END                         2023-03-07 13:37:27.4620
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/36864
TRN_ID                          226385
ATT_ID                          673
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: 9313.76 25063 1117651
customer order: creation 169.39 437 20327
customer order: refuse 14.53 392 1743
order to supplier: creation 45.81 1301 5497
order to supplier: removal 3.54 151518 425
invoice (draft): creation 46.32 3070 5558
invoice (draft): removal 51.51 978 6181
invoice accept: apply 43.05 24995 5166
invoice accept: cancel 46.60 1097 5592
customer reserve: creation 4013.04 237 481565
customer reserve: removal 4580.11 90 549613
realization accept: apply 83.84 259 10061
realization accept: cancel 84.47 156 10136
payment from customer: creation 59.33 161 7119
payment from customer: removal 5.65 117 678
payment to supplier: creation 49.64 195 5957
payment to supplier: removal 3.63 121 436
service: total inventory turnovers 4.31 3669 517
service: total monetary turnovers 8.07 319 968
service: aggregate perf. data 0.90 16676 108
service: refresh index statistics 0.03 295468 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-03-2023 13:39:26 to 07-03-2023 13:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6676
TEST_TIME 2 4455
TEST_TIME 3 5330
TEST_TIME 4 5043
TEST_TIME 5 4972
TEST_TIME 6 4720
TEST_TIME 7 5071
TEST_TIME 8 5141
TEST_TIME 9 5504
TEST_TIME 10 5509
TEST_TIME 11 5906
TEST_TIME 12 6294
TEST_TIME 13 6569
TEST_TIME 14 6871
TEST_TIME 15 7530
TEST_TIME 16 7861
TEST_TIME 17 7853
TEST_TIME 18 8132
TEST_TIME 19 8350
TEST_TIME 20 8505
TEST_TIME 21 8729
TEST_TIME 22 9112
TEST_TIME 23 8905
TEST_TIME 24 8755
TEST_TIME 25 8691
TEST_TIME 26 8802
TEST_TIME 27 9091
TEST_TIME 28 8990
TEST_TIME 29 9454
TEST_TIME 30 9850
TEST_TIME 31 10453
TEST_TIME 32 10678
TEST_TIME 33 11137
TEST_TIME 34 11151
TEST_TIME 35 11187
TEST_TIME 36 11433
TEST_TIME 37 11362
TEST_TIME 38 11220
TEST_TIME 39 11161
TEST_TIME 40 11048
TEST_TIME 41 11014
TEST_TIME 42 10970
TEST_TIME 43 10920
TEST_TIME 44 10985
TEST_TIME 45 10937
TEST_TIME 46 10714
TEST_TIME 47 10621
TEST_TIME 48 10540
TEST_TIME 49 10413
TEST_TIME 50 10339
TEST_TIME 51 10287
TEST_TIME 52 10173
TEST_TIME 53 10129
TEST_TIME 54 10047
TEST_TIME 55 10106
TEST_TIME 56 10037
TEST_TIME 57 10065
TEST_TIME 58 9989
TEST_TIME 59 9858
TEST_TIME 60 9993
TEST_TIME 61 10038
TEST_TIME 62 10137
TEST_TIME 63 10132
TEST_TIME 64 10089
TEST_TIME 65 10088
TEST_TIME 66 10146
TEST_TIME 67 10166
TEST_TIME 68 10104
TEST_TIME 69 10100
TEST_TIME 70 10115
TEST_TIME 71 10130
TEST_TIME 72 10096
TEST_TIME 73 9996
TEST_TIME 74 9896
TEST_TIME 75 9878
TEST_TIME 76 9796
TEST_TIME 77 9712
TEST_TIME 78 9613
TEST_TIME 79 9562
TEST_TIME 80 9602
TEST_TIME 81 9672
TEST_TIME 82 9683
TEST_TIME 83 9682
TEST_TIME 84 9639
TEST_TIME 85 9664
TEST_TIME 86 9745
TEST_TIME 87 9699
TEST_TIME 88 9697
TEST_TIME 89 9661
TEST_TIME 90 9651
TEST_TIME 91 9676
TEST_TIME 92 9656
TEST_TIME 93 9586
TEST_TIME 94 9531
TEST_TIME 95 9521
TEST_TIME 96 9478
TEST_TIME 97 9428
TEST_TIME 98 9397
TEST_TIME 99 9380
TEST_TIME 100 9365
TEST_TIME 101 9352
TEST_TIME 102 9359
TEST_TIME 103 9325
TEST_TIME 104 9338
TEST_TIME 105 9348
TEST_TIME 106 9344
TEST_TIME 107 9395
TEST_TIME 108 9393
TEST_TIME 109 9371
TEST_TIME 110 9391
TEST_TIME 111 9478
TEST_TIME 112 9529
TEST_TIME 113 9547
TEST_TIME 114 9539
TEST_TIME 115 9471
TEST_TIME 116 9464
TEST_TIME 117 9412
TEST_TIME 118 9425
TEST_TIME 119 9397
TEST_TIME 120 9337

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-03-2023 13:39:30 to 07-03-2023 13:39:34.

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 1560751 1560751 0 0.00 0 31439 13 0 0
doc_list_biud 1560739 1560739 0 0.00 0 44711 38 0 0
sp_add_invoice_to_stock 5224 5166 58 1.11 32 271922 24996 0 58
sp_cancel_adding_invoice 5592 5592 0 0.00 6 64061 1097 0 0
sp_cancel_client_order 1743 1743 0 0.00 6 13733 393 0 0
sp_cancel_customer_reserve 549613 549613 0 0.00 1 20482 91 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 4 7374 117 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 5988 121 0 0
sp_cancel_supplier_invoice 6185 6181 4 0.06 3 31904 978 0 4
sp_cancel_supplier_order 425 425 0 0.00 15 1378814 151518 0 0
sp_cancel_write_off 10136 10136 0 0.00 2 44719 157 0 0
sp_client_order 20327 20327 0 0.00 5 35474 438 0 0
sp_customer_reserve 481565 481565 0 0.00 0 110812 238 0 0
sp_fill_shopping_cart 31372 31372 0 0.00 1 13418 74 0 0
sp_fill_shopping_cart_clo_res 481533 481533 0 0.00 0 12829 17 0 0
sp_get_clo_for_invoice 5127 5127 0 0.00 0 23102 262 0 0
sp_kill_cost_storno 16842 16842 0 0.00 0 7813 66 0 0
sp_kill_qstorno_ret_qs2qd 1115968 1115968 0 0.00 0 44708 39 0 0
sp_kill_qty_storno 588878 588878 0 0.00 0 44710 92 0 0
sp_lock_selected_doc 20966 20966 0 0.00 0 9149 9 0 0
sp_make_cost_storno 28264 28264 0 0.00 0 31434 76 0 0
sp_multiply_rows_for_pdistr 28264 28264 0 0.00 0 12433 35 0 0
sp_multiply_rows_for_qdistr 20327 20327 0 0.00 0 8334 158 0 0
sp_pay_from_customer 7119 7119 0 0.00 5 17475 161 0 0
sp_pay_to_supplier 5957 5957 0 0.00 6 16638 195 0 0
sp_payment_common 13076 13076 0 0.00 2 12516 44 0 0
sp_qd_handle_on_cancel_clo 1743 1743 0 0.00 0 13671 318 0 0
sp_qd_handle_on_invoice_upd_sts 10719 10719 0 0.00 0 20763 512 0 0
sp_qd_handle_on_reserve_upd_sts 10061 10061 0 0.00 0 11351 51 0 0
sp_reserve_write_off 10061 10061 0 0.00 8 31453 260 0 0
sp_supplier_invoice 5558 5558 0 0.00 11 104064 3070 0 0
sp_supplier_order 5497 5497 0 0.00 8 37932 1301 0 0
srv_aggregate_perf_data 108 108 0 0.00 2820 205514 16677 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 818 161708 34027 0 0
srv_find_qd_qs_mism 1067728 1067728 0 0.00 0 16125 10 0 0
srv_make_invnt_saldo 517 517 0 0.00 63 66111 3670 0 0
srv_make_money_saldo 968 968 0 0.00 1 17189 319 0 0
srv_recalc_idx_stat 4 4 0 0.00 121140 398591 295469 0 0
t$perf-abend:sp_add_invoice_to_stock 58 58 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 5167 5167 0 0.00 1 10055 852 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 1 6838 381 0 0
t$perf-norm:sp_cancel_client_order 1743 1743 0 0.00 0 1590 22 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 4056 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 3981 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 18933 60 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 6272 60 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 90156 6840 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 3982 25 0 0
t$perf-norm:sp_client_order 20327 20327 0 0.00 0 6496 32 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 2610 15 0 0
t$perf-norm:sp_pay_from_customer 7119 7119 0 0.00 0 4085 17 0 0
t$perf-norm:sp_pay_to_supplier 5957 5957 0 0.00 0 4493 18 0 0
t$perf-norm:sp_reserve_write_off 10061 10061 0 0.00 0 7684 23 0 0
t$perf-norm:sp_supplier_invoice 5558 5558 0 0.00 0 5581 27 0 0
t$perf-norm:sp_supplier_order 5497 5497 0 0.00 0 5424 27 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 656 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 3126 51 0 0
t$perf-norm:srv_make_invnt_saldo 517 517 0 0.00 0 1050 15 0 0
t$perf-norm:srv_make_money_saldo 968 968 0 0.00 0 2773 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 34 15 0 0
v_add_invoice_to_stock 10368 10252 116 1.11 0 4854 24 0 116
v_all_customers 36982 36982 0 0.00 0 132 3 0 0
v_all_suppliers 30892 30892 0 0.00 0 129 4 0 0
v_all_wares 40654 40654 0 0.00 0 59 4 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 2871 19 0 0
v_cancel_client_order 3486 3486 0 0.00 0 2898 12 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 7365 18 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 5025 18 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 3381 14 0 0
v_cancel_supplier_invoice 792 784 8 1.01 0 2250 30 0 8
v_cancel_supplier_order 850 850 0 0.00 0 2283 13 0 0
v_cancel_write_off 2046 2046 0 0.00 0 7775 29 0 0
v_max_id_clo_ord 5497 5497 0 0.00 0 800 5 0 0
v_max_id_clo_res 991 991 0 0.00 0 2383 11 0 0
v_max_id_ord_sup 5548 5548 0 0.00 0 2166 6 0 0
v_max_non_paid_invoice 1556 1556 0 0.00 0 759 8 0 0
v_max_non_paid_realizn 3812 3812 0 0.00 0 580 7 0 0
v_min_id_clo_ord 5497 5497 0 0.00 0 1458 8 0 0
v_min_id_clo_res 991 991 0 0.00 0 3008 12 0 0
v_min_id_ord_sup 5548 5548 0 0.00 0 7852 10 0 0
v_min_non_paid_invoice 5957 5957 0 0.00 0 12507 19 0 0
v_min_non_paid_realizn 7119 7119 0 0.00 0 4595 16 0 0
v_our_firm 10286 10286 0 0.00 0 63 4 0 0
v_reserve_write_off 20122 20122 0 0.00 0 4816 11 0 0
x_lock_depdocs_on_canc_invoice 11381 11381 0 0.00 0 20133 125 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 3275 315 0 0
x_make_qstorno_1000_1200 5497 5497 0 0.00 2 37920 1143 0 0
x_make_qstorno_1200_2000 5558 5558 0 0.00 4 104045 2876 0 0
x_make_qstorno_2100_3300 481565 481565 0 0.00 0 110809 209 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-03-2023 13:39:35 to 07-03-2023 13:39:39.

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:31 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:32 27201 8192000000 9711128240 10011447296 792292464 8.818 100 87 174 0 822016688 7889600 21834624
11:10:09 18043 8192000000 9762332208 10068738048 835995040 9.260 100 92 184 0 870509024 12423440 22090544
11:11:34 14239 8192000000 9794379008 10112647168 863475216 9.535 100 87 174 0 904874864 20495104 20904544
11:12:51 4603 8192000000 9852984240 10191720448 911406816 10.011 100 71 142 0 965795520 38762112 15626592
11:14:01 5173 8192000000 9900807168 10243092480 957085456 10.460 100 87 169 2 1022134672 47013920 18035296
11:15:10 2514 8192000000 9965483344 10318442496 991330032 10.794 100 91 176 2 1079811808 69580144 18901632
11:16:14 1210 8192000000 9994683264 10356707328 1027794272 11.147 100 89 170 2 1116751920 72459392 16498256
11:17:17 701 8192000000 10006778960 10377207808 1045983104 11.322 100 95 180 0 1125469888 61198800 18287984
11:18:20 952 8192000000 10033913728 10412978176 1064153408 11.496 100 97 190 1 1160213712 75957712 20102592
11:19:22 1087 8192000000 10055983440 10432458752 1070148080 11.553 100 94 180 1 1169599152 81504144 17946928
11:20:24 1041 8192000000 10061182832 10442539008 1078314992 11.631 100 92 178 1 1181166608 84358288 18493328
11:21:26 1023 8192000000 10062961824 10444857344 1083637856 11.682 100 92 177 0 1182226656 81884624 16704176
11:22:28 972 8192000000 10034302432 10424901632 1090200352 11.745 100 86 166 0 1158145280 50855216 17089712
11:23:30 949 8192000000 10045273888 10434945024 1090064352 11.743 100 87 166 3 1165815488 59838816 15912320
11:24:32 1036 8192000000 10053961376 10444333056 1097442320 11.813 100 93 174 1 1174147312 58137264 18567728
11:25:34 1107 8192000000 10073476000 10466127872 1103178112 11.868 100 85 163 0 1193108976 73388624 16542240
11:26:36 1751 8192000000 10048947344 10441723904 1099600720 11.834 100 74 143 0 1170780624 56759184 14420720
11:27:38 1053 8192000000 10070648256 10464890880 1098841776 11.827 100 92 175 2 1194498816 77180352 18476688
11:28:40 1261 8192000000 10058139040 10449244160 1098559072 11.824 100 98 181 1 1187137792 68570432 20008288
11:29:42 1464 8192000000 10045102000 10439639040 1086342352 11.708 100 76 149 2 1170142720 67850304 15950064
11:30:44 1082 8192000000 10023089312 10409635840 1069813376 11.550 99 85 154 6 1144206064 58406864 15985824
11:31:46 5124 8192000000 9990010464 10372800512 1045066464 11.313 98 71 137 3 1109092624 51409360 12616800
11:32:52 5680 8192000000 9973147024 10360754176 1023127600 11.102 99 66 128 2 1094413136 58199136 13086400
11:34:06 973 8192000000 9996220480 10372915200 1037488752 11.241 100 89 171 2 1119733136 63881280 18363104
11:35:15 838 8192000000 9988477152 10364563456 1035866272 11.225 100 93 168 0 1109703216 56087616 17749328
11:36:17 1100 8192000000 9992108832 10374545408 1046100064 11.323 100 76 149 1 1116268816 53513536 16655216
11:37:19 1451 8192000000 9985218912 10372296704 1056414512 11.422 100 69 131 0 1109256384 39243968 13597904
11:38:25 13276 8192000000 9982372032 10371796992 1068278128 11.536 100 62 103 1 1102338032 23284928 10774976
11:39:52 3158 8192000000 10069534560 10453381120 1087435712 11.718 100 92 174 3 1190808480 84046000 19326768
11:40:59 15664 8192000000 10066312736 10464710656 1086702384 11.711 100 56 109 1 1184637696 86934400 11000912
11:42:19 7065 8192000000 10089350528 10485260288 1094072752 11.781 100 71 137 1 1207805472 99555232 14177488
11:43:28 14860 8192000000 10137703152 10537926656 1108728560 11.920 100 58 114 0 1254885680 133569488 12587632
11:44:47 4155 8192000000 10148050896 10554449920 1116741296 11.996 100 75 144 0 1265378944 133157344 15480304
11:46:04 1428 8192000000 10197652768 10587725824 1131473744 12.135 100 91 171 0 1305170928 155886032 17811152
11:47:12 2817 8192000000 10213584800 10608840704 1132643632 12.146 100 73 144 1 1324176896 176135664 15397600
11:48:18 2535 8192000000 10266657472 10663419904 1159091920 12.395 100 92 178 1 1376958112 199253968 18612224
11:49:22 1452 8192000000 10297915680 10701209600 1171084320 12.507 100 97 184 2 1408995280 217694896 20216064
11:50:27 1238 8192000000 10330547808 10731798528 1184389184 12.631 100 94 181 2 1440843680 237032336 19422160
11:51:31 1062 8192000000 10326167808 10766389248 1181539296 12.605 100 97 189 1 1429584944 225864560 22181088
11:52:34 12527 8192000000 10318042432 10749935616 1167522752 12.474 99 51 102 0 1426360928 245842656 12995520
11:53:57 1295 8192000000 10301868848 10714374144 1169928560 12.496 100 76 145 2 1412762768 225181280 17652928
11:55:02 813 8192000000 10330801408 10757783552 1162076864 12.423 100 95 187 1 1438195024 253669664 22448496
11:56:07 927 8192000000 10360518720 10777739264 1164047616 12.441 100 98 188 3 1464484496 279518224 20918656
11:57:09 976 8192000000 10373539264 10788102144 1170610640 12.503 100 96 192 1 1481813056 289034688 22167728
11:58:14 1584 8192000000 10340055888 10789429248 1174390096 12.538 100 95 186 1 1446206800 249061568 22755136
11:59:19 2956 8192000000 10353055520 10774650880 1171868064 12.514 100 80 156 3 1459016480 266808032 20340384
12:00:24 6332 8192000000 10383360800 10806988800 1180478080 12.595 100 53 104 0 1480618448 287352128 12788240
12:01:35 3857 8192000000 10429490384 10837823488 1171104848 12.507 100 75 148 0 1535725984 347213616 17407520
12:02:45 1512 8192000000 10478092672 10901741568 1178785664 12.579 100 81 160 1 1583553760 385356448 19411648
12:03:52 1211 8192000000 10478531312 10909380608 1158825120 12.392 99 81 159 1 1580815936 402157536 19833280
12:04:56 12102 8192000000 10503010096 10939023360 1173884752 12.533 100 54 104 0 1609772576 422139616 13748208
12:06:10 6333 8192000000 10502217280 10958749696 1166072256 12.460 100 59 118 0 1608204288 427031648 15100384
12:07:26 23089 8192000000 10413249072 10893901824 1132064320 12.141 98 40 80 0 1512293456 369446592 10782544
12:08:57 17742 8192000000 10235626272 10725511168 1123411344 12.059 99 30 59 0 1331444944 201011824 7021776
12:10:16 28285 8192000000 10133469984 10642759680 1127580528 12.099 100 18 36 0 1232291472 100384720 4326224
12:11:45 33154 8192000000 10181810960 10659586048 1134833904 12.167 99 21 43 0 1263916480 124438752 4643824
12:13:24 62237 8192000000 10063212368 10527719424 1108493504 11.918 98 6 12 0 1166976096 57329152 1153440
12:15:29 47254 8192000000 10085377600 10528624640 1107861248 11.912 98 32 56 1 1174751920 60591088 6299584
12:17:17 52290 8192000000 10079007072 10524012544 1110963520 11.942 98 31 59 0 1140184832 22691504 6529808
12:19:22 82242 8192000000 10076805600 10534207488 1121580272 12.042 98 34 68 2 1170613008 41953696 7079040
12:21:45 142134 8192000000 10090981072 10532323328 1120333280 12.030 98 35 69 1 1158606592 31299792 6973520
12:25:14 32572 8192000000 10095847520 10542473216 1138979968 12.206 100 49 91 1 1178786864 30705872 9101024
12:26:52 18377 8192000000 10083156224 10536562688 1142256256 12.237 100 23 45 0 1183793136 36732864 4804016
12:28:13 31857 8192000000 10115356832 10554527744 1143175584 12.245 100 39 76 0 1202764928 51087776 8501568
12:29:45 13358 8192000000 10147181520 10578268160 1143591504 12.249 99 65 117 1 1228009472 70342944 14075024
12:31:00 34540 8192000000 10201234416 10660577280 1143861792 12.252 99 36 70 2 1289447424 137585056 8000576
12:32:57 60341 8192000000 10178345936 10682540032 1124364320 12.068 98 23 42 1 1245526512 116524048 4638144
12:34:58 39129 8192000000 10161039792 10632638464 1150480096 12.314 100 11 20 0 1244228560 90927136 2821328
12:36:38 74306 8192000000 10151101696 10656813056 1134957904 12.168 99 21 38 0 1232587040 93157360 4471776
12:39:09 76666 8192000000 10086887312 10537717760 1095949632 11.799 96 20 40 0 1179200560 79102576 4148352
12:41:26 32713 8192000000 10105398416 10557841408 1137765264 12.195 100 51 102 2 1204761888 56259152 10737472
12:43:04 65916 8192000000 10108854976 10575171584 1144943584 12.262 100 28 56 1 1250424288 99834720 5645984
12:45:11 15144 8192000000 10076161872 10516504576 1125220880 12.076 100 68 121 1 1166414448 29368208 11825360
12:46:28 35219 8192000000 10107344416 10559217664 1097767936 11.816 98 44 84 0 1160707584 53848944 9090704
12:48:13 81926 8192000000 10091993808 10517004288 1049614240 11.357 93 20 38 0 1075806768 22414736 3777792
12:50:38 79485 8192000000 10083920896 10513240064 1077303776 11.622 96 46 89 1 1118865280 32013856 9547648
12:54:35 54543 8192000000 10141012464 10593173504 1097775024 11.817 97 13 26 0 1206315376 105430560 3109792
12:56:48 54702 8192000000 10152625600 10666102784 1118508288 12.013 99 18 36 0 1247901952 124992848 4400816
12:59:08 71709 8192000000 10053330336 10573402112 1076114688 11.610 96 8 14 0 1138319344 60541360 1663296
13:01:21 77305 8192000000 10137579184 10614149120 1087134912 11.715 96 25 50 0 1193511488 101298976 5077600
13:03:47 25621 8192000000 10063215904 10496794624 1116513072 11.994 100 36 65 0 1158272624 34250336 7509216
13:05:16 6399 8192000000 10065944704 10483875840 1111439264 11.946 100 69 127 0 1155647120 31293952 12913904
13:06:25 28278 8192000000 10093605680 10515517440 1111504480 11.947 99 50 96 1 1168782944 47330368 9948096
13:07:55 9207 8192000000 10104506096 10541150208 1120274320 12.030 100 64 122 2 1200999184 68393968 12330896
13:09:08 17849 8192000000 10092558432 10525446144 1120827312 12.035 100 48 87 0 1187706000 56679648 10199040
13:10:26 90377 8192000000 10099951056 10556870656 1129746544 12.119 100 29 56 0 1204931456 69773888 5411024
13:13:08 48972 8192000000 10073072416 10543730688 1131597840 12.136 100 11 22 0 1189065888 55202864 2265184
13:15:05 82508 8192000000 10080405296 10589290496 1134150384 12.160 100 6 12 0 1198062576 62467872 1444320
13:17:27 24186 8192000000 10080437088 10554630144 1138564528 12.202 100 16 32 1 1187233504 45347168 3321808
13:18:57 45534 8192000000 10081866080 10531565568 1117395328 12.002 98 13 26 0 1169269664 49313920 2560416
13:20:43 11039 8192000000 10114350048 10556805120 1143765248 12.251 100 55 110 0 1208842032 53597696 11479088
13:21:55 63760 8192000000 10131180176 10607849472 1120726720 12.034 98 30 58 0 1199413312 73227248 5459344
13:23:59 74191 8192000000 10120137984 10622496768 1121540592 12.042 98 21 41 0 1164853216 39096288 4216336
13:26:25 26478 8192000000 10175400400 10634321920 1157051376 12.376 100 42 84 0 1273550784 108108272 8391136
13:27:56 62132 8192000000 10128480928 10653732864 1113033008 11.961 97 24 48 0 1248404672 130629808 4741856
13:30:34 161708 8192000000 10112426352 10597298176 1108162848 11.915 97 22 42 0 1181467776 68710576 4594352
13:34:29 29370 8192000000 10101468544 10571780096 1128717808 12.109 99 36 67 0 1191919888 56508880 6693200
13:36:04 48770 8192000000 10046676656 10509160448 1094553920 11.786 96 20 40 0 1129586432 30625952 4406560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-03-2023 13:39:41 to 07-03-2023 13:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 58 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:02, from 07-03-2023 13:39:58 to 07-03-2023 13:40:00.

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          224889
MON$OLDEST_ACTIVE               226498
MON$OLDEST_SNAPSHOT             226498
MON$NEXT_TRANSACTION            226498
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                       2080672
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2906 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2906 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2906 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2906 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 ---
:140: 
:141: mscs-test-161.budzdorov.ru    Tue Mar  7 13:37:48 2023
:142:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:143: 
:144: 
:145: mscs-test-161.budzdorov.ru    Tue Mar  7 13:37:48 2023
:146:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:147: 
:148: 
:149: mscs-test-161.budzdorov.ru    Tue Mar  7 13:37:48 2023
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:151: 
:152: 
:153: mscs-test-161.budzdorov.ru    Tue Mar  7 13:37:48 2023
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Tue Mar  7 13:37:48 2023
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:159: 
:160: 
:161: mscs-test-161.budzdorov.ru    Tue Mar  7 13:37:48 2023
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:163: 
:164: 
:165: mscs-test-161.budzdorov.ru    Tue Mar  7 13:37:48 2023
:166:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:167: 
:168: 
:169: mscs-test-161.budzdorov.ru    Tue Mar  7 13:37:48 2023
:170:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:171: 
:172: 
:173: mscs-test-161.budzdorov.ru    Tue Mar  7 13:37:48 2023
:174:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:175: 
:176: 
:177: mscs-test-161.budzdorov.ru    Tue Mar  7 13:37:48 2023
:178:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:179: 
:180: 
:181: mscs-test-161.budzdorov.ru    Tue Mar  7 13:37:48 2023
:182:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:183: 
:184: 
:185: mscs-test-161.budzdorov.ru    Tue Mar  7 13:37:48 2023
:186:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:187: 
--- end of diff output ---

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

07.03.23 13:40:01. 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-07 13:40:04 No severe PSQL-related problems occured
07.03.23 13:40:05. End of report.