Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.12.2021 10: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 16250852 kB
MemAvailable 31443608 kB
Buffers 0 kB
Cached 15459668 kB
SwapCached 444 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.1
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 2021-12-22 10:37:10
DTS_END                         2021-12-22 10:37:10.6440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42594
TRN_ID                          239638
ATT_ID                          627
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: 9343.62 22936 1121234
customer order: creation 178.41 347 21409
customer order: refuse 15.06 367 1807
order to supplier: creation 47.29 1356 5675
order to supplier: removal 3.36 125905 403
invoice (draft): creation 48.01 3288 5761
invoice (draft): removal 51.24 1152 6149
invoice accept: apply 44.23 18397 5307
invoice accept: cancel 45.96 959 5515
customer reserve: creation 4097.92 181 491750
customer reserve: removal 4502.65 71 540318
realization accept: apply 86.09 170 10331
realization accept: cancel 89.32 126 10718
payment from customer: creation 61.77 94 7412
payment from customer: removal 6.38 90 766
payment to supplier: creation 50.99 120 6119
payment to supplier: removal 3.88 58 466
service: total inventory turnovers 3.49 4460 419
service: total monetary turnovers 6.58 571 789
service: aggregate perf. data 0.97 14459 116
service: refresh index statistics 0.03 286553 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-12-2021 10:38:37 to 22-12-2021 10:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6288
TEST_TIME 2 6108
TEST_TIME 3 5812
TEST_TIME 4 5261
TEST_TIME 5 4686
TEST_TIME 6 4593
TEST_TIME 7 4557
TEST_TIME 8 4833
TEST_TIME 9 5026
TEST_TIME 10 5352
TEST_TIME 11 5805
TEST_TIME 12 6308
TEST_TIME 13 6722
TEST_TIME 14 6891
TEST_TIME 15 7515
TEST_TIME 16 7951
TEST_TIME 17 7798
TEST_TIME 18 7837
TEST_TIME 19 8231
TEST_TIME 20 8450
TEST_TIME 21 8388
TEST_TIME 22 8523
TEST_TIME 23 8395
TEST_TIME 24 8463
TEST_TIME 25 8362
TEST_TIME 26 8444
TEST_TIME 27 9118
TEST_TIME 28 10643
TEST_TIME 29 10793
TEST_TIME 30 11412
TEST_TIME 31 11470
TEST_TIME 32 11474
TEST_TIME 33 11255
TEST_TIME 34 11316
TEST_TIME 35 11407
TEST_TIME 36 11316
TEST_TIME 37 11433
TEST_TIME 38 11268
TEST_TIME 39 11178
TEST_TIME 40 11046
TEST_TIME 41 10894
TEST_TIME 42 10823
TEST_TIME 43 10772
TEST_TIME 44 10713
TEST_TIME 45 10595
TEST_TIME 46 10612
TEST_TIME 47 10626
TEST_TIME 48 10525
TEST_TIME 49 10473
TEST_TIME 50 10537
TEST_TIME 51 10501
TEST_TIME 52 10450
TEST_TIME 53 10315
TEST_TIME 54 10232
TEST_TIME 55 10137
TEST_TIME 56 10044
TEST_TIME 57 10035
TEST_TIME 58 9930
TEST_TIME 59 10043
TEST_TIME 60 9978
TEST_TIME 61 10031
TEST_TIME 62 10022
TEST_TIME 63 10139
TEST_TIME 64 10145
TEST_TIME 65 10099
TEST_TIME 66 10208
TEST_TIME 67 10235
TEST_TIME 68 10239
TEST_TIME 69 10285
TEST_TIME 70 10331
TEST_TIME 71 10307
TEST_TIME 72 10245
TEST_TIME 73 10146
TEST_TIME 74 10054
TEST_TIME 75 10065
TEST_TIME 76 10056
TEST_TIME 77 9996
TEST_TIME 78 9939
TEST_TIME 79 9888
TEST_TIME 80 9838
TEST_TIME 81 9818
TEST_TIME 82 9765
TEST_TIME 83 9832
TEST_TIME 84 9825
TEST_TIME 85 9809
TEST_TIME 86 9892
TEST_TIME 87 9850
TEST_TIME 88 9859
TEST_TIME 89 9852
TEST_TIME 90 9836
TEST_TIME 91 9930
TEST_TIME 92 9931
TEST_TIME 93 9911
TEST_TIME 94 9821
TEST_TIME 95 9801
TEST_TIME 96 9727
TEST_TIME 97 9668
TEST_TIME 98 9607
TEST_TIME 99 9560
TEST_TIME 100 9614
TEST_TIME 101 9577
TEST_TIME 102 9580
TEST_TIME 103 9573
TEST_TIME 104 9521
TEST_TIME 105 9566
TEST_TIME 106 9538
TEST_TIME 107 9531
TEST_TIME 108 9537
TEST_TIME 109 9550
TEST_TIME 110 9573
TEST_TIME 111 9557
TEST_TIME 112 9590
TEST_TIME 113 9573
TEST_TIME 114 9493
TEST_TIME 115 9510
TEST_TIME 116 9464
TEST_TIME 117 9447
TEST_TIME 118 9438
TEST_TIME 119 9413
TEST_TIME 120 9347

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-12-2021 10:38:41 to 22-12-2021 10:38:44.

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 1573992 1573992 0 0.00 0 13928 7 0 0
doc_list_biud 1573991 1573991 0 0.00 0 27868 32 0 0
sp_add_invoice_to_stock 5403 5307 96 1.77 49 252990 18397 0 96
sp_cancel_adding_invoice 5515 5515 0 0.00 5 93900 959 0 0
sp_cancel_client_order 1807 1807 0 0.00 4 9267 368 0 0
sp_cancel_customer_reserve 540318 540318 0 0.00 1 27870 72 0 0
sp_cancel_pay_from_customer 766 766 0 0.00 3 8881 90 0 0
sp_cancel_pay_to_supplier 466 466 0 0.00 3 1264 58 0 0
sp_cancel_supplier_invoice 6161 6149 12 0.19 2 20355 1152 0 12
sp_cancel_supplier_order 403 403 0 0.00 4 1085403 125905 0 0
sp_cancel_write_off 10718 10718 0 0.00 1 15754 127 0 0
sp_client_order 21409 21409 0 0.00 4 28017 348 0 0
sp_customer_reserve 491750 491750 0 0.00 0 85803 182 0 0
sp_fill_shopping_cart 32836 32836 0 0.00 1 12274 41 0 0
sp_fill_shopping_cart_clo_res 491746 491746 0 0.00 0 19219 14 0 0
sp_get_clo_for_invoice 5301 5301 0 0.00 0 23297 313 0 0
sp_kill_cost_storno 17465 17465 0 0.00 0 8478 67 0 0
sp_kill_qstorno_ret_qs2qd 1097906 1097906 0 0.00 0 27864 40 0 0
sp_kill_qty_storno 580539 580539 0 0.00 0 27868 85 0 0
sp_lock_selected_doc 21786 21786 0 0.00 0 9436 4 0 0
sp_make_cost_storno 29161 29161 0 0.00 0 13921 47 0 0
sp_multiply_rows_for_pdistr 29161 29161 0 0.00 0 6841 28 0 0
sp_multiply_rows_for_qdistr 21409 21409 0 0.00 0 27008 164 0 0
sp_pay_from_customer 7412 7412 0 0.00 4 11123 94 0 0
sp_pay_to_supplier 6119 6119 0 0.00 5 12718 120 0 0
sp_payment_common 13531 13531 0 0.00 1 4372 19 0 0
sp_qd_handle_on_cancel_clo 1807 1807 0 0.00 0 7437 327 0 0
sp_qd_handle_on_invoice_upd_sts 10813 10813 0 0.00 0 21969 411 0 0
sp_qd_handle_on_reserve_upd_sts 10331 10331 0 0.00 0 9911 54 0 0
sp_reserve_write_off 10331 10331 0 0.00 7 16931 170 0 0
sp_supplier_invoice 5761 5761 0 0.00 7 105496 3289 0 0
sp_supplier_order 5675 5675 0 0.00 5 34168 1356 0 0
srv_aggregate_perf_data 116 116 0 0.00 2549 166236 14459 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1137 136093 40791 0 0
srv_find_qd_qs_mism 1069888 1069888 0 0.00 0 13792 6 0 0
srv_make_invnt_saldo 419 419 0 0.00 61 110962 4460 0 0
srv_make_money_saldo 789 789 0 0.00 1 24030 572 0 0
srv_recalc_idx_stat 4 4 0 0.00 148156 489941 286553 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5307 5307 0 0.00 0 5080 137 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 10927 106 0 0
t$perf-norm:sp_cancel_client_order 1807 1807 0 0.00 0 1421 8 0 0
t$perf-norm:sp_cancel_customer_reserve 763 763 0 0.00 0 8389 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 766 766 0 0.00 0 3045 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 466 466 0 0.00 0 1765 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 448 448 0 0.00 0 2932 33 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 28881 1851 0 0
t$perf-norm:sp_cancel_write_off 1068 1068 0 0.00 0 1059 4 0 0
t$perf-norm:sp_client_order 21409 21409 0 0.00 0 7572 18 0 0
t$perf-norm:sp_customer_reserve 1107 1107 0 0.00 0 1060 4 0 0
t$perf-norm:sp_pay_from_customer 7412 7412 0 0.00 0 5820 8 0 0
t$perf-norm:sp_pay_to_supplier 6119 6119 0 0.00 0 4110 8 0 0
t$perf-norm:sp_reserve_write_off 10331 10331 0 0.00 0 5263 10 0 0
t$perf-norm:sp_supplier_invoice 5761 5761 0 0.00 0 4098 16 0 0
t$perf-norm:sp_supplier_order 5675 5675 0 0.00 0 5570 14 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 2120 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 22 2 0 0
t$perf-norm:srv_make_invnt_saldo 419 419 0 0.00 0 2840 15 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 1910 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 5 0 0
v_add_invoice_to_stock 10788 10596 192 1.77 0 7237 18 0 192
v_all_customers 39502 39502 0 0.00 0 14 1 0 0
v_all_suppliers 31934 31934 0 0.00 0 20 1 0 0
v_all_wares 42818 42818 0 0.00 0 136 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 1827 9 0 0
v_cancel_client_order 3614 3614 0 0.00 0 7213 10 0 0
v_cancel_customer_prepayment 1532 1532 0 0.00 0 3240 8 0 0
v_cancel_customer_reserve 1526 1526 0 0.00 0 1494 7 0 0
v_cancel_payment_to_supplier 932 932 0 0.00 0 1248 7 0 0
v_cancel_supplier_invoice 920 896 24 2.60 0 633 13 0 24
v_cancel_supplier_order 806 806 0 0.00 0 8204 20 0 0
v_cancel_write_off 2136 2136 0 0.00 0 2161 14 0 0
v_max_id_clo_ord 5673 5673 0 0.00 0 580 1 0 0
v_max_id_clo_res 1107 1107 0 0.00 0 1573 8 0 0
v_max_id_ord_sup 5754 5754 0 0.00 0 3179 5 0 0
v_max_non_paid_invoice 1579 1579 0 0.00 0 3211 5 0 0
v_max_non_paid_realizn 3671 3671 0 0.00 0 4368 3 0 0
v_min_id_clo_ord 5673 5673 0 0.00 0 3764 7 0 0
v_min_id_clo_res 1107 1107 0 0.00 0 1700 5 0 0
v_min_id_ord_sup 5754 5754 0 0.00 0 9077 11 0 0
v_min_non_paid_invoice 6119 6119 0 0.00 0 3979 11 0 0
v_min_non_paid_realizn 7412 7412 0 0.00 0 3732 14 0 0
v_our_firm 10798 10798 0 0.00 0 14 1 0 0
v_reserve_write_off 20662 20662 0 0.00 0 4533 8 0 0
x_lock_depdocs_on_canc_invoice 11216 11216 0 0.00 0 17962 112 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 17964 376 0 0
x_make_qstorno_1000_1200 5675 5675 0 0.00 2 34160 1277 0 0
x_make_qstorno_1200_2000 5761 5761 0 0.00 3 100167 3137 0 0
x_make_qstorno_2100_3300 491750 491750 0 0.00 0 85801 165 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 22-12-2021 10:38:45 to 22-12-2021 10:38:50.

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
08:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:14 18821 8192000000 9713660944 10014035968 799612064 8.892 100 84 168 0 827825728 7817056 20396608
08:09:41 27765 8192000000 9770737312 10078945280 844680704 9.347 100 93 186 0 879905072 13475728 21748640
08:11:13 18464 8192000000 9804375792 10122178560 864346176 9.544 100 87 174 1 908961536 24907856 19707504
08:12:37 13327 8192000000 9866529872 10199744512 917156816 10.068 100 81 162 1 978985440 43966064 17862560
08:13:53 10528 8192000000 9924450800 10264907776 947760336 10.369 100 80 160 0 1028244464 63423088 17061040
08:15:07 6585 8192000000 9956769088 10310651904 984442992 10.727 100 83 165 0 1072578384 70898224 17237168
08:16:16 3822 8192000000 9980890336 10345013248 1021442096 11.086 100 78 156 1 1093782784 57226480 15114208
08:17:23 2999 8192000000 10015385840 10382848000 1042523632 11.289 100 78 155 0 1122249648 64208944 15517072
08:18:28 2352 8192000000 10037822656 10410479616 1064487040 11.499 100 91 183 1 1147604480 64908432 18209008
08:19:31 5255 8192000000 10018579760 10407063552 1072135168 11.572 100 66 129 0 1138126208 51404224 14586816
08:20:38 4666 8192000000 9985826816 10387185664 1084978976 11.695 100 44 89 2 1117021392 21828560 10213856
08:21:49 60870 8192000000 9999262352 10403151872 1089523936 11.738 100 34 68 1 1112279808 13791264 8964608
08:23:52 5091 8192000000 10021252288 10416492544 1099048576 11.829 100 47 93 2 1129730560 20944112 9737872
08:24:58 6305 8192000000 10032273904 10427600896 1107682736 11.910 100 36 68 0 1130688976 16062224 6944016
08:26:06 57586 8192000000 10045428480 10447118336 1097536432 11.814 99 58 116 1 1134705808 24444624 12724752
08:28:07 6598 8192000000 9988591136 10390761472 1066107312 11.515 97 31 62 1 1089255488 16436528 6711648
08:29:14 10012 8192000000 9972295808 10370301952 1025406176 11.124 97 33 66 1 1051409184 18009120 7993888
08:30:26 5385 8192000000 9939056448 10320674816 1024609648 11.116 99 47 96 0 1045430000 9202000 11618352
08:31:37 13469 8192000000 9928572176 10313338880 983160640 10.715 95 28 54 1 1028527120 40424128 4942352
08:32:52 5507 8192000000 9939854560 10318274560 1000911504 10.887 97 48 96 2 1033982304 22894336 10176464
08:34:01 6292 8192000000 9964383504 10350268416 1043858224 11.302 100 36 69 5 1076978944 26130112 6990608
08:35:08 6589 8192000000 9954985088 10349281280 1052154816 11.381 100 44 87 1 1080868960 18645984 10068160
08:36:17 12925 8192000000 9960875904 10360180736 1054767104 11.406 100 23 45 2 1081364832 21830016 4767712
08:37:34 29878 8192000000 10016932912 10407772160 1084804816 11.693 100 49 98 0 1119053488 24137952 10110720
08:39:17 12986 8192000000 10065998528 10461868032 1104974064 11.885 100 46 91 2 1171813840 57781520 9058256
08:40:39 73705 8192000000 10131698592 10554167296 1123415776 12.059 100 45 86 2 1216494976 83235120 9844080
08:42:54 40963 8192000000 10164623568 10583613440 1137847536 12.195 100 40 80 0 1282672224 135602272 9222416
08:44:42 18009 8192000000 10203582080 10612764672 1149094608 12.301 100 48 96 0 1306604192 145294704 12214880
08:46:04 17147 8192000000 10234933664 10645966848 1156502704 12.370 100 58 116 2 1340388816 170978144 12907968
08:47:31 2273 8192000000 10282161424 10679853056 1168476208 12.483 100 81 160 1 1380152480 193811072 17865200
08:48:38 5929 8192000000 10327747568 10733903872 1174643456 12.540 100 66 132 1 1419829456 230225760 14960240
08:49:52 2299 8192000000 10329622480 10737750016 1160114880 12.404 99 81 161 0 1430246256 251838736 18292640
08:51:00 3673 8192000000 10318936144 10740068352 1182828096 12.617 100 55 109 2 1423495984 227199920 13467968
08:52:18 1135 8192000000 10367737104 10803089408 1197944384 12.757 100 89 176 0 1462555984 244942576 19669024
08:53:21 5235 8192000000 10390227472 10812489728 1186933984 12.655 100 68 136 0 1493158128 290490160 15733984
08:54:30 3134 8192000000 10389838944 10799546368 1191121856 12.694 100 79 152 1 1482846128 274742528 16981744
08:55:40 1594 8192000000 10433568352 10835120128 1167136896 12.470 98 93 187 0 1526422656 338179424 21106336
08:56:51 1956 8192000000 10434094736 10836004864 1181267840 12.602 100 95 190 3 1525186336 322008656 21909840
08:58:01 5018 8192000000 10404967888 10822598656 1166730560 12.466 100 58 111 1 1502763104 322339376 13693168
08:59:14 8637 8192000000 10460190368 10894684160 1177248448 12.565 100 38 76 0 1561240752 373905968 10086336
09:00:39 12144 8192000000 10509703136 10938044416 1146783152 12.279 98 39 78 1 1592872656 435676400 10413104
09:01:58 8167 8192000000 10545044320 10970013696 1125394592 12.078 99 41 81 3 1632490896 496284736 10811568
09:03:14 7702 8192000000 10589693360 11026100224 1137680032 12.194 100 51 101 0 1690913264 539466096 13767136
09:04:28 15913 8192000000 10533943008 10981322752 1136863024 12.186 100 38 75 0 1640161584 493130384 10168176
09:05:52 18390 8192000000 10243262992 10705301504 1114653344 11.976 99 22 44 0 1365689856 245317776 5718736
09:07:13 21542 8192000000 10115619024 10647961600 1103846800 11.874 98 11 22 1 1222825584 116582240 2396544
09:09:14 76271 8192000000 10080682992 10504716288 1103809984 11.874 98 16 32 0 1134989504 27739216 3440304
09:11:32 47190 8192000000 10112991584 10562621440 1105663904 11.891 98 12 24 2 1185057072 76895984 2497184
09:13:19 136093 8192000000 10054087056 10506342400 1067747936 11.531 94 7 14 0 1130590256 61448944 1393376
09:16:52 60977 8192000000 10101291152 10517438464 1123132128 12.057 98 42 83 0 1172446864 41088128 8226608
09:19:36 18172 8192000000 10126151632 10543828992 1140953184 12.224 100 55 108 1 1211694096 58032688 12708224
09:21:00 99159 8192000000 10121000608 10585804800 1131404944 12.135 99 14 28 0 1201614336 66744080 3465312
09:23:43 34631 8192000000 10106727024 10548543488 1130858864 12.129 99 36 72 2 1197122848 58573568 7690416
09:25:22 3549 8192000000 10099208336 10520637440 1136080400 12.179 100 69 138 4 1194176512 42597488 15498624
09:26:27 68247 8192000000 10084285712 10590560256 1124941408 12.074 98 14 29 1 1217522128 89279328 3301392
09:28:36 15119 8192000000 10041278608 10493333504 1105078016 11.886 97 30 60 1 1131877360 20501056 6298288
09:29:54 36342 8192000000 10044467488 10502668288 1100554784 11.843 97 9 18 1 1124663760 22350576 1758400
09:31:31 73114 8192000000 10062448656 10525102080 1127536736 12.098 99 19 38 0 1169916592 38333968 4045888
09:33:44 46625 8192000000 10077924160 10552672256 1123325232 12.058 98 19 38 0 1183741664 56151648 4264784
09:35:37 44929 8192000000 10089169808 10584825856 1137892480 12.196 99 12 24 0 1216625648 76078880 2654288
09:37:24 24091 8192000000 10125813312 10564956160 1125052016 12.075 98 40 80 0 1203978688 70532416 8394256
09:38:52 65579 8192000000 10124411296 10589048832 1145733136 12.269 100 15 30 0 1230484400 81515200 3236064
09:40:59 5905 8192000000 10051835088 10474614784 1115194144 11.982 99 55 106 2 1144885840 17728160 11963536
09:42:07 32314 8192000000 10117053728 10544332800 1148361568 12.294 100 46 92 1 1217411376 59406208 9643600
09:43:42 22717 8192000000 10074309072 10503376896 1100933840 11.846 97 41 83 0 1152428960 43506160 7988960
09:45:06 79109 8192000000 10088643280 10603872256 1099080464 11.829 96 12 24 1 1161102384 59667648 2354272
09:47:33 81999 8192000000 10083504848 10527023104 1090055248 11.743 96 24 47 0 1141351200 46018240 5277712
09:49:55 70709 8192000000 10029831888 10449641472 1047885808 11.340 93 36 71 2 1074852080 19239888 7726384
09:52:15 2558 8192000000 10073821456 10503335936 1111275760 11.944 100 33 66 3 1179170032 60900816 6993456
09:53:57 52064 8192000000 10033552592 10491764736 1079244304 11.640 97 12 24 0 1122676576 40653168 2779104
09:55:49 63386 8192000000 10066832160 10516451328 1106913072 11.903 99 14 28 0 1159775504 49748896 3113536
09:57:53 56957 8192000000 10074940656 10541551616 1093204048 11.773 97 22 44 0 1129727552 31714096 4809408
09:59:50 74746 8192000000 10085109248 10580574208 1128674816 12.109 100 18 36 3 1212760048 80303856 3781376
10:02:20 72849 8192000000 10063862464 10520133632 1079405104 11.642 96 20 40 0 1137542048 53814784 4322160
10:04:40 19095 8192000000 10087062272 10509488128 1128764768 12.110 100 42 84 0 1173918928 37191088 7963072
10:06:00 42557 8192000000 10103102160 10530566144 1125523808 12.079 100 54 107 0 1201301568 64661744 11116016
10:07:46 93989 8192000000 10065067088 10508865536 1082999232 11.676 95 25 50 1 1147367824 59407184 4961408
10:10:20 73930 8192000000 10058419792 10499182592 1137271696 12.190 100 16 32 1 1174985040 33914256 3799088
10:12:58 65186 8192000000 10077203312 10526842880 1143623184 12.250 100 16 32 2 1162108448 15214896 3270368
10:15:10 101216 8192000000 10127472512 10587791360 1133687920 12.156 98 19 38 1 1215497216 77737776 4071520
10:17:51 66827 8192000000 10050240064 10536349696 1103776416 11.873 96 9 18 1 1149126336 43443024 1906896
10:19:59 70826 8192000000 10082514752 10520756224 1123976256 12.065 98 12 24 0 1163986240 37103232 2906752
10:22:13 52444 8192000000 10074934704 10522820608 1109674976 11.929 97 20 40 0 1171001792 57023264 4303552
10:24:10 29067 8192000000 10089765520 10527195136 1093052416 11.772 97 46 91 1 1156115504 53988480 9074608
10:25:39 82300 8192000000 10056458384 10527215616 1076191968 11.611 95 24 47 1 1164162992 83043792 4927232
10:28:04 34787 8192000000 10090420368 10525564928 1129493264 12.117 100 27 54 1 1171838784 36131408 6214112
10:29:39 87095 8192000000 10070822624 10563596288 1120594208 12.033 99 5 10 0 1167060368 45812384 653776
10:32:07 56703 8192000000 10057808432 10541162496 1114032096 11.971 99 26 52 1 1186736096 67231264 5472736
10:34:12 55568 8192000000 10060418560 10535813120 1111423184 11.946 98 14 28 0 1161689888 47180752 3085952
10:36:27 18560 8192000000 10081029904 10520203264 1129456304 12.116 100 32 64 1 1190350864 54059792 6834768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-12-2021 10:38:51 to 22-12-2021 10:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-12-2021 10:39:08 to 22-12-2021 10:39:10.

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          235811
MON$OLDEST_ACTIVE               240504
MON$OLDEST_SNAPSHOT             240504
MON$NEXT_TRANSACTION            240504
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                       2080352
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             805
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2694 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2694 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2694 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2694 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Dec 22 10:37:12 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Wed Dec 22 10:37:19 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Wed Dec 22 10:37:19 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Wed Dec 22 10:37:19 2021
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
--- end of diff output ---

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

22.12.21 10:39:11. 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
2021-12-22 10:39:13 No severe PSQL-related problems occured
22.12.21 10:39:14. End of report.