Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.03.2023 04: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 14972188 kB
MemAvailable 30447208 kB
Buffers 20 kB
Cached 16690976 kB
SwapCached 3388 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-19 04:37:26
DTS_END                         2023-03-19 04:37:26.8620
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/46250
TRN_ID                          208438
ATT_ID                          597
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: 8700.98 21742 1044117
customer order: creation 155.95 478 18714
customer order: refuse 13.65 421 1638
order to supplier: creation 41.72 1536 5006
order to supplier: removal 3.39 165915 407
invoice (draft): creation 41.53 3380 4984
invoice (draft): removal 50.38 1050 6046
invoice accept: apply 37.92 27435 4551
invoice accept: cancel 45.89 1085 5507
customer reserve: creation 3565.48 259 427857
customer reserve: removal 4475.23 97 537028
realization accept: apply 76.78 285 9214
realization accept: cancel 75.57 172 9068
payment from customer: creation 54.03 195 6483
payment from customer: removal 5.34 135 641
payment to supplier: creation 44.14 194 5297
payment to supplier: removal 2.88 137 345
service: total inventory turnovers 3.83 3910 459
service: total monetary turnovers 6.50 361 780
service: aggregate perf. data 0.73 22099 87
service: refresh index statistics 0.04 205696 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-03-2023 04:40:15 to 19-03-2023 04:40:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6367
TEST_TIME 2 6471
TEST_TIME 3 5964
TEST_TIME 4 5645
TEST_TIME 5 5402
TEST_TIME 6 6097
TEST_TIME 7 6081
TEST_TIME 8 6353
TEST_TIME 9 6303
TEST_TIME 10 6362
TEST_TIME 11 6410
TEST_TIME 12 6503
TEST_TIME 13 6549
TEST_TIME 14 6689
TEST_TIME 15 6830
TEST_TIME 16 7002
TEST_TIME 17 6927
TEST_TIME 18 7191
TEST_TIME 19 7717
TEST_TIME 20 7921
TEST_TIME 21 7993
TEST_TIME 22 8271
TEST_TIME 23 8261
TEST_TIME 24 8377
TEST_TIME 25 8560
TEST_TIME 26 8373
TEST_TIME 27 9106
TEST_TIME 28 9865
TEST_TIME 29 10053
TEST_TIME 30 10187
TEST_TIME 31 10242
TEST_TIME 32 10205
TEST_TIME 33 11082
TEST_TIME 34 11488
TEST_TIME 35 11236
TEST_TIME 36 10993
TEST_TIME 37 10872
TEST_TIME 38 10661
TEST_TIME 39 10621
TEST_TIME 40 10614
TEST_TIME 41 10541
TEST_TIME 42 10356
TEST_TIME 43 10193
TEST_TIME 44 10232
TEST_TIME 45 10101
TEST_TIME 46 9973
TEST_TIME 47 9876
TEST_TIME 48 9802
TEST_TIME 49 9731
TEST_TIME 50 9610
TEST_TIME 51 9626
TEST_TIME 52 9483
TEST_TIME 53 9357
TEST_TIME 54 9291
TEST_TIME 55 9361
TEST_TIME 56 9339
TEST_TIME 57 9337
TEST_TIME 58 9263
TEST_TIME 59 9149
TEST_TIME 60 9181
TEST_TIME 61 9127
TEST_TIME 62 9130
TEST_TIME 63 9070
TEST_TIME 64 9052
TEST_TIME 65 9005
TEST_TIME 66 9013
TEST_TIME 67 9118
TEST_TIME 68 9174
TEST_TIME 69 9234
TEST_TIME 70 9202
TEST_TIME 71 9152
TEST_TIME 72 9211
TEST_TIME 73 9185
TEST_TIME 74 9170
TEST_TIME 75 9124
TEST_TIME 76 9025
TEST_TIME 77 9081
TEST_TIME 78 9014
TEST_TIME 79 9021
TEST_TIME 80 9009
TEST_TIME 81 8959
TEST_TIME 82 8911
TEST_TIME 83 8891
TEST_TIME 84 8814
TEST_TIME 85 8780
TEST_TIME 86 8850
TEST_TIME 87 8852
TEST_TIME 88 8804
TEST_TIME 89 8841
TEST_TIME 90 8943
TEST_TIME 91 8915
TEST_TIME 92 8919
TEST_TIME 93 8923
TEST_TIME 94 8894
TEST_TIME 95 9037
TEST_TIME 96 9000
TEST_TIME 97 9017
TEST_TIME 98 9051
TEST_TIME 99 9003
TEST_TIME 100 8952
TEST_TIME 101 8907
TEST_TIME 102 8883
TEST_TIME 103 8850
TEST_TIME 104 8801
TEST_TIME 105 8779
TEST_TIME 106 8781
TEST_TIME 107 8788
TEST_TIME 108 8776
TEST_TIME 109 8752
TEST_TIME 110 8770
TEST_TIME 111 8741
TEST_TIME 112 8776
TEST_TIME 113 8763
TEST_TIME 114 8791
TEST_TIME 115 8796
TEST_TIME 116 8775
TEST_TIME 117 8782
TEST_TIME 118 8796
TEST_TIME 119 8782
TEST_TIME 120 8731

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-03-2023 04:40:19 to 19-03-2023 04:40:23.

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 1438417 1438417 0 0.00 0 23738 13 0 0
doc_list_biud 1438397 1438397 0 0.00 0 32039 42 0 0
sp_add_invoice_to_stock 4743 4551 192 4.04 40 210818 27436 0 192
sp_cancel_adding_invoice 5507 5507 0 0.00 6 54785 1086 0 0
sp_cancel_client_order 1638 1638 0 0.00 5 12395 421 0 0
sp_cancel_customer_reserve 537028 537028 0 0.00 1 32045 98 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 3 7857 135 0 0
sp_cancel_pay_to_supplier 345 345 0 0.00 4 12113 138 0 0
sp_cancel_supplier_invoice 6063 6046 17 0.28 3 21615 1050 0 17
sp_cancel_supplier_order 407 407 0 0.00 8 1540241 165915 0 0
sp_cancel_write_off 9068 9068 0 0.00 2 14527 172 0 0
sp_client_order 18714 18714 0 0.00 6 30281 479 0 0
sp_customer_reserve 427857 427857 0 0.00 1 80304 260 0 0
sp_fill_shopping_cart 28695 28695 0 0.00 1 14429 82 0 0
sp_fill_shopping_cart_clo_res 427810 427810 0 0.00 0 13763 20 0 0
sp_get_clo_for_invoice 4493 4493 0 0.00 0 26356 339 0 0
sp_kill_cost_storno 15561 15561 0 0.00 0 10645 71 0 0
sp_kill_qstorno_ret_qs2qd 1089577 1089577 0 0.00 0 32029 43 0 0
sp_kill_qty_storno 573397 573397 0 0.00 0 32037 99 0 0
sp_lock_selected_doc 18910 18910 0 0.00 0 9307 7 0 0
sp_make_cost_storno 25487 25487 0 0.00 0 23724 81 0 0
sp_multiply_rows_for_pdistr 25485 25485 0 0.00 0 9889 37 0 0
sp_multiply_rows_for_qdistr 18714 18714 0 0.00 0 14212 177 0 0
sp_pay_from_customer 6483 6483 0 0.00 6 14061 196 0 0
sp_pay_to_supplier 5297 5297 0 0.00 6 12114 194 0 0
sp_payment_common 11779 11779 0 0.00 2 9710 49 0 0
sp_qd_handle_on_cancel_clo 1638 1638 0 0.00 0 11254 341 0 0
sp_qd_handle_on_invoice_upd_sts 10000 10000 0 0.00 0 23525 501 0 0
sp_qd_handle_on_reserve_upd_sts 9210 9210 0 0.00 0 13707 70 0 0
sp_reserve_write_off 9214 9214 0 0.00 8 23802 285 0 0
sp_supplier_invoice 4984 4984 0 0.00 9 89174 3380 0 0
sp_supplier_order 5006 5006 0 0.00 7 66405 1536 0 0
srv_aggregate_perf_data 87 87 0 0.00 2638 263996 22100 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 756 121888 36397 0 0
srv_find_qd_qs_mism 999539 999539 0 0.00 0 17797 10 0 0
srv_make_invnt_saldo 459 459 0 0.00 57 104480 3911 0 0
srv_make_money_saldo 780 780 0 0.00 1 15236 362 0 0
srv_recalc_idx_stat 5 5 0 0.00 55619 461406 205696 0 0
t$perf-abend:sp_add_invoice_to_stock 192 192 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4554 4554 0 0.00 0 11725 853 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 1 9995 407 0 0
t$perf-norm:sp_cancel_client_order 1638 1638 0 0.00 0 20812 42 0 0
t$perf-norm:sp_cancel_customer_reserve 598 598 0 0.00 0 2450 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 19329 42 0 0
t$perf-norm:sp_cancel_pay_to_supplier 345 345 0 0.00 0 11571 43 0 0
t$perf-norm:sp_cancel_supplier_invoice 343 343 0 0.00 0 599 31 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 102178 6546 0 0
t$perf-norm:sp_cancel_write_off 852 852 0 0.00 0 14853 37 0 0
t$perf-norm:sp_client_order 18716 18716 0 0.00 0 25054 38 0 0
t$perf-norm:sp_customer_reserve 885 885 0 0.00 0 12083 28 0 0
t$perf-norm:sp_pay_from_customer 6483 6483 0 0.00 0 5050 23 0 0
t$perf-norm:sp_pay_to_supplier 5297 5297 0 0.00 0 30437 35 0 0
t$perf-norm:sp_reserve_write_off 9215 9215 0 0.00 0 20407 22 0 0
t$perf-norm:sp_supplier_invoice 4984 4984 0 0.00 0 19015 34 0 0
t$perf-norm:sp_supplier_order 5006 5006 0 0.00 0 5388 30 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 2476 38 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 457 12 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 472 12 0 0
t$perf-norm:srv_make_money_saldo 780 780 0 0.00 0 3810 14 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 209 81 0 0
v_add_invoice_to_stock 9366 8982 384 4.09 0 12249 31 0 384
v_all_customers 34328 34328 0 0.00 0 140 3 0 0
v_all_suppliers 28144 28144 0 0.00 0 153 4 0 0
v_all_wares 37424 37424 0 0.00 0 78 4 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 788 13 0 0
v_cancel_client_order 3276 3276 0 0.00 0 12051 16 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 5365 17 0 0
v_cancel_customer_reserve 1196 1196 0 0.00 0 3991 21 0 0
v_cancel_payment_to_supplier 690 690 0 0.00 0 12066 34 0 0
v_cancel_supplier_invoice 720 686 34 4.72 0 12042 61 0 34
v_cancel_supplier_order 814 814 0 0.00 0 1987 12 0 0
v_cancel_write_off 1704 1704 0 0.00 0 2487 24 0 0
v_max_id_clo_ord 5004 5004 0 0.00 0 1921 5 0 0
v_max_id_clo_res 885 885 0 0.00 0 1417 8 0 0
v_max_id_ord_sup 4978 4978 0 0.00 0 5166 6 0 0
v_max_non_paid_invoice 1206 1206 0 0.00 0 482 8 0 0
v_max_non_paid_realizn 3299 3299 0 0.00 0 9706 15 0 0
v_min_id_clo_ord 5004 5004 0 0.00 0 3501 11 0 0
v_min_id_clo_res 885 885 0 0.00 0 412 6 0 0
v_min_id_ord_sup 4978 4978 0 0.00 0 8677 17 0 0
v_min_non_paid_invoice 5296 5296 0 0.00 0 6878 16 0 0
v_min_non_paid_realizn 6483 6483 0 0.00 0 7213 23 0 0
v_our_firm 9458 9458 0 0.00 0 60 4 0 0
v_reserve_write_off 18415 18415 0 0.00 0 12063 13 0 0
x_lock_depdocs_on_canc_invoice 11210 11210 0 0.00 0 30304 134 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 7782 328 0 0
x_make_qstorno_1000_1200 5006 5006 0 0.00 2 66393 1386 0 0
x_make_qstorno_1200_2000 4984 4984 0 0.00 4 89152 3137 0 0
x_make_qstorno_2100_3300 427856 427856 0 0.00 0 80300 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-03-2023 04:40:24 to 19-03-2023 04:40:28.

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:30 10 8192000000 8808472416 8823627776 12001216 0.146 5 3 4 0 12265008 151648 112144
02:08:32 25601 8192000000 9725753568 10023370752 803908288 8.936 100 81 162 0 830956864 7268336 19780240
02:10:15 18994 8192000000 9768447216 10075398144 842578048 9.326 100 89 179 0 876050768 12903040 20569680
02:11:36 15085 8192000000 9798264304 10114420736 863570960 9.536 100 87 175 0 903374976 19608592 20195424
02:12:57 7230 8192000000 9871873648 10204725248 911803712 10.015 100 81 156 0 981020096 52255248 16961136
02:14:07 2477 8192000000 9945809184 10286592000 965583424 10.544 100 96 188 0 1059820048 74477504 19759120
02:15:12 2069 8192000000 9969701136 10322046976 993363824 10.814 100 95 185 1 1089430368 76648640 19417904
02:16:17 1263 8192000000 9998218048 10361257984 1020602816 11.078 100 97 188 1 1123514592 84488880 18422896
02:17:21 1181 8192000000 10025393952 10393858048 1043294064 11.296 100 95 190 1 1141540608 80516160 17730384
02:18:23 1214 8192000000 10025699568 10405462016 1054284640 11.402 100 92 178 1 1148295856 76507920 17503296
02:19:25 1005 8192000000 10053770272 10431746048 1073916800 11.589 100 95 186 0 1177573824 84909840 18747184
02:20:28 826 8192000000 10051255744 10437926912 1078887104 11.637 100 87 166 1 1175351808 79778112 16686592
02:21:29 1347 8192000000 10053523136 10445828096 1087045152 11.715 100 73 140 0 1170987536 69996544 13945840
02:22:32 2660 8192000000 10050635312 10443464704 1089778944 11.741 100 67 126 0 1171462848 68933520 12750384
02:23:37 1244 8192000000 10052144640 10438209536 1093996768 11.781 100 98 187 2 1174538352 61419056 19122528
02:24:39 1160 8192000000 10061359216 10446532608 1102286464 11.859 100 97 187 1 1182639008 62563808 17788736
02:25:41 1221 8192000000 10064900352 10452402176 1097920672 11.818 100 95 186 1 1185700624 68784928 18995024
02:26:43 1071 8192000000 10050686592 10446659584 1098040560 11.819 100 85 163 0 1175753904 59555072 18158272
02:27:45 3611 8192000000 10074928112 10471874560 1095730544 11.797 100 74 144 1 1198175552 88270528 14174480
02:28:50 1725 8192000000 10069846384 10463457280 1101983600 11.856 100 75 146 1 1193018192 76083232 14951360
02:29:53 1563 8192000000 10007938784 10386534400 1055704464 11.415 99 87 168 1 1128807184 56491376 16611344
02:30:55 1114 8192000000 10029660896 10408431616 1078427680 11.632 100 95 181 2 1151119392 54026384 18665328
02:31:57 1176 8192000000 9989607712 10363035648 1038566688 11.251 100 95 173 2 1113967952 56641120 18760144
02:33:00 1402 8192000000 9985457568 10358677504 1037098992 11.237 99 98 182 1 1109110944 53363664 18648288
02:34:02 5782 8192000000 9987693136 10363027456 1038293552 11.248 100 74 140 1 1109533248 58218384 13021312
02:35:08 9586 8192000000 9990219952 10369576960 1038288672 11.248 99 58 107 1 1102840480 53637440 10914368
02:36:19 2501 8192000000 9982907184 10370117632 1047640960 11.338 100 66 118 1 1110209888 49866800 12702128
02:37:22 2915 8192000000 9989820544 10360360960 1031800336 11.186 99 83 158 2 1101534752 54872208 14862208
02:38:33 8971 8192000000 10009205568 10388873216 1048659440 11.348 99 79 148 3 1130448448 65276784 16512224
02:39:47 1028 8192000000 10048645536 10431885312 1080459680 11.652 100 91 174 4 1169803824 69629152 19714992
02:40:51 8041 8192000000 10044347376 10439204864 1090503488 11.747 100 58 114 0 1156287888 54096768 11687632
02:42:05 6778 8192000000 10104423584 10501804032 1107671632 11.910 100 71 134 2 1225620320 103598064 14350624
02:43:16 6041 8192000000 10137497968 10542514176 1122800400 12.053 100 74 144 0 1252855392 114290128 15764864
02:44:29 2200 8192000000 10160199152 10560843776 1135550576 12.174 100 80 150 0 1273319632 121380128 16388928
02:45:34 1450 8192000000 10196981328 10599743488 1150203072 12.311 100 93 173 0 1314115296 144475472 19436752
02:46:39 1480 8192000000 10219841280 10624749568 1169326464 12.491 100 83 156 1 1336225664 149215024 17684176
02:47:46 2701 8192000000 10267318128 10676604928 1169939136 12.496 100 88 172 2 1379615824 189833888 19842800
02:48:56 1380 8192000000 10336125792 10737627136 1186749856 12.653 100 93 179 0 1439778608 234037552 18991200
02:49:59 3456 8192000000 10363821520 10779291648 1195045312 12.730 100 67 131 0 1474125200 263642944 15436944
02:51:09 1479 8192000000 10398701616 10810195968 1201141744 12.787 100 94 182 1 1513581216 292005120 20434352
02:52:14 2226 8192000000 10444972048 10858840064 1201233488 12.788 100 91 179 0 1550147728 326987552 21926688
02:53:20 823 8192000000 10458730832 10874048512 1211008960 12.878 100 97 185 3 1569190304 335110464 23070880
02:54:25 2224 8192000000 10510674464 10917076992 1203795264 12.812 100 93 181 0 1612714096 387266688 21652144
02:55:30 743 8192000000 10527976928 10982830080 1208142944 12.852 100 96 189 0 1629273280 398827472 22302864
02:56:32 1832 8192000000 10582544352 11023589376 1213558752 12.902 100 98 195 0 1688350368 451088320 23703296
02:57:36 13431 8192000000 10494227824 10956341248 1184526944 12.632 100 46 89 0 1615172976 418934096 11711936
02:58:52 3012 8192000000 10513623904 10930069504 1183556160 12.623 100 71 132 3 1613523616 413497936 16469520
02:59:58 8334 8192000000 10496700208 10934284288 1187299728 12.658 100 55 106 1 1598171552 398154192 12717632
03:01:13 20731 8192000000 10529246768 10991009792 1178374944 12.575 100 44 88 0 1647518640 457318352 11825344
03:02:37 17962 8192000000 10517624496 10956660736 1157442064 12.379 100 38 74 0 1628788640 460924672 10421904
03:04:10 2346 8192000000 10467524976 11021389824 1156597568 12.371 100 74 141 0 1570171792 395623776 17950448
03:05:13 14909 8192000000 10382366304 10879053824 1176407264 12.557 100 52 104 0 1485071376 295862976 12801136
03:06:37 27583 8192000000 10366520128 10814902272 1162594800 12.428 100 33 66 0 1451922736 281055744 8272192
03:08:17 43125 8192000000 10368903136 10836545536 1169244688 12.490 100 23 46 0 1473127616 297679344 6203584
03:10:36 59475 8192000000 10107759008 10609696768 1132922736 12.149 99 11 21 0 1253141344 118144864 2073744
03:13:48 41040 8192000000 10115357280 10557947904 1147520768 12.286 99 44 85 2 1204930544 49458944 7950832
03:15:32 77154 8192000000 10151086144 10649325568 1129555888 12.117 99 16 32 0 1268724448 135180528 3988032
03:17:53 59191 8192000000 10081823584 10565267456 1099704704 11.835 97 24 52 0 1168208656 63201824 5302128
03:19:58 74480 8192000000 10087112560 10578391040 1136180928 12.180 100 13 26 0 1212095616 72974816 2939872
03:22:22 51068 8192000000 10086174160 10523070464 1126989744 12.093 99 14 28 0 1166833840 36701328 3142768
03:24:43 74388 8192000000 10106541712 10564521984 1116009424 11.989 97 20 38 0 1199275616 78161232 5104960
03:27:10 79185 8192000000 10086042816 10534682624 1111222944 11.944 98 34 63 2 1171184208 53092416 6868848
03:29:29 25106 8192000000 10081489088 10540670976 1135219856 12.171 100 22 40 1 1184413504 44956352 4237296
03:30:55 27846 8192000000 10149253952 10580533248 1135224544 12.171 99 64 126 0 1231396544 82605344 13566656
03:32:24 88989 8192000000 10085800896 10597584896 1095604064 11.796 96 11 22 0 1189015936 91192880 2218992
03:35:32 88587 8192000000 10061324960 10532888576 1124034592 12.065 100 17 34 0 1165633936 37432720 4166624
03:38:02 121888 8192000000 10091358848 10547331072 1101454752 11.851 96 13 26 0 1140437776 36832432 2150592
03:41:04 31358 8192000000 10139964960 10591072256 1128089936 12.103 98 34 65 0 1224694896 89910496 6694464
03:42:37 25777 8192000000 10184993856 10625757184 1138639424 12.203 99 36 72 0 1265828096 119157232 8031440
03:44:03 45946 8192000000 10139703056 10603778048 1115935312 11.989 97 21 42 0 1248651792 127661008 5055472
03:45:53 27593 8192000000 10098014416 10544062464 1125725008 12.081 99 33 64 0 1226167280 93296656 7145616
03:47:31 45237 8192000000 10099812512 10559791104 1131836320 12.139 99 32 62 0 1192076528 53983984 6256224
03:49:24 28806 8192000000 10092980400 10516742144 1144312080 12.256 100 53 99 1 1182709344 28474112 9923152
03:50:55 74760 8192000000 10158117232 10622328832 1147952080 12.290 100 33 66 0 1255493664 100760752 6780832
03:53:22 66993 8192000000 10046868800 10509103104 1127458640 12.097 99 26 51 0 1196989664 63892080 5638944
03:55:45 11854 8192000000 10122172144 10559377408 1136219840 12.180 100 60 120 0 1217632288 69532320 11880128
03:57:00 57501 8192000000 10073790736 10563362816 1121498336 12.041 99 10 21 0 1182839696 59243120 2098240
03:58:58 100079 8192000000 10102674240 10562617344 1125982800 12.083 99 17 34 0 1211716832 81805376 3928656
04:02:49 47387 8192000000 10092020752 10585649152 1099443792 11.832 98 22 42 0 1211314720 107351616 4519312
04:05:19 33373 8192000000 10144036720 10580140032 1127743632 12.100 100 49 94 1 1235700032 97105440 10850960
04:06:56 60638 8192000000 10055573824 10503204864 1079475584 11.642 96 19 37 0 1184988096 101454096 4058416
04:09:19 37932 8192000000 10114450768 10552647680 1133019168 12.150 100 33 66 0 1206064352 66297840 6747344
04:10:59 47343 8192000000 10135007920 10562277376 1124182144 12.066 99 40 78 0 1235307536 102010272 9115120
04:12:50 67717 8192000000 10104839968 10544209920 1122829024 12.054 99 9 18 0 1175157536 50569280 1759232
04:14:58 42151 8192000000 10089040832 10560962560 1120626432 12.033 98 31 63 0 1200495600 73838944 6030224
04:16:41 58994 8192000000 10054397984 10488729600 1108108848 11.915 98 48 85 1 1154324448 37775808 8439792
04:18:51 27098 8192000000 10098212960 10545119232 1110113904 11.933 98 45 84 1 1174442144 55549648 8778592
04:20:26 46840 8192000000 10089025328 10562244608 1114592320 11.976 98 7 14 0 1184398928 68220160 1586448
04:22:27 67878 8192000000 10093664576 10589188096 1113132160 11.962 98 16 32 0 1204823104 87861840 3829104
04:24:42 85623 8192000000 10049865184 10523443200 1092833792 11.770 97 9 18 0 1134066992 39744992 1488208
04:27:14 52974 8192000000 10064962944 10528497664 1100691840 11.844 98 23 46 0 1167186336 61082448 5412048
04:29:10 52576 8192000000 10045483072 10491166720 1115191536 11.982 99 19 36 0 1156261232 37363872 3705824
04:31:03 76143 8192000000 10062946576 10553196544 1102202656 11.859 97 25 50 1 1180465264 73630352 4632256
04:33:21 10775 8192000000 10066149344 10496532480 1097800960 11.817 98 44 85 0 1148077776 41689424 8587392
04:34:35 28460 8192000000 10110597568 10557427712 1087824960 11.722 97 44 88 2 1183582352 87140576 8616816
04:36:06 42226 8192000000 10096507840 10527449088 1110123440 11.934 99 32 60 1 1171965472 55836544 6005488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-03-2023 04:40:30 to 19-03-2023 04:40:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 192 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-03-2023 04:40:47 to 19-03-2023 04:40:49.

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          206590
MON$OLDEST_ACTIVE               210081
MON$OLDEST_SNAPSHOT             210081
MON$NEXT_TRANSACTION            210081
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                       2069088
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2911 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2911 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2911 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2911 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Sun Mar 19 04:37:50 2023
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sun Mar 19 04:38:18 2023
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sun Mar 19 04:38:34 2023
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
--- end of diff output ---

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

19.03.23 04:40:50. 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-19 04:40:53 No severe PSQL-related problems occured
19.03.23 04:40:54. End of report.