Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.01.2023 07:37
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 15123164 kB
MemAvailable 30390140 kB
Buffers 0 kB
Cached 16557204 kB
SwapCached 1640 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-01-23 07:37:23
DTS_END                         2023-01-23 07:37:23.5350
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50890
TRN_ID                          212562
ATT_ID                          600
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: 8799.36 28003 1055923
customer order: creation 156.85 456 18822
customer order: refuse 12.68 416 1522
order to supplier: creation 43.25 1362 5190
order to supplier: removal 3.13 163831 375
invoice (draft): creation 43.51 3335 5221
invoice (draft): removal 49.92 1021 5990
invoice accept: apply 39.47 26861 4736
invoice accept: cancel 44.90 1253 5388
customer reserve: creation 3714.43 254 445732
customer reserve: removal 4410.98 89 529318
realization accept: apply 77.09 277 9251
realization accept: cancel 82.56 151 9907
payment from customer: creation 55.78 194 6693
payment from customer: removal 5.29 133 635
payment to supplier: creation 44.63 201 5355
payment to supplier: removal 3.38 112 405
service: total inventory turnovers 3.97 3870 476
service: total monetary turnovers 6.70 384 804
service: aggregate perf. data 0.83 16860 99
service: refresh index statistics 0.03 339005 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-01-2023 07:38:00 to 23-01-2023 07:38:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5993
TEST_TIME 2 7334
TEST_TIME 3 7056
TEST_TIME 4 6929
TEST_TIME 5 6855
TEST_TIME 6 6763
TEST_TIME 7 6422
TEST_TIME 8 6649
TEST_TIME 9 6772
TEST_TIME 10 6869
TEST_TIME 11 6921
TEST_TIME 12 7165
TEST_TIME 13 7321
TEST_TIME 14 7511
TEST_TIME 15 7641
TEST_TIME 16 7645
TEST_TIME 17 7597
TEST_TIME 18 7937
TEST_TIME 19 8312
TEST_TIME 20 8548
TEST_TIME 21 8718
TEST_TIME 22 8607
TEST_TIME 23 8618
TEST_TIME 24 8686
TEST_TIME 25 9073
TEST_TIME 26 9196
TEST_TIME 27 10294
TEST_TIME 28 10192
TEST_TIME 29 10615
TEST_TIME 30 11156
TEST_TIME 31 11330
TEST_TIME 32 11406
TEST_TIME 33 11350
TEST_TIME 34 11316
TEST_TIME 35 11272
TEST_TIME 36 10978
TEST_TIME 37 10881
TEST_TIME 38 11005
TEST_TIME 39 10768
TEST_TIME 40 10652
TEST_TIME 41 10465
TEST_TIME 42 10612
TEST_TIME 43 10448
TEST_TIME 44 10461
TEST_TIME 45 10384
TEST_TIME 46 10240
TEST_TIME 47 10119
TEST_TIME 48 9986
TEST_TIME 49 9927
TEST_TIME 50 9898
TEST_TIME 51 9903
TEST_TIME 52 9802
TEST_TIME 53 9693
TEST_TIME 54 9643
TEST_TIME 55 9546
TEST_TIME 56 9473
TEST_TIME 57 9456
TEST_TIME 58 9445
TEST_TIME 59 9354
TEST_TIME 60 9366
TEST_TIME 61 9322
TEST_TIME 62 9208
TEST_TIME 63 9154
TEST_TIME 64 9100
TEST_TIME 65 9246
TEST_TIME 66 9285
TEST_TIME 67 9245
TEST_TIME 68 9268
TEST_TIME 69 9278
TEST_TIME 70 9315
TEST_TIME 71 9297
TEST_TIME 72 9347
TEST_TIME 73 9320
TEST_TIME 74 9412
TEST_TIME 75 9397
TEST_TIME 76 9385
TEST_TIME 77 9401
TEST_TIME 78 9403
TEST_TIME 79 9287
TEST_TIME 80 9188
TEST_TIME 81 9240
TEST_TIME 82 9215
TEST_TIME 83 9158
TEST_TIME 84 9165
TEST_TIME 85 9140
TEST_TIME 86 9064
TEST_TIME 87 8994
TEST_TIME 88 8982
TEST_TIME 89 8905
TEST_TIME 90 8944
TEST_TIME 91 8966
TEST_TIME 92 8999
TEST_TIME 93 9000
TEST_TIME 94 8983
TEST_TIME 95 9016
TEST_TIME 96 8976
TEST_TIME 97 8982
TEST_TIME 98 9058
TEST_TIME 99 9064
TEST_TIME 100 9098
TEST_TIME 101 9113
TEST_TIME 102 9081
TEST_TIME 103 9030
TEST_TIME 104 8955
TEST_TIME 105 9006
TEST_TIME 106 8956
TEST_TIME 107 8911
TEST_TIME 108 8875
TEST_TIME 109 8846
TEST_TIME 110 8832
TEST_TIME 111 8882
TEST_TIME 112 8855
TEST_TIME 113 8850
TEST_TIME 114 8834
TEST_TIME 115 8863
TEST_TIME 116 8847
TEST_TIME 117 8846
TEST_TIME 118 8860
TEST_TIME 119 8867
TEST_TIME 120 8821

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-01-2023 07:38:04 to 23-01-2023 07:38:08.

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 1464942 1464942 0 0.00 0 28190 13 0 0
doc_list_biud 1464909 1464909 0 0.00 0 92148 38 0 0
sp_add_invoice_to_stock 4788 4736 52 1.08 71 243517 26862 0 52
sp_cancel_adding_invoice 5388 5388 0 0.00 6 110788 1254 0 0
sp_cancel_client_order 1522 1522 0 0.00 6 10127 417 0 0
sp_cancel_customer_reserve 529318 529318 0 0.00 1 92150 89 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 3 7083 134 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 4 3005 112 0 0
sp_cancel_supplier_invoice 5995 5990 5 0.08 3 62388 1021 0 5
sp_cancel_supplier_order 375 375 0 0.00 7 1433545 163832 0 0
sp_cancel_write_off 9907 9907 0 0.00 2 23476 152 0 0
sp_client_order 18822 18822 0 0.00 6 34943 457 0 0
sp_customer_reserve 445732 445732 0 0.00 0 132420 254 0 0
sp_fill_shopping_cart 29231 29231 0 0.00 1 38952 83 0 0
sp_fill_shopping_cart_clo_res 445671 445671 0 0.00 0 10820 18 0 0
sp_get_clo_for_invoice 4671 4671 0 0.00 0 24514 318 0 0
sp_kill_cost_storno 16335 16335 0 0.00 0 23471 75 0 0
sp_kill_qstorno_ret_qs2qd 1074908 1074908 0 0.00 0 92144 39 0 0
sp_kill_qty_storno 566422 566422 0 0.00 0 92147 92 0 0
sp_lock_selected_doc 19157 19157 0 0.00 0 7993 7 0 0
sp_make_cost_storno 25970 25970 0 0.00 0 28181 83 0 0
sp_multiply_rows_for_pdistr 25970 25970 0 0.00 0 18942 39 0 0
sp_multiply_rows_for_qdistr 18822 18822 0 0.00 0 23740 162 0 0
sp_pay_from_customer 6693 6693 0 0.00 5 18269 194 0 0
sp_pay_to_supplier 5355 5355 0 0.00 6 25463 202 0 0
sp_payment_common 12047 12047 0 0.00 2 9999 46 0 0
sp_qd_handle_on_cancel_clo 1522 1522 0 0.00 0 10117 336 0 0
sp_qd_handle_on_invoice_upd_sts 10059 10059 0 0.00 0 29914 521 0 0
sp_qd_handle_on_reserve_upd_sts 9251 9251 0 0.00 0 23373 71 0 0
sp_reserve_write_off 9251 9251 0 0.00 9 23399 277 0 0
sp_supplier_invoice 5221 5221 0 0.00 9 95218 3335 0 0
sp_supplier_order 5190 5190 0 0.00 7 39964 1362 0 0
srv_aggregate_perf_data 99 99 0 0.00 2560 212571 16860 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 766 209061 41901 0 0
srv_find_qd_qs_mism 1009155 1009155 0 0.00 0 20097 10 0 0
srv_make_invnt_saldo 476 476 0 0.00 57 88116 3870 0 0
srv_make_money_saldo 804 804 0 0.00 1 15624 384 0 0
srv_recalc_idx_stat 4 4 0 0.00 174487 558965 339006 0 0
t$perf-abend:sp_add_invoice_to_stock 52 52 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4736 4736 0 0.00 2 9883 874 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 1 5653 508 0 0
t$perf-norm:sp_cancel_client_order 1522 1522 0 0.00 0 4643 30 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 5376 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 2045 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 575 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 651 28 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 109583 7069 0 0
t$perf-norm:sp_cancel_write_off 894 894 0 0.00 0 904 15 0 0
t$perf-norm:sp_client_order 18822 18822 0 0.00 0 8680 31 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 1827 13 0 0
t$perf-norm:sp_pay_from_customer 6693 6693 0 0.00 0 5114 17 0 0
t$perf-norm:sp_pay_to_supplier 5355 5355 0 0.00 0 5115 20 0 0
t$perf-norm:sp_reserve_write_off 9251 9251 0 0.00 0 8134 22 0 0
t$perf-norm:sp_supplier_invoice 5221 5221 0 0.00 0 7611 28 0 0
t$perf-norm:sp_supplier_order 5190 5190 0 0.00 0 5577 26 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 1565 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 7904 138 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 490 11 0 0
t$perf-norm:srv_make_money_saldo 804 804 0 0.00 0 633 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 212 72 0 0
v_add_invoice_to_stock 9446 9342 104 1.10 0 8335 24 0 104
v_all_customers 34770 34770 0 0.00 0 62 3 0 0
v_all_suppliers 28696 28696 0 0.00 0 66 4 0 0
v_all_wares 37642 37642 0 0.00 0 75 4 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 1381 18 0 0
v_cancel_client_order 3044 3044 0 0.00 0 8384 16 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 7002 18 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 9056 19 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 1344 13 0 0
v_cancel_supplier_invoice 792 782 10 1.26 0 890 21 0 10
v_cancel_supplier_order 750 750 0 0.00 0 10643 28 0 0
v_cancel_write_off 1788 1788 0 0.00 0 4396 28 0 0
v_max_id_clo_ord 5190 5190 0 0.00 0 398 5 0 0
v_max_id_clo_res 951 951 0 0.00 0 6505 23 0 0
v_max_id_ord_sup 5220 5220 0 0.00 0 4699 8 0 0
v_max_non_paid_invoice 1416 1416 0 0.00 0 520 8 0 0
v_max_non_paid_realizn 3375 3375 0 0.00 0 3276 9 0 0
v_min_id_clo_ord 5190 5190 0 0.00 0 3242 9 0 0
v_min_id_clo_res 951 951 0 0.00 0 1272 10 0 0
v_min_id_ord_sup 5220 5220 0 0.00 0 8884 12 0 0
v_min_non_paid_invoice 5354 5354 0 0.00 0 7203 20 0 0
v_min_non_paid_realizn 6693 6693 0 0.00 0 8087 20 0 0
v_our_firm 9508 9508 0 0.00 0 64 4 0 0
v_reserve_write_off 18502 18502 0 0.00 0 16050 11 0 0
x_lock_depdocs_on_canc_invoice 10987 10987 0 0.00 0 42421 139 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 11265 415 0 0
x_make_qstorno_1000_1200 5190 5190 0 0.00 3 39948 1207 0 0
x_make_qstorno_1200_2000 5221 5221 0 0.00 4 93693 3096 0 0
x_make_qstorno_2100_3300 445730 445730 0 0.00 0 132354 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-01-2023 07:38:09 to 23-01-2023 07:38:13.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:27 900 8192000000 8930744912 8987287552 28189680 0.342 6 5 10 0 28912224 377696 344848
05:08:30 35070 8192000000 9727099312 10029924352 805053440 8.947 100 86 172 0 833902864 7945440 20903984
05:10:09 25232 8192000000 9776317616 10084839424 843311792 9.333 100 91 182 0 879473232 15001744 21159696
05:11:36 15272 8192000000 9807381568 10129362944 874489888 9.645 100 82 165 0 920820752 28050832 18280032
05:13:03 10093 8192000000 9881427568 10215018496 924921360 10.145 100 83 164 1 1001402304 58895728 17585216
05:14:15 5045 8192000000 9922202976 10268012544 961395920 10.503 100 88 174 0 1040531328 61282000 17853408
05:15:24 2399 8192000000 9977333968 10329464832 998608096 10.865 100 93 180 0 1085856176 69433456 17814624
05:16:28 1239 8192000000 10010395600 10373816320 1022324240 11.094 100 92 181 0 1131535936 90911440 18300256
05:17:32 833 8192000000 10011521216 10384031744 1045226784 11.315 100 94 180 0 1133582704 70806224 17549696
05:18:33 956 8192000000 10062449584 10441146368 1060787776 11.464 100 91 172 1 1184892832 106322016 17783040
05:19:36 1023 8192000000 10053677248 10430488576 1068318704 11.536 100 96 184 0 1173763264 87096880 18347680
05:20:38 1058 8192000000 10013093920 10395123712 1073021744 11.581 100 88 171 1 1133562080 41804480 18735856
05:21:40 1714 8192000000 10040806640 10439245824 1077651696 11.625 100 68 125 0 1160841936 71014176 12176064
05:22:42 1101 8192000000 10018770736 10406776832 1079470208 11.642 100 80 153 6 1140005456 45503536 15031712
05:23:44 1904 8192000000 10042398880 10440458240 1085741536 11.702 100 73 136 2 1156819488 58130480 12947472
05:24:47 1012 8192000000 10056749360 10446610432 1093394624 11.775 100 88 165 0 1174471296 64386960 16689712
05:25:49 968 8192000000 10044268688 10435121152 1096361520 11.803 100 89 170 3 1166882336 53142480 17378336
05:26:51 2316 8192000000 10023683760 10413682688 1066711168 11.521 97 77 146 3 1146916480 64404880 15800432
05:27:54 4051 8192000000 10030406032 10419060736 1095385168 11.794 100 75 134 3 1154209200 45678864 13145168
05:28:58 2468 8192000000 10027588112 10426376192 1075124208 11.601 98 78 145 2 1148615936 57789488 15702240
05:30:02 1134 8192000000 10031808608 10423934976 1101288064 11.850 100 85 154 2 1154472112 35690336 17493712
05:31:03 1052 8192000000 10076367936 10466426880 1086913152 11.713 100 88 169 4 1201608384 97417088 17278144
05:32:05 972 8192000000 10054824480 10436104192 1067376688 11.527 100 95 175 1 1172937200 86045392 19515120
05:33:09 1159 8192000000 10005979648 10383781888 1063287136 11.488 100 96 177 3 1126970288 45287856 18395296
05:34:11 989 8192000000 10014961952 10395443200 1052595072 11.386 100 86 164 2 1137662864 66510608 18557184
05:35:12 4930 8192000000 9999000736 10385932288 1026251744 11.132 99 62 119 1 1111980560 73234352 12494464
05:36:18 887 8192000000 9971123664 10342469632 1015968272 11.033 100 96 172 1 1093166768 56990480 20208016
05:37:20 1036 8192000000 9963396704 10329649152 1024120448 11.112 100 92 176 0 1081452256 38694672 18637136
05:38:45 6894 8192000000 10013928208 10390372352 1040185488 11.266 99 69 136 1 1140846176 86304432 14356256
05:39:56 988 8192000000 10030129056 10407190528 1070107200 11.553 100 97 183 2 1147904816 58411488 19386128
05:41:00 5892 8192000000 10065623712 10453897216 1085766624 11.702 100 79 145 1 1184235136 83417920 15050592
05:42:12 860 8192000000 10124199488 10507436032 1101760896 11.854 100 94 184 0 1241992768 119797744 20434128
05:43:19 8926 8192000000 10152838368 10549231616 1116195376 11.991 100 69 136 0 1261761328 130572016 14993936
05:44:33 10753 8192000000 10194734560 10593071104 1121761536 12.044 100 70 138 1 1306661728 169705168 15195024
05:45:49 2987 8192000000 10213327472 10614464512 1136965744 12.187 100 76 146 3 1332245232 178485408 16794080
05:46:58 1871 8192000000 10243620912 10643247104 1143853824 12.252 100 82 159 1 1355290112 194396640 17039648
05:48:03 2511 8192000000 10303381488 10706771968 1156613136 12.372 100 86 172 0 1416426608 241153488 18659984
05:49:10 967 8192000000 10337499632 10747895808 1164398784 12.444 100 91 169 0 1449574208 266796944 18378480
05:50:16 936 8192000000 10357868544 10760654848 1180347056 12.593 100 97 186 0 1464223728 263272624 20604048
05:51:19 756 8192000000 10352176304 10753519616 1168269264 12.481 99 98 186 1 1455625072 267119920 20235888
05:52:22 3094 8192000000 10420884992 10833059840 1187480016 12.660 100 91 175 0 1525502608 318497408 19525184
05:53:28 820 8192000000 10419936336 10822279168 1179039968 12.581 99 96 182 1 1525808768 326545536 20223264
05:54:32 1556 8192000000 10486077136 10914025472 1193709648 12.718 100 98 197 1 1589415792 372609088 23097056
05:55:38 1585 8192000000 10466252928 10936487936 1167235136 12.471 98 88 176 0 1563375584 373900336 22240112
05:56:42 769 8192000000 10479510256 10917183488 1205838688 12.831 100 93 184 1 1584775680 357487840 21449152
05:57:45 4576 8192000000 10507319664 10932215808 1189089696 12.675 100 70 135 0 1610822752 405256976 16476080
05:58:51 6499 8192000000 10538332528 10969329664 1186234176 12.648 99 59 117 0 1639681712 439350304 14097232
06:00:03 12041 8192000000 10581394368 11018240000 1197460640 12.753 100 45 90 0 1686266192 476689312 12116240
06:01:16 9098 8192000000 10590311856 11053342720 1188990880 12.674 100 44 87 0 1714793504 514420928 11381696
06:02:30 22035 8192000000 10553631968 11024748544 1174109376 12.535 100 38 76 0 1655297360 470484384 10703600
06:03:55 30291 8192000000 10391174944 10973278208 1133013216 12.150 97 32 63 0 1527350464 385963728 8373520
06:05:53 26402 8192000000 10299209376 10830069760 1131578800 12.136 99 18 37 0 1418279904 281784192 4916912
06:07:21 3931 8192000000 10193655648 10641645568 1126202832 12.086 100 56 112 1 1302957360 164463136 12291392
06:08:28 20122 8192000000 10130355488 10554613760 1122225120 12.048 100 38 67 2 1228487568 97591024 8671424
06:09:50 39599 8192000000 10177739952 10597535744 1134046544 12.159 100 36 64 1 1260218368 118732464 7439360
06:11:33 39946 8192000000 10111022992 10595397632 1098932752 11.828 98 16 30 0 1230357904 128346944 3078208
06:13:14 35685 8192000000 10129123056 10546593792 1115640144 11.986 100 83 161 4 1223406720 89899840 17866736
06:14:52 58735 8192000000 10088651664 10601418752 1112941424 11.960 99 33 65 0 1237352480 116667616 7743440
06:16:51 52705 8192000000 10137224608 10587078656 1115030592 11.980 98 33 63 0 1209987008 87451952 7504464
06:18:44 209060 8192000000 10070752080 10557083648 1099806992 11.836 97 26 47 0 1204598768 99941344 4850432
06:23:26 13545 8192000000 10091823728 10528833536 1128208000 12.104 99 25 48 0 1177372720 43723376 5441344
06:25:14 30921 8192000000 10106567968 10544549888 1138981488 12.206 99 39 75 1 1206748880 59603360 8164032
06:27:12 75738 8192000000 10087955360 10559623168 1134141552 12.160 99 17 34 0 1197991792 60151888 3698352
06:29:36 83579 8192000000 10126396624 10593062912 1145532608 12.268 99 17 34 0 1206369088 56996160 3840320
06:32:10 81680 8192000000 10114895216 10591936512 1140617200 12.221 99 24 48 0 1212894832 66530400 5747232
06:35:03 106647 8192000000 10090143456 10538831872 1087227520 11.716 95 30 55 0 1164959632 72508240 5223872
06:37:56 66264 8192000000 10101708832 10578350080 1136431472 12.182 99 13 26 0 1197169648 57929936 2808240
06:40:43 85003 8192000000 10179590304 10659344384 1132385712 12.144 99 9 18 0 1256740112 122142976 2211424
06:43:17 51723 8192000000 10077647920 10536955904 1101129440 11.848 96 20 39 0 1143792400 38397280 4265680
06:45:11 99265 8192000000 10101236832 10601525248 1122317344 12.049 98 24 48 0 1188929136 61661504 4950288
06:48:01 46064 8192000000 10128716592 10583863296 1119915088 12.026 98 34 68 0 1207958992 80857344 7186560
06:49:51 49685 8192000000 10157721472 10612891648 1150466864 12.314 100 37 73 0 1231747824 74459456 6821504
06:51:43 54347 8192000000 10112586016 10540265472 1146137456 12.273 99 43 85 0 1207484880 52499856 8847568
06:53:39 30430 8192000000 10148551472 10601000960 1144652592 12.259 99 45 90 0 1247196384 93929840 8613952
06:55:10 107644 8192000000 10115271296 10552741888 1139275792 12.209 99 13 24 0 1224548832 82361824 2911216
06:58:24 61934 8192000000 10089779168 10568749056 1135592864 12.174 99 16 32 0 1185593888 46818080 3182944
07:00:33 61583 8192000000 10060080704 10535530496 1097347232 11.812 96 24 47 0 1155953680 54018816 4587632
07:02:48 68270 8192000000 10080660592 10539810816 1126620432 12.089 99 12 24 0 1164810208 35571040 2618736
07:05:17 66571 8192000000 10116785712 10573750272 1131380064 12.134 99 16 32 0 1221097344 86040496 3676784
07:07:46 81159 8192000000 10045025808 10559266816 1090062928 11.743 97 10 20 0 1176966912 84200256 2703728
07:10:11 40247 8192000000 10060484240 10489536512 1075211152 11.602 96 25 51 0 1117446240 36996256 5238832
07:12:02 38872 8192000000 10049747072 10514419712 1057834848 11.436 95 31 62 0 1115051904 50418640 6798416
07:13:49 25043 8192000000 10118908512 10545709056 1112423136 11.955 99 43 84 1 1203649408 82576880 8649392
07:15:17 121833 8192000000 10110151120 10545897472 1100334256 11.841 97 27 54 0 1189169680 82921632 5913792
07:18:20 53819 8192000000 10093647472 10515951616 1122080928 12.047 99 49 94 0 1175505248 44293744 9130576
07:20:18 83132 8192000000 10114405120 10544226304 1127938160 12.102 100 40 81 0 1213702368 77868176 7896032
07:22:43 43483 8192000000 10054623056 10526633984 1126729776 12.091 100 4 8 0 1166265472 38734432 801264
07:25:50 41968 8192000000 10092813392 10535075840 1147066128 12.282 100 24 46 1 1197056880 45618208 4372544
07:27:38 78852 8192000000 10062550288 10555785216 1127309392 12.096 99 13 26 0 1175651072 45384704 2956976
07:29:57 16066 8192000000 10087146032 10505986048 1128504320 12.107 99 65 110 4 1169147920 31454352 9189248
07:31:13 155046 8192000000 10084200144 10532663296 1072490672 11.576 94 23 46 0 1121408240 44376464 4541104
07:34:49 55849 8192000000 10110342736 10555748352 1129596912 12.118 100 30 60 0 1199096560 63500112 5999536
07:36:47 24154 8192000000 10092412224 10533158912 1135759840 12.176 100 44 88 0 1192119264 47591744 8767680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 23-01-2023 07:38:15 to 23-01-2023 07:38:31.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-01-2023 07:38:32 to 23-01-2023 07:38:34.

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          211073
MON$OLDEST_ACTIVE               212940
MON$OLDEST_SNAPSHOT             212940
MON$NEXT_TRANSACTION            212940
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                       2058272
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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 ---
:1032: 
:1033: mscs-test-161.budzdorov.ru    Mon Jan 23 07:37:26 2023
:1034:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1035: 
--- end of diff output ---

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

23.01.23 07:38:35. 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-01-23 07:38:38 No severe PSQL-related problems occured
23.01.23 07:38:39. End of report.