Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.05.2022 07: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 16139752 kB
MemAvailable 31442492 kB
Buffers 0 kB
Cached 15588672 kB
SwapCached 2588 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-21 07:37:15
DTS_END                         2022-05-21 07:37:15.9340
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/59680
TRN_ID                          212397
ATT_ID                          596
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: 8850.79 23616 1062095
customer order: creation 169.78 471 20374
customer order: refuse 14.79 458 1775
order to supplier: creation 45.11 1403 5413
order to supplier: removal 3.52 168909 422
invoice (draft): creation 46.54 3428 5585
invoice (draft): removal 49.02 1100 5882
invoice accept: apply 41.35 26410 4962
invoice accept: cancel 44.13 1412 5296
customer reserve: creation 3825.18 251 459022
customer reserve: removal 4324.73 107 518967
realization accept: apply 81.60 290 9792
realization accept: cancel 76.86 193 9223
payment from customer: creation 57.38 188 6886
payment from customer: removal 5.29 149 635
payment to supplier: creation 48.65 208 5838
payment to supplier: removal 3.66 140 439
service: total inventory turnovers 4.25 4103 510
service: total monetary turnovers 7.78 384 933
service: aggregate perf. data 1.13 14711 136
service: refresh index statistics 0.04 248000 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-05-2022 07:38:53 to 21-05-2022 07:38:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8693
TEST_TIME 2 7247
TEST_TIME 3 6427
TEST_TIME 4 5630
TEST_TIME 5 5456
TEST_TIME 6 5398
TEST_TIME 7 5808
TEST_TIME 8 5946
TEST_TIME 9 5789
TEST_TIME 10 6218
TEST_TIME 11 6338
TEST_TIME 12 6397
TEST_TIME 13 6334
TEST_TIME 14 6330
TEST_TIME 15 6438
TEST_TIME 16 6574
TEST_TIME 17 6749
TEST_TIME 18 6699
TEST_TIME 19 6946
TEST_TIME 20 7132
TEST_TIME 21 7380
TEST_TIME 22 7430
TEST_TIME 23 7476
TEST_TIME 24 7440
TEST_TIME 25 7755
TEST_TIME 26 8282
TEST_TIME 27 8222
TEST_TIME 28 8155
TEST_TIME 29 8734
TEST_TIME 30 9455
TEST_TIME 31 9839
TEST_TIME 32 10083
TEST_TIME 33 10268
TEST_TIME 34 10243
TEST_TIME 35 10223
TEST_TIME 36 10481
TEST_TIME 37 10498
TEST_TIME 38 10403
TEST_TIME 39 10443
TEST_TIME 40 10326
TEST_TIME 41 10295
TEST_TIME 42 10265
TEST_TIME 43 10185
TEST_TIME 44 10248
TEST_TIME 45 10142
TEST_TIME 46 10236
TEST_TIME 47 10139
TEST_TIME 48 9964
TEST_TIME 49 9857
TEST_TIME 50 9733
TEST_TIME 51 9650
TEST_TIME 52 9582
TEST_TIME 53 9504
TEST_TIME 54 9522
TEST_TIME 55 9471
TEST_TIME 56 9390
TEST_TIME 57 9423
TEST_TIME 58 9487
TEST_TIME 59 9634
TEST_TIME 60 9616
TEST_TIME 61 9553
TEST_TIME 62 9472
TEST_TIME 63 9381
TEST_TIME 64 9342
TEST_TIME 65 9328
TEST_TIME 66 9304
TEST_TIME 67 9356
TEST_TIME 68 9366
TEST_TIME 69 9325
TEST_TIME 70 9308
TEST_TIME 71 9288
TEST_TIME 72 9286
TEST_TIME 73 9340
TEST_TIME 74 9341
TEST_TIME 75 9517
TEST_TIME 76 9488
TEST_TIME 77 9497
TEST_TIME 78 9500
TEST_TIME 79 9433
TEST_TIME 80 9389
TEST_TIME 81 9348
TEST_TIME 82 9260
TEST_TIME 83 9259
TEST_TIME 84 9232
TEST_TIME 85 9199
TEST_TIME 86 9154
TEST_TIME 87 9118
TEST_TIME 88 9098
TEST_TIME 89 9147
TEST_TIME 90 9116
TEST_TIME 91 9079
TEST_TIME 92 9089
TEST_TIME 93 9107
TEST_TIME 94 9116
TEST_TIME 95 9131
TEST_TIME 96 9227
TEST_TIME 97 9233
TEST_TIME 98 9207
TEST_TIME 99 9311
TEST_TIME 100 9278
TEST_TIME 101 9274
TEST_TIME 102 9291
TEST_TIME 103 9214
TEST_TIME 104 9177
TEST_TIME 105 9141
TEST_TIME 106 9137
TEST_TIME 107 9093
TEST_TIME 108 9045
TEST_TIME 109 9046
TEST_TIME 110 9000
TEST_TIME 111 8981
TEST_TIME 112 8955
TEST_TIME 113 8929
TEST_TIME 114 8905
TEST_TIME 115 8921
TEST_TIME 116 8924
TEST_TIME 117 8945
TEST_TIME 118 8926
TEST_TIME 119 8941
TEST_TIME 120 8881

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-05-2022 07:38:56 to 21-05-2022 07:39:00.

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 1485647 1485647 0 0.00 0 19352 14 0 0
doc_list_biud 1485634 1485634 0 0.00 0 26057 44 0 0
sp_add_invoice_to_stock 5056 4962 94 1.85 78 197507 26410 0 94
sp_cancel_adding_invoice 5296 5296 0 0.00 7 105109 1413 0 0
sp_cancel_client_order 1775 1775 0 0.00 6 9538 459 0 0
sp_cancel_customer_reserve 518967 518967 0 0.00 1 24091 108 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 4 7200 150 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 4 4478 140 0 0
sp_cancel_supplier_invoice 5890 5882 8 0.13 3 21963 1100 0 8
sp_cancel_supplier_order 422 422 0 0.00 16 1678358 168909 0 0
sp_cancel_write_off 9223 9223 0 0.00 2 26149 193 0 0
sp_client_order 20374 20374 0 0.00 6 25581 472 0 0
sp_customer_reserve 459022 459022 0 0.00 1 92406 251 0 0
sp_fill_shopping_cart 31365 31365 0 0.00 1 21355 87 0 0
sp_fill_shopping_cart_clo_res 458984 458984 0 0.00 0 14261 19 0 0
sp_get_clo_for_invoice 4912 4912 0 0.00 0 27629 280 0 0
sp_kill_cost_storno 15593 15593 0 0.00 0 6712 87 0 0
sp_kill_qstorno_ret_qs2qd 1053461 1053461 0 0.00 0 26054 47 0 0
sp_kill_qty_storno 556268 556268 0 0.00 0 26056 108 0 0
sp_lock_selected_doc 20493 20493 0 0.00 0 7850 7 0 0
sp_make_cost_storno 27426 27426 0 0.00 0 19344 82 0 0
sp_multiply_rows_for_pdistr 27424 27424 0 0.00 0 7781 45 0 0
sp_multiply_rows_for_qdistr 20374 20374 0 0.00 0 9524 178 0 0
sp_pay_from_customer 6886 6886 0 0.00 5 9667 188 0 0
sp_pay_to_supplier 5838 5838 0 0.00 6 9657 209 0 0
sp_payment_common 12721 12721 0 0.00 2 5920 45 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 9528 379 0 0
sp_qd_handle_on_invoice_upd_sts 10207 10207 0 0.00 0 15923 554 0 0
sp_qd_handle_on_reserve_upd_sts 9792 9792 0 0.00 0 15773 66 0 0
sp_reserve_write_off 9792 9792 0 0.00 8 15799 291 0 0
sp_supplier_invoice 5585 5585 0 0.00 10 99755 3428 0 0
sp_supplier_order 5413 5413 0 0.00 9 56234 1404 0 0
srv_aggregate_perf_data 136 136 0 0.00 2516 168572 14711 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 771 135126 32629 0 0
srv_find_qd_qs_mism 1014073 1014073 0 0.00 0 13223 11 0 0
srv_make_invnt_saldo 510 510 0 0.00 67 85351 4103 0 0
srv_make_money_saldo 933 933 0 0.00 1 12567 384 0 0
srv_recalc_idx_stat 5 5 0 0.00 86122 405296 248001 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4968 4968 0 0.00 0 26338 979 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 2 8242 549 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 2983 34 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 2175 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 2559 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 495 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 6496 49 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 102208 7536 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 4573 28 0 0
t$perf-norm:sp_client_order 20374 20374 0 0.00 0 7325 40 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 2795 21 0 0
t$perf-norm:sp_pay_from_customer 6886 6886 0 0.00 0 4305 18 0 0
t$perf-norm:sp_pay_to_supplier 5838 5838 0 0.00 0 10323 20 0 0
t$perf-norm:sp_reserve_write_off 9793 9793 0 0.00 0 10443 23 0 0
t$perf-norm:sp_supplier_invoice 5585 5585 0 0.00 0 7446 37 0 0
t$perf-norm:sp_supplier_order 5413 5413 0 0.00 0 3294 34 0 0
t$perf-norm:srv_aggregate_perf_data 136 136 0 0.00 0 982 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 6560 137 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 525 15 0 0
t$perf-norm:srv_make_money_saldo 933 933 0 0.00 0 574 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 169 43 0 0
v_add_invoice_to_stock 10006 9818 188 1.87 0 6361 26 0 188
v_all_customers 37214 37214 0 0.00 0 156 4 0 0
v_all_suppliers 30606 30606 0 0.00 0 96 4 0 0
v_all_wares 40740 40740 0 0.00 0 152 4 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 1893 19 0 0
v_cancel_client_order 3550 3550 0 0.00 0 2866 14 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 2369 14 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 1494 11 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 1691 15 0 0
v_cancel_supplier_invoice 818 802 16 1.95 0 2406 27 0 16
v_cancel_supplier_order 844 844 0 0.00 0 2770 16 0 0
v_cancel_write_off 2024 2024 0 0.00 0 2781 26 0 0
v_max_id_clo_ord 5406 5406 0 0.00 0 1548 5 0 0
v_max_id_clo_res 988 988 0 0.00 0 5108 19 0 0
v_max_id_ord_sup 5584 5584 0 0.00 0 2962 6 0 0
v_max_non_paid_invoice 1524 1524 0 0.00 0 5915 13 0 0
v_max_non_paid_realizn 3447 3447 0 0.00 0 298 7 0 0
v_min_id_clo_ord 5406 5406 0 0.00 0 3596 10 0 0
v_min_id_clo_res 988 988 0 0.00 0 2436 12 0 0
v_min_id_ord_sup 5584 5584 0 0.00 0 7564 16 0 0
v_min_non_paid_invoice 5837 5837 0 0.00 0 2833 15 0 0
v_min_non_paid_realizn 6884 6884 0 0.00 0 5896 17 0 0
v_our_firm 10400 10400 0 0.00 0 94 4 0 0
v_reserve_write_off 19582 19582 0 0.00 0 6535 12 0 0
x_lock_depdocs_on_canc_invoice 10777 10777 0 0.00 0 21694 141 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 10186 342 0 0
x_make_qstorno_1000_1200 5413 5413 0 0.00 3 56207 1226 0 0
x_make_qstorno_1200_2000 5585 5585 0 0.00 5 93106 3190 0 0
x_make_qstorno_2100_3300 459020 459020 0 0.00 0 92389 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-05-2022 07:39:01 to 21-05-2022 07:39:05.

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:19 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:21 29203 8192000000 9723097248 10023714816 800070368 8.897 100 84 168 0 827021200 7206800 19744032
05:09:55 19273 8192000000 9759267920 10066198528 835484720 9.254 100 90 180 0 869071040 12317728 21268592
05:11:22 22417 8192000000 9794512016 10115125248 866658576 9.567 100 84 168 0 904691264 18782896 19249792
05:12:57 8079 8192000000 9858364624 10190184448 909039104 9.988 100 86 172 0 969240480 41474064 18727312
05:14:09 4506 8192000000 9906744704 10245156864 938737280 10.281 100 87 169 2 1015280224 57944576 18598368
05:15:17 2660 8192000000 9953726736 10304110592 980008272 10.684 100 93 180 1 1072407248 73830432 18568544
05:16:23 2303 8192000000 9980524000 10337492992 1006094096 10.938 100 89 174 2 1089373136 65418368 17860672
05:17:28 852 8192000000 10009178832 10376458240 1034791232 11.215 100 90 171 2 1129263952 77151856 17320864
05:18:30 1067 8192000000 10026458320 10398060544 1061628928 11.472 100 97 184 2 1145920208 66064896 18226384
05:19:32 1520 8192000000 10051484480 10431660032 1069114368 11.544 100 93 183 0 1169344976 80926224 19304384
05:20:35 1390 8192000000 10041593344 10419408896 1076287456 11.612 100 83 154 0 1164094080 73101264 14705360
05:21:37 1221 8192000000 10065601904 10449879040 1084522944 11.691 100 89 173 0 1186120032 84559440 17037648
05:22:39 1244 8192000000 10044899232 10437476352 1086353072 11.708 100 74 142 1 1167468544 66886240 14229232
05:23:41 1005 8192000000 10027287488 10413047808 1089908832 11.742 100 89 175 1 1151241904 43730912 17602160
05:24:42 1048 8192000000 10035441040 10426105856 1094128992 11.782 100 94 187 0 1159436176 46237152 19070032
05:25:44 1121 8192000000 10038342720 10430316544 1095213216 11.792 100 89 161 1 1158858496 47077312 16567968
05:26:47 1299 8192000000 10065422368 10452795392 1095692720 11.797 100 92 181 1 1178793712 65034176 18066816
05:27:49 1345 8192000000 10066272000 10456932352 1091475776 11.757 100 98 177 2 1192274816 81786176 19012864
05:28:51 1073 8192000000 10044983280 10436075520 1096535088 11.805 100 92 174 0 1169639920 54898928 18205904
05:29:53 2155 8192000000 10035641936 10428002304 1091620512 11.758 100 80 158 2 1161489328 55107968 14760848
05:30:56 958 8192000000 10032443040 10420506624 1076028304 11.610 100 81 157 0 1148674144 56756976 15888864
05:31:58 1081 8192000000 9991316096 10369105920 1045790224 11.320 99 92 175 1 1113023680 49318320 17915136
05:33:00 1173 8192000000 10008273424 10386644992 1048434688 11.346 100 86 169 2 1129328352 64405296 16488368
05:34:03 1219 8192000000 10010073184 10382630912 1044781072 11.311 100 91 178 0 1132019008 69107712 18130224
05:35:05 6018 8192000000 9961420144 10329518080 1010310000 10.978 99 66 128 1 1080540704 56668272 13562432
05:36:12 2749 8192000000 9975979248 10352025600 1019630736 11.068 99 81 148 4 1097882864 62040816 16211312
05:37:16 1159 8192000000 10000391760 10373935104 1042057776 11.284 100 94 172 1 1120049792 60863536 17128480
05:38:24 10302 8192000000 10036683872 10425729024 1069102448 11.544 100 58 111 1 1151749456 71741984 10905024
05:39:43 4765 8192000000 10033285312 10425700352 1091128976 11.753 100 70 129 2 1159227760 55332272 12766512
05:40:58 10786 8192000000 10077622224 10474180608 1103136640 11.867 100 61 119 0 1200299648 84817312 12345696
05:42:12 23593 8192000000 10124364960 10531745792 1112686592 11.958 100 59 114 1 1244570352 119115632 12768128
05:43:44 5366 8192000000 10156962112 10555043840 1130869984 12.130 100 86 159 1 1271297296 123479280 16948032
05:44:56 3325 8192000000 10164388160 10562469888 1133980848 12.159 99 84 162 2 1281247616 130372352 16894416
05:46:02 1261 8192000000 10212828608 10614562816 1161600336 12.418 100 83 146 2 1332213088 154660400 15952352
05:47:06 1466 8192000000 10279298304 10684346368 1171113952 12.507 100 97 195 1 1388677616 196621904 20941760
05:48:10 1387 8192000000 10289514864 10690973696 1172431472 12.520 100 94 180 2 1400327456 206176768 21719216
05:49:15 1380 8192000000 10322469168 10736316416 1193256928 12.714 100 80 155 0 1433519936 222970672 17292336
05:50:22 1695 8192000000 10351718528 10755268608 1196840544 12.747 100 92 178 1 1464297808 247015872 20441392
05:51:26 2182 8192000000 10407941984 10815873024 1203961120 12.813 100 88 173 0 1522718160 297960128 20796912
05:52:32 762 8192000000 10417329744 10822807552 1203553712 12.809 100 96 186 2 1523919984 300142864 20223408
05:53:37 1501 8192000000 10494746800 10911973376 1208800752 12.858 100 84 165 0 1599418064 371008912 19608400
05:54:44 1401 8192000000 10507566432 10933776384 1209276192 12.862 100 88 174 1 1615970976 384985312 21709472
05:55:50 1877 8192000000 10528309008 10961764352 1196133664 12.740 100 91 177 2 1630131664 412902800 21095200
05:56:54 1714 8192000000 10523521280 10973429760 1211490128 12.883 100 93 180 0 1633319936 399030560 22799248
05:58:00 1115 8192000000 10507587744 10943557632 1187625424 12.661 99 98 196 1 1615919312 404127056 24166832
05:59:05 1447 8192000000 10532610176 10951106560 1192385472 12.706 100 97 191 2 1635321984 419675504 23261008
06:00:09 870 8192000000 10555330976 10976223232 1199255264 12.769 100 97 195 3 1662007120 439069536 23682320
06:01:14 1652 8192000000 10560924352 11016761344 1197996544 12.758 100 88 170 1 1664227776 445369552 20861680
06:02:19 946 8192000000 10555895904 11039498240 1187538576 12.660 100 99 193 1 1660573968 448422096 24613296
06:03:23 6272 8192000000 10518497824 11009589248 1177206512 12.564 99 74 148 0 1612600688 416882608 18511568
06:04:32 9328 8192000000 10517330352 10952957952 1169003664 12.488 100 46 90 1 1620302560 439201488 12097408
06:05:50 889 8192000000 10490945936 10981613568 1162725248 12.429 100 91 179 1 1594946224 407931152 24289824
06:06:54 6822 8192000000 10411166752 10949128192 1163804960 12.439 100 56 101 1 1518712320 342242192 12665168
06:08:06 23765 8192000000 10280539136 10755362816 1162631920 12.428 100 24 48 0 1392033376 223385632 6015824
06:10:23 72958 8192000000 10176864480 10631704576 1130653536 12.128 97 22 44 1 1256003904 119961072 5389296
06:12:38 25081 8192000000 10181425472 10634948608 1132480160 12.145 99 21 42 1 1279158896 141277760 5400976
06:15:08 12883 8192000000 10088226016 10519773184 1144163968 12.255 100 54 89 4 1185578400 31546608 9867824
06:16:23 88079 8192000000 10075912656 10511278080 1138618096 12.203 99 31 59 2 1173620848 27406896 7595856
06:19:01 68031 8192000000 10091729632 10559262720 1122795824 12.053 99 21 39 2 1162786704 35845232 4145648
06:21:20 103220 8192000000 10099160128 10551377920 1115065152 11.980 97 9 18 0 1170521184 53593344 1862688
06:24:20 135123 8192000000 10077584432 10510614528 1107941712 11.913 97 40 74 0 1141136112 25345968 7848432
06:27:38 23620 8192000000 10126324400 10551951360 1134828944 12.167 99 77 149 1 1202057824 51757760 15471120
06:29:13 63076 8192000000 10121499072 10552614912 1136212896 12.180 99 28 55 1 1221952672 79701776 6038000
06:31:18 44058 8192000000 10165595072 10608599040 1153629856 12.344 99 32 64 0 1268083632 107140336 7313440
06:33:30 20095 8192000000 10219288816 10649812992 1149354048 12.303 100 65 128 1 1310781792 146107280 15320464
06:34:54 53008 8192000000 10151234960 10614341632 1136392192 12.182 99 19 38 0 1286124768 145089984 4642592
06:37:25 24979 8192000000 10035188080 10487861248 1101237104 11.849 98 8 14 1 1126785232 23990192 1557936
06:38:51 29206 8192000000 10061022048 10516217856 1134768160 12.166 100 16 32 0 1164715392 26778544 3168688
06:40:20 47157 8192000000 10068209328 10522853376 1115398704 11.984 98 23 44 0 1164204304 43738448 5067152
06:42:09 74126 8192000000 10088794768 10584150016 1126305936 12.087 99 12 24 0 1188511920 59637152 2568832
06:44:29 72948 8192000000 10060018832 10526994432 1112370288 11.955 98 15 30 2 1163316528 47803856 3142384
06:46:43 22746 8192000000 10126876656 10565783552 1143728128 12.251 100 35 70 2 1221381936 69954384 7699424
06:48:07 40296 8192000000 10155365088 10599337984 1116754160 11.996 98 24 49 2 1215000304 92789616 5456528
06:49:50 40767 8192000000 10180933008 10645352448 1136458848 12.182 100 43 86 0 1265256512 118974320 9823344
06:51:41 42127 8192000000 10086145392 10603204608 1116200816 11.991 98 20 40 1 1226513040 105982448 4329776
06:53:28 41793 8192000000 10091202448 10546835456 1124898976 12.073 99 40 76 1 1185712464 53197952 7615536
06:55:12 18825 8192000000 10129684976 10569011200 1123922944 12.064 99 49 98 2 1197675488 64204112 9548432
06:56:41 101515 8192000000 10079202368 10515800064 1118269360 12.011 98 31 62 0 1163982336 39298080 6414896
06:59:24 16442 8192000000 10105594784 10566750208 1129622976 12.118 100 28 54 1 1210424368 75405456 5395936
07:00:42 62694 8192000000 10092039216 10545250304 1119628688 12.023 99 26 52 1 1182040608 56548384 5863536
07:02:52 69172 8192000000 10112676000 10581360640 1138453920 12.201 100 17 33 2 1206693520 64428848 3810752
07:05:06 111994 8192000000 10077328432 10580447232 1095846112 11.798 96 16 32 0 1170625056 71069744 3709200
07:07:58 37413 8192000000 10102693072 10538070016 1119960416 12.027 98 38 77 0 1153118560 25737184 7420960
07:09:41 42331 8192000000 10143482768 10580500480 1113788496 11.968 97 28 56 1 1210493904 90635216 6070192
07:11:24 16582 8192000000 10182984880 10618761216 1152484912 12.333 100 49 98 1 1275535616 113156272 9894432
07:12:43 51790 8192000000 10146171616 10623082496 1127916288 12.102 98 26 52 3 1217923600 84342592 5664720
07:14:56 16451 8192000000 10146205296 10583830528 1146535648 12.277 100 41 82 2 1235042688 79920544 8586496
07:16:12 46256 8192000000 10069444768 10533367808 1118759776 12.015 98 19 38 0 1189028960 66001232 4267952
07:18:01 13395 8192000000 10139537360 10572922880 1154779456 12.354 100 51 100 1 1211938352 47038048 10120848
07:19:20 84302 8192000000 10096645696 10557665280 1144613616 12.259 100 18 36 1 1185506400 37084176 3808608
07:21:46 22396 8192000000 10088931376 10561638400 1144267888 12.256 100 30 60 0 1193663696 43294896 6100912
07:24:11 59528 8192000000 10086466672 10549321728 1149519856 12.305 100 5 10 0 1209693296 58726112 1447328
07:26:12 109772 8192000000 10083109136 10573578240 1115292160 11.982 97 17 34 0 1164920544 45141776 4486608
07:29:12 60330 8192000000 10076233888 10537488384 1100158928 11.839 97 14 28 0 1159680384 56534512 2986944
07:31:14 20185 8192000000 10104252992 10545463296 1105374656 11.889 97 23 44 1 1183142256 72555952 5211648
07:32:36 54261 8192000000 10113925088 10561441792 1126535888 12.089 99 26 52 0 1213448192 81218512 5693792
07:34:38 35386 8192000000 10131825328 10580672512 1099208416 11.830 98 34 68 0 1207457904 101170160 7079328
07:36:17 23556 8192000000 10205153376 10632445952 1109177856 11.925 98 46 92 0 1268414432 149864176 9372400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-05-2022 07:39:07 to 21-05-2022 07:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-05-2022 07:39:24 to 21-05-2022 07:39:26.

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          208758
MON$OLDEST_ACTIVE               218478
MON$OLDEST_SNAPSHOT             218478
MON$NEXT_TRANSACTION            218478
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                       2074848
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2767 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2767 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2767 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2767 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 ---
:94: 
:95: mscs-test-161.budzdorov.ru    Sat May 21 07:37:32 2022
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:97: 
:98: 
:99: mscs-test-161.budzdorov.ru    Sat May 21 07:37:33 2022
:100:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:101: 
--- end of diff output ---

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

21.05.22 07:39:27. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-05-21 07:39:29 No severe PSQL-related problems occured
21.05.22 07:39:31. End of report.