Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.09.2022 04:38
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 15421884 kB
MemAvailable 30600708 kB
Buffers 20 kB
Cached 16262432 kB
SwapCached 2048 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.2
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 2022-09-24 04:37:30
DTS_END                         2022-09-24 04:37:30.3490
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/51032
TRN_ID                          216608
ATT_ID                          606
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: 8521.96 22756 1022635
customer order: creation 167.28 457 20073
customer order: refuse 13.96 436 1675
order to supplier: creation 43.35 1338 5202
order to supplier: removal 3.18 164516 381
invoice (draft): creation 43.61 3216 5233
invoice (draft): removal 46.07 1038 5528
invoice accept: apply 40.63 28190 4876
invoice accept: cancel 41.39 1285 4967
customer reserve: creation 3740.54 271 448865
customer reserve: removal 4107.74 98 492929
realization accept: apply 79.24 289 9509
realization accept: cancel 72.43 185 8692
payment from customer: creation 55.74 198 6689
payment from customer: removal 4.98 142 598
payment to supplier: creation 46.67 186 5600
payment to supplier: removal 3.19 136 383
service: total inventory turnovers 4.05 3831 486
service: total monetary turnovers 6.98 433 838
service: aggregate perf. data 0.88 16620 105
service: refresh index statistics 0.05 232263 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-09-2022 04:39:08 to 24-09-2022 04:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7182
TEST_TIME 2 8995
TEST_TIME 3 8143
TEST_TIME 4 8326
TEST_TIME 5 7689
TEST_TIME 6 7575
TEST_TIME 7 7316
TEST_TIME 8 7106
TEST_TIME 9 7415
TEST_TIME 10 7394
TEST_TIME 11 7356
TEST_TIME 12 7611
TEST_TIME 13 7486
TEST_TIME 14 7674
TEST_TIME 15 7780
TEST_TIME 16 7735
TEST_TIME 17 7617
TEST_TIME 18 7724
TEST_TIME 19 7951
TEST_TIME 20 7842
TEST_TIME 21 7851
TEST_TIME 22 7799
TEST_TIME 23 7878
TEST_TIME 24 8180
TEST_TIME 25 8175
TEST_TIME 26 8321
TEST_TIME 27 8732
TEST_TIME 28 8865
TEST_TIME 29 9724
TEST_TIME 30 10209
TEST_TIME 31 10121
TEST_TIME 32 10592
TEST_TIME 33 10653
TEST_TIME 34 10451
TEST_TIME 35 10306
TEST_TIME 36 10388
TEST_TIME 37 10265
TEST_TIME 38 10289
TEST_TIME 39 10218
TEST_TIME 40 10091
TEST_TIME 41 10014
TEST_TIME 42 9874
TEST_TIME 43 9831
TEST_TIME 44 9686
TEST_TIME 45 9581
TEST_TIME 46 9415
TEST_TIME 47 9295
TEST_TIME 48 9212
TEST_TIME 49 9288
TEST_TIME 50 9209
TEST_TIME 51 9358
TEST_TIME 52 9367
TEST_TIME 53 9306
TEST_TIME 54 9189
TEST_TIME 55 9300
TEST_TIME 56 9228
TEST_TIME 57 9217
TEST_TIME 58 9197
TEST_TIME 59 9146
TEST_TIME 60 9130
TEST_TIME 61 9202
TEST_TIME 62 9133
TEST_TIME 63 9114
TEST_TIME 64 9097
TEST_TIME 65 9100
TEST_TIME 66 9089
TEST_TIME 67 9076
TEST_TIME 68 9050
TEST_TIME 69 8956
TEST_TIME 70 8888
TEST_TIME 71 8865
TEST_TIME 72 8808
TEST_TIME 73 8775
TEST_TIME 74 8745
TEST_TIME 75 8670
TEST_TIME 76 8628
TEST_TIME 77 8620
TEST_TIME 78 8576
TEST_TIME 79 8692
TEST_TIME 80 8656
TEST_TIME 81 8683
TEST_TIME 82 8648
TEST_TIME 83 8692
TEST_TIME 84 8743
TEST_TIME 85 8722
TEST_TIME 86 8723
TEST_TIME 87 8790
TEST_TIME 88 8825
TEST_TIME 89 8837
TEST_TIME 90 8795
TEST_TIME 91 8727
TEST_TIME 92 8671
TEST_TIME 93 8752
TEST_TIME 94 8679
TEST_TIME 95 8662
TEST_TIME 96 8609
TEST_TIME 97 8666
TEST_TIME 98 8629
TEST_TIME 99 8625
TEST_TIME 100 8655
TEST_TIME 101 8663
TEST_TIME 102 8666
TEST_TIME 103 8695
TEST_TIME 104 8677
TEST_TIME 105 8661
TEST_TIME 106 8661
TEST_TIME 107 8722
TEST_TIME 108 8690
TEST_TIME 109 8722
TEST_TIME 110 8723
TEST_TIME 111 8791
TEST_TIME 112 8756
TEST_TIME 113 8738
TEST_TIME 114 8709
TEST_TIME 115 8673
TEST_TIME 116 8685
TEST_TIME 117 8639
TEST_TIME 118 8598
TEST_TIME 119 8590
TEST_TIME 120 8546

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-09-2022 04:39:12 to 24-09-2022 04:39:15.

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 1433768 1433768 0 0.00 0 26981 14 0 0
doc_list_biud 1433760 1433760 0 0.00 0 40455 40 0 0
sp_add_invoice_to_stock 4978 4876 102 2.04 72 212070 28190 0 102
sp_cancel_adding_invoice 4967 4967 0 0.00 5 109387 1285 0 0
sp_cancel_client_order 1675 1675 0 0.00 6 20293 437 0 0
sp_cancel_customer_reserve 492929 492929 0 0.00 1 40489 99 0 0
sp_cancel_pay_from_customer 598 598 0 0.00 4 10265 142 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 2832 136 0 0
sp_cancel_supplier_invoice 5542 5528 14 0.25 3 35708 1039 0 14
sp_cancel_supplier_order 381 381 0 0.00 18 1552413 164517 0 0
sp_cancel_write_off 8692 8692 0 0.00 2 35312 186 0 0
sp_client_order 20073 20073 0 0.00 5 36484 458 0 0
sp_customer_reserve 448865 448865 0 0.00 1 118864 272 0 0
sp_fill_shopping_cart 30496 30496 0 0.00 1 12012 86 0 0
sp_fill_shopping_cart_clo_res 448832 448832 0 0.00 0 16594 21 0 0
sp_get_clo_for_invoice 4830 4830 0 0.00 0 24786 314 0 0
sp_kill_cost_storno 14640 14640 0 0.00 0 26971 78 0 0
sp_kill_qstorno_ret_qs2qd 1000459 1000459 0 0.00 0 40415 43 0 0
sp_kill_qty_storno 528509 528509 0 0.00 0 40452 100 0 0
sp_lock_selected_doc 19678 19678 0 0.00 0 7209 7 0 0
sp_make_cost_storno 26627 26627 0 0.00 0 15176 85 0 0
sp_multiply_rows_for_pdistr 26626 26626 0 0.00 0 9175 39 0 0
sp_multiply_rows_for_qdistr 20073 20073 0 0.00 0 10538 158 0 0
sp_pay_from_customer 6689 6689 0 0.00 5 21488 198 0 0
sp_pay_to_supplier 5600 5600 0 0.00 6 15942 187 0 0
sp_payment_common 12289 12289 0 0.00 2 11988 50 0 0
sp_qd_handle_on_cancel_clo 1675 1675 0 0.00 0 20264 351 0 0
sp_qd_handle_on_invoice_upd_sts 9796 9796 0 0.00 0 30697 548 0 0
sp_qd_handle_on_reserve_upd_sts 9508 9508 0 0.00 0 20534 61 0 0
sp_reserve_write_off 9509 9509 0 0.00 7 20665 289 0 0
sp_supplier_invoice 5233 5233 0 0.00 10 79813 3217 0 0
sp_supplier_order 5202 5202 0 0.00 7 29904 1338 0 0
srv_aggregate_perf_data 105 105 0 0.00 2518 154133 16621 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 766 128134 38943 0 0
srv_find_qd_qs_mism 974515 974515 0 0.00 0 19579 11 0 0
srv_make_invnt_saldo 486 486 0 0.00 65 59593 3832 0 0
srv_make_money_saldo 838 838 0 0.00 1 16345 434 0 0
srv_recalc_idx_stat 6 6 0 0.00 39555 454619 232264 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 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 4879 4879 0 0.00 1 21240 957 0 0
t$perf-norm:sp_cancel_adding_invoice 364 364 0 0.00 1 8620 542 0 0
t$perf-norm:sp_cancel_client_order 1675 1675 0 0.00 0 7698 37 0 0
t$perf-norm:sp_cancel_customer_reserve 632 632 0 0.00 0 7203 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 598 598 0 0.00 0 1210 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 1115 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 1220 33 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 79229 6378 0 0
t$perf-norm:sp_cancel_write_off 915 915 0 0.00 0 2376 26 0 0
t$perf-norm:sp_client_order 20073 20073 0 0.00 0 16325 37 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 533 12 0 0
t$perf-norm:sp_pay_from_customer 6689 6689 0 0.00 0 7669 21 0 0
t$perf-norm:sp_pay_to_supplier 5600 5600 0 0.00 0 1767 18 0 0
t$perf-norm:sp_reserve_write_off 9509 9509 0 0.00 0 5756 24 0 0
t$perf-norm:sp_supplier_invoice 5233 5233 0 0.00 0 2997 30 0 0
t$perf-norm:sp_supplier_order 5202 5202 0 0.00 0 5131 33 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 351 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 248 7 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 4567 32 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 3419 9 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 3 196 60 0 0
v_add_invoice_to_stock 9858 9654 204 2.06 0 21629 29 0 204
v_all_customers 36512 36512 0 0.00 0 73 4 0 0
v_all_suppliers 28904 28904 0 0.00 0 78 4 0 0
v_all_wares 40140 40140 0 0.00 0 87 4 0 0
v_cancel_adding_invoice 728 728 0 0.00 0 2190 18 0 0
v_cancel_client_order 3350 3350 0 0.00 0 18748 18 0 0
v_cancel_customer_prepayment 1196 1196 0 0.00 0 10178 24 0 0
v_cancel_customer_reserve 1264 1264 0 0.00 0 21437 29 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 2259 20 0 0
v_cancel_supplier_invoice 818 790 28 3.42 0 5397 33 0 28
v_cancel_supplier_order 762 762 0 0.00 0 9284 23 0 0
v_cancel_write_off 1830 1830 0 0.00 0 985 25 0 0
v_max_id_clo_ord 5200 5200 0 0.00 0 806 5 0 0
v_max_id_clo_res 965 965 0 0.00 0 2516 16 0 0
v_max_id_ord_sup 5226 5226 0 0.00 0 11948 10 0 0
v_max_non_paid_invoice 1574 1574 0 0.00 0 422 8 0 0
v_max_non_paid_realizn 3467 3467 0 0.00 0 560 8 0 0
v_min_id_clo_ord 5200 5200 0 0.00 0 8483 9 0 0
v_min_id_clo_res 965 965 0 0.00 0 3834 22 0 0
v_min_id_ord_sup 5226 5226 0 0.00 0 7603 10 0 0
v_min_non_paid_invoice 5600 5600 0 0.00 0 4767 16 0 0
v_min_non_paid_realizn 6689 6689 0 0.00 0 11771 23 0 0
v_our_firm 10070 10070 0 0.00 0 66 5 0 0
v_reserve_write_off 19015 19015 0 0.00 0 19388 15 0 0
x_lock_depdocs_on_canc_invoice 10100 10100 0 0.00 0 26721 149 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 13451 433 0 0
x_make_qstorno_1000_1200 5202 5202 0 0.00 3 29861 1172 0 0
x_make_qstorno_1200_2000 5233 5233 0 0.00 5 79667 2970 0 0
x_make_qstorno_2100_3300 448865 448865 0 0.00 0 118830 239 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-09-2022 04:39:16 to 24-09-2022 04:39:20.

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
02:07:34 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:35 32647 8192000000 9719623632 10016423936 801177440 8.908 100 91 181 1 830837264 7584512 22075312
02:10:15 16246 8192000000 9759166976 10066280448 831837952 9.218 100 89 178 1 866680896 13093008 21749936
02:11:37 12142 8192000000 9802764672 10122661888 864015904 9.540 100 82 164 0 907806928 24969696 18821328
02:12:54 10576 8192000000 9861952704 10194436096 915269888 10.049 100 73 144 0 970875424 39971936 15633600
02:14:07 3239 8192000000 9926459152 10269573120 963869440 10.527 100 90 172 0 1046729248 65036016 17823792
02:15:15 1562 8192000000 9973645328 10326405120 1000044384 10.879 100 95 176 0 1088373712 70493600 17835728
02:16:20 971 8192000000 10008589008 10372415488 1024728112 11.118 100 91 176 1 1130312432 87669552 17914768
02:17:22 2683 8192000000 10038528384 10408517632 1045487216 11.317 100 84 164 0 1148810640 87211136 16112288
02:18:26 956 8192000000 10053487008 10429857792 1068437872 11.537 100 95 182 0 1169106656 82552672 18116112
02:19:28 1001 8192000000 10025540992 10404159488 1078517152 11.633 100 96 177 1 1142440464 46306912 17616400
02:20:30 1011 8192000000 10038368304 10424569856 1081778464 11.664 100 87 168 0 1160046672 61768688 16499520
02:21:33 958 8192000000 10061627440 10448973824 1087157888 11.716 100 91 182 0 1179240896 73800032 18282976
02:22:35 3910 8192000000 10056780720 10448367616 1093522928 11.776 100 71 143 3 1183499120 75925008 14051184
02:23:40 1180 8192000000 10058601376 10450075648 1099451424 11.832 100 84 157 1 1180996736 64533168 17012144
02:24:42 1235 8192000000 10083226080 10471964672 1102881088 11.865 100 98 188 3 1205039936 82626368 19532480
02:25:44 966 8192000000 10046025008 10440876032 1108523280 11.918 100 71 132 2 1172016464 50762800 12730384
02:26:45 2665 8192000000 10068797728 10467508224 1102296304 11.859 100 78 154 1 1195284592 77061936 15926352
02:27:49 871 8192000000 10038320816 10433363968 1092104208 11.763 100 86 169 2 1161795536 52546512 17144816
02:28:53 984 8192000000 10040409936 10426785792 1085821120 11.703 100 82 162 1 1158681168 56731936 16128112
02:29:57 1769 8192000000 10076052368 10467999744 1102241664 11.859 100 89 174 3 1198709136 78918224 17549248
02:31:00 1088 8192000000 10038425616 10422919168 1077619728 11.625 100 94 180 4 1160750880 64738832 18392320
02:32:02 1076 8192000000 10029197984 10404208640 1051094032 11.371 99 96 183 1 1148034560 78411440 18529088
02:33:04 1568 8192000000 9993000256 10366840832 1034719296 11.214 99 83 154 2 1111341776 60257488 16364992
02:34:07 1380 8192000000 9999476320 10382090240 1046697872 11.329 99 79 156 2 1117455776 54644384 16113520
02:35:09 1219 8192000000 9992899376 10361774080 1034229408 11.209 99 98 179 5 1113704016 60692144 18782464
02:36:11 940 8192000000 10004123024 10375557120 1032982192 11.197 99 91 176 1 1127225200 76071440 18171568
02:37:13 3151 8192000000 9988779328 10371461120 1057841600 11.436 100 58 110 2 1115749168 46635376 11272192
02:38:20 4165 8192000000 10014640112 10398449664 1074101056 11.591 100 73 121 2 1134218480 49336000 10781424
02:39:28 1442 8192000000 10067128352 10452512768 1088496384 11.728 100 87 168 0 1182151104 75720624 17934096
02:40:32 1742 8192000000 10073586544 10460930048 1104160048 11.877 100 84 163 1 1189369280 68759536 16449696
02:41:38 4534 8192000000 10090846800 10486493184 1108809792 11.921 100 83 160 1 1206756272 80399680 17546800
02:42:48 3875 8192000000 10102126944 10495873024 1123322992 12.058 100 80 153 1 1217962288 79307344 15331952
02:43:55 1460 8192000000 10139345808 10533531648 1134435200 12.163 100 80 146 1 1255022224 105886864 14700160
02:44:59 1143 8192000000 10153268176 10550788096 1135462544 12.173 100 96 189 1 1271467120 115881616 20122960
02:46:04 1727 8192000000 10177511936 10593640448 1151614960 12.325 100 91 172 1 1295792032 125457264 18719808
02:47:09 3881 8192000000 10205410096 10617778176 1156358096 12.369 100 74 146 0 1318894256 146562880 15973280
02:48:16 2410 8192000000 10243302560 10646335488 1164882656 12.449 100 84 150 2 1350813600 170125520 15805424
02:49:19 826 8192000000 10246298816 10650038272 1170876672 12.505 100 92 173 1 1357398384 166752752 19768960
02:50:22 2221 8192000000 10274744928 10687279104 1180165376 12.592 100 81 160 0 1380940512 182694688 18080448
02:51:28 805 8192000000 10307201952 10720251904 1181938752 12.608 100 95 179 3 1415098928 213518304 19641872
02:52:31 1095 8192000000 10300326640 10723352576 1178460464 12.576 99 89 165 2 1407747120 210740128 18546528
02:53:34 2420 8192000000 10334923232 10742624256 1184912976 12.636 100 94 182 2 1442388400 235746656 21728768
02:54:42 878 8192000000 10379516880 10800734208 1195823808 12.738 100 91 175 2 1487539376 270184240 21531328
02:55:45 1735 8192000000 10408894320 10826784768 1201377408 12.789 100 97 190 0 1514662416 290516576 22768432
02:56:49 759 8192000000 10430051520 10850275328 1202485472 12.799 100 96 184 0 1535350192 311116608 21748112
02:57:52 911 8192000000 10464344288 10874585088 1191255520 12.695 100 99 195 1 1570757104 355794592 23706992
02:58:57 1085 8192000000 10514945920 10923614208 1200892416 12.785 100 92 177 2 1623585808 401360288 21333104
03:00:01 1024 8192000000 10545918144 10980544512 1201668448 12.792 100 98 196 0 1653846752 427532896 24645408
03:01:06 1083 8192000000 10566920880 11007746048 1198722544 12.764 100 91 180 0 1669189712 447980240 22486928
03:02:09 3785 8192000000 10577172304 11016335360 1194280048 12.723 100 60 120 1 1682985904 473548256 15157600
03:03:15 4515 8192000000 10569121616 11038363648 1186420816 12.650 100 61 114 0 1673354608 471345760 15588032
03:04:34 15756 8192000000 10551272144 11027718144 1178778768 12.579 100 51 102 0 1642428768 450923136 12726864
03:05:56 22324 8192000000 10388476352 10884964352 1147284960 12.284 99 34 67 0 1526677792 370438080 8954752
03:07:43 40603 8192000000 10194674976 10681675776 1079165136 11.640 95 15 30 1 1259957456 177003904 3788416
03:09:25 9469 8192000000 10144188432 10669412352 1133061232 12.150 99 41 80 0 1231865136 89969840 8834064
03:10:36 39870 8192000000 10163379504 10587406336 1144620592 12.259 100 63 99 1 1265705600 111994320 9090688
03:12:24 61058 8192000000 10185764624 10653106176 1149647712 12.306 100 25 50 0 1288042960 133123936 5271312
03:14:25 118904 8192000000 10101055168 10542657536 1105648272 11.891 96 17 34 1 1158933712 50178752 3106688
03:18:05 31935 8192000000 10064534848 10528747520 1116452608 11.993 99 31 58 0 1167443552 44478864 6512080
03:19:41 118555 8192000000 10128423120 10594918400 1120143296 12.028 98 24 47 0 1186885936 61653888 5088752
03:22:41 77193 8192000000 10174199584 10624147456 1145344368 12.266 100 21 42 0 1270874992 120612096 4918528
03:25:00 111187 8192000000 10150166688 10636414976 1123033968 12.056 98 21 42 0 1287638032 159464464 5139600
03:27:52 55878 8192000000 10119051712 10650337280 1146884880 12.280 100 16 32 0 1225113440 74783840 3444720
03:29:48 99028 8192000000 10127664944 10642296832 1136969856 12.187 99 13 26 0 1238055280 97686384 3399040
03:32:30 55709 8192000000 10064695568 10535931904 1121457072 12.041 98 10 18 0 1153334176 30104736 1772368
03:34:26 72262 8192000000 10060891040 10528034816 1132277248 12.143 99 26 50 0 1200192032 62366448 5548336
03:36:42 25230 8192000000 10122730560 10560950272 1146563136 12.277 99 46 92 1 1207318688 51960736 8794816
03:38:10 21270 8192000000 10130957984 10600349696 1151166032 12.320 100 46 91 0 1220292144 60093568 9032544
03:39:34 48819 8192000000 10135690400 10579828736 1142909728 12.243 99 39 78 0 1222173824 71008528 8255568
03:41:25 60893 8192000000 10112361552 10599784448 1139159392 12.208 99 28 56 0 1215327792 70681856 5486544
03:43:28 34731 8192000000 10112372816 10563198976 1135294864 12.171 99 34 69 0 1195252432 53098480 6859088
03:45:04 60077 8192000000 10090272336 10521964544 1106567024 11.900 96 26 52 0 1142102000 30625360 4909616
03:47:15 76946 8192000000 10066720368 10508939264 1126911792 12.092 98 30 58 1 1180209536 47130864 6166880
03:49:35 25257 8192000000 10103090432 10554241024 1145209408 12.265 100 60 120 1 1218138624 60861520 12067696
03:51:09 51930 8192000000 10089236272 10549424128 1104776336 11.883 96 14 25 0 1163574384 55689408 3108640
03:53:03 68854 8192000000 10131839216 10591629312 1112638480 11.957 97 12 24 0 1220519264 104967952 2912832
03:55:40 57240 8192000000 10076569232 10544549888 1072320752 11.574 96 10 20 0 1156336080 81435728 2579600
03:57:38 43950 8192000000 10075702800 10517913600 1104467456 11.880 98 28 55 0 1183429248 73354864 5606928
03:59:23 113324 8192000000 10058101664 10556260352 1068127344 11.534 95 14 28 0 1111998816 41103568 2767904
04:02:33 38829 8192000000 10118153040 10557841408 1124525760 12.070 99 49 97 0 1198478720 64397104 9555856
04:04:14 102957 8192000000 10055215200 10504806400 1063397536 11.489 94 15 30 0 1129712336 63246624 3068176
04:06:58 40548 8192000000 10086036864 10515550208 1116702208 11.996 99 42 85 1 1179974240 54148512 9123520
04:08:46 68065 8192000000 10172579088 10618839040 1129637200 12.118 100 35 70 0 1235741312 98876704 7227408
04:11:01 86868 8192000000 10099354336 10623332352 1107036224 11.904 98 4 8 0 1190607536 82728624 842688
04:13:28 75485 8192000000 10083003664 10574770176 1113478848 11.965 99 21 43 0 1208864928 91173984 4212096
04:16:03 60478 8192000000 10063724112 10542530560 1117799440 12.006 99 8 16 0 1177975504 58567264 1608800
04:18:04 56258 8192000000 10068675584 10509336576 1101947040 11.856 97 31 58 1 1144369120 35083088 7338992
04:20:04 51291 8192000000 10061090864 10499588096 1115721392 11.987 99 26 52 0 1167932272 46697168 5513712
04:21:56 16575 8192000000 10089843728 10517045248 1114981456 11.980 99 45 90 0 1173277456 49276128 9019872
04:23:19 30358 8192000000 10107890240 10532659200 1097862432 11.817 98 46 88 0 1178605600 71865856 8877312
04:24:54 19676 8192000000 10080359888 10528837632 1117008864 11.999 100 39 78 0 1169017168 44583552 7424752
04:26:15 25387 8192000000 10111109104 10568941568 1110836864 11.940 99 46 91 0 1191467360 71470544 9159952
04:27:51 88303 8192000000 10080858432 10549719040 1104673008 11.882 97 10 20 0 1140507408 33989440 1844960
04:30:20 128133 8192000000 10102228528 10554462208 1128369920 12.106 99 34 68 0 1190700016 56113760 6216336
04:33:28 52136 8192000000 10065861920 10554417152 1114453584 11.975 98 11 22 0 1160768944 44179504 2135856
04:35:20 77575 8192000000 10076967472 10547793920 1136657312 12.184 100 5 10 0 1171764896 34085632 1021952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-09-2022 04:39:22 to 24-09-2022 04:39:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 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 24-09-2022 04:39:39 to 24-09-2022 04:39:41.

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          214716
MON$OLDEST_ACTIVE               216751
MON$OLDEST_SNAPSHOT             216751
MON$NEXT_TRANSACTION            216751
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                       2052640
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2817 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2817 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2817 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2817 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 ---
:29: 
:30: mscs-test-161.budzdorov.ru    Sat Sep 24 04:37:33 2022
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Sat Sep 24 04:37:35 2022
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Sat Sep 24 04:37:36 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sat Sep 24 04:37:36 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sat Sep 24 04:37:36 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Sat Sep 24 04:37:38 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
--- end of diff output ---

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

24.09.22 04:39:42. 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
2022-09-24 04:39:44 No severe PSQL-related problems occured
24.09.22 04:39:46. End of report.