Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.05.2022 04:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16063308 kB
MemAvailable 31509184 kB
Buffers 20 kB
Cached 15607176 kB
SwapCached 3264 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-04 04:37:17
DTS_END                         2022-05-04 04:37:17.9480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54630
TRN_ID                          215684
ATT_ID                          592
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: 9170.20 24412 1100424
customer order: creation 167.30 538 20076
customer order: refuse 13.80 427 1656
order to supplier: creation 43.20 1488 5184
order to supplier: removal 3.64 163900 437
invoice (draft): creation 43.69 3315 5243
invoice (draft): removal 54.83 1033 6580
invoice accept: apply 39.48 25879 4738
invoice accept: cancel 49.00 1125 5880
customer reserve: creation 3678.83 246 441460
customer reserve: removal 4785.65 97 574278
realization accept: apply 79.28 281 9514
realization accept: cancel 87.73 164 10527
payment from customer: creation 55.93 192 6712
payment from customer: removal 5.33 195 640
payment to supplier: creation 47.48 211 5698
payment to supplier: removal 3.43 108 412
service: total inventory turnovers 3.72 4260 446
service: total monetary turnovers 6.98 366 837
service: aggregate perf. data 0.85 18894 102
service: refresh index statistics 0.03 265525 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8453
TEST_TIME 2 6836
TEST_TIME 3 6038
TEST_TIME 4 6016
TEST_TIME 5 5853
TEST_TIME 6 5962
TEST_TIME 7 5782
TEST_TIME 8 5570
TEST_TIME 9 6023
TEST_TIME 10 6273
TEST_TIME 11 6408
TEST_TIME 12 6363
TEST_TIME 13 6415
TEST_TIME 14 6515
TEST_TIME 15 6545
TEST_TIME 16 6666
TEST_TIME 17 6796
TEST_TIME 18 6799
TEST_TIME 19 6817
TEST_TIME 20 6998
TEST_TIME 21 7283
TEST_TIME 22 7863
TEST_TIME 23 7881
TEST_TIME 24 8020
TEST_TIME 25 8497
TEST_TIME 26 8891
TEST_TIME 27 9204
TEST_TIME 28 9758
TEST_TIME 29 10008
TEST_TIME 30 10188
TEST_TIME 31 10246
TEST_TIME 32 10432
TEST_TIME 33 10640
TEST_TIME 34 11189
TEST_TIME 35 11146
TEST_TIME 36 10954
TEST_TIME 37 10998
TEST_TIME 38 10943
TEST_TIME 39 10724
TEST_TIME 40 10616
TEST_TIME 41 10611
TEST_TIME 42 10518
TEST_TIME 43 10412
TEST_TIME 44 10248
TEST_TIME 45 10114
TEST_TIME 46 10052
TEST_TIME 47 9954
TEST_TIME 48 9898
TEST_TIME 49 9884
TEST_TIME 50 9860
TEST_TIME 51 9826
TEST_TIME 52 9878
TEST_TIME 53 9816
TEST_TIME 54 9761
TEST_TIME 55 9650
TEST_TIME 56 9523
TEST_TIME 57 9626
TEST_TIME 58 9719
TEST_TIME 59 9798
TEST_TIME 60 9782
TEST_TIME 61 9770
TEST_TIME 62 9754
TEST_TIME 63 9933
TEST_TIME 64 9855
TEST_TIME 65 9941
TEST_TIME 66 9866
TEST_TIME 67 9851
TEST_TIME 68 9957
TEST_TIME 69 9924
TEST_TIME 70 9796
TEST_TIME 71 9798
TEST_TIME 72 9771
TEST_TIME 73 9675
TEST_TIME 74 9690
TEST_TIME 75 9638
TEST_TIME 76 9607
TEST_TIME 77 9529
TEST_TIME 78 9452
TEST_TIME 79 9387
TEST_TIME 80 9336
TEST_TIME 81 9370
TEST_TIME 82 9461
TEST_TIME 83 9484
TEST_TIME 84 9482
TEST_TIME 85 9451
TEST_TIME 86 9573
TEST_TIME 87 9520
TEST_TIME 88 9522
TEST_TIME 89 9560
TEST_TIME 90 9573
TEST_TIME 91 9594
TEST_TIME 92 9661
TEST_TIME 93 9628
TEST_TIME 94 9553
TEST_TIME 95 9478
TEST_TIME 96 9507
TEST_TIME 97 9456
TEST_TIME 98 9408
TEST_TIME 99 9346
TEST_TIME 100 9351
TEST_TIME 101 9301
TEST_TIME 102 9279
TEST_TIME 103 9255
TEST_TIME 104 9232
TEST_TIME 105 9246
TEST_TIME 106 9232
TEST_TIME 107 9226
TEST_TIME 108 9222
TEST_TIME 109 9196
TEST_TIME 110 9248
TEST_TIME 111 9258
TEST_TIME 112 9377
TEST_TIME 113 9436
TEST_TIME 114 9407
TEST_TIME 115 9333
TEST_TIME 116 9306
TEST_TIME 117 9284
TEST_TIME 118 9296
TEST_TIME 119 9256
TEST_TIME 120 9195

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-05-2022 04:38:57 to 04-05-2022 04: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 1509407 1509407 0 0.00 0 23197 13 0 0
doc_list_biud 1509398 1509398 0 0.00 0 74263 43 0 0
sp_add_invoice_to_stock 4855 4738 117 2.40 38 176312 25879 0 117
sp_cancel_adding_invoice 5880 5880 0 0.00 7 69931 1126 0 0
sp_cancel_client_order 1656 1656 0 0.00 6 23601 428 0 0
sp_cancel_customer_reserve 574278 574278 0 0.00 1 74268 98 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 3 18601 196 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 4 4956 109 0 0
sp_cancel_supplier_invoice 6587 6580 7 0.10 3 60050 1034 0 7
sp_cancel_supplier_order 437 437 0 0.00 10 1492492 163900 0 0
sp_cancel_write_off 10527 10527 0 0.00 2 10709 164 0 0
sp_client_order 20076 20076 0 0.00 6 76302 538 0 0
sp_customer_reserve 441460 441460 0 0.00 0 55287 247 0 0
sp_fill_shopping_cart 30493 30493 0 0.00 1 10645 78 0 0
sp_fill_shopping_cart_clo_res 441437 441437 0 0.00 0 17466 18 0 0
sp_get_clo_for_invoice 4699 4699 0 0.00 0 30771 308 0 0
sp_kill_cost_storno 17459 17459 0 0.00 0 10232 69 0 0
sp_kill_qstorno_ret_qs2qd 1166100 1166100 0 0.00 0 74236 43 0 0
sp_kill_qty_storno 613569 613569 0 0.00 0 74261 98 0 0
sp_lock_selected_doc 19908 19908 0 0.00 0 8774 8 0 0
sp_make_cost_storno 26622 26622 0 0.00 0 15944 80 0 0
sp_multiply_rows_for_pdistr 26622 26622 0 0.00 0 7989 36 0 0
sp_multiply_rows_for_qdistr 20076 20076 0 0.00 0 76257 199 0 0
sp_pay_from_customer 6712 6712 0 0.00 6 23272 192 0 0
sp_pay_to_supplier 5698 5698 0 0.00 6 26551 212 0 0
sp_payment_common 12410 12410 0 0.00 2 13074 44 0 0
sp_qd_handle_on_cancel_clo 1656 1656 0 0.00 1 10162 335 0 0
sp_qd_handle_on_invoice_upd_sts 10577 10577 0 0.00 0 20697 488 0 0
sp_qd_handle_on_reserve_upd_sts 9514 9514 0 0.00 0 11376 64 0 0
sp_reserve_write_off 9514 9514 0 0.00 8 23410 281 0 0
sp_supplier_invoice 5243 5243 0 0.00 10 91784 3315 0 0
sp_supplier_order 5184 5184 0 0.00 9 92036 1488 0 0
srv_aggregate_perf_data 102 102 0 0.00 2359 169897 18894 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 794 117578 35284 0 0
srv_find_qd_qs_mism 1054765 1054765 0 0.00 0 23168 10 0 0
srv_make_invnt_saldo 446 446 0 0.00 81 120922 4261 0 0
srv_make_money_saldo 837 837 0 0.00 1 19731 366 0 0
srv_recalc_idx_stat 4 4 0 0.00 88869 534419 265525 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4743 4743 0 0.00 1 16313 866 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 1 11609 392 0 0
t$perf-norm:sp_cancel_client_order 1656 1656 0 0.00 0 2204 26 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 2002 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 790 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 4795 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 748 23 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 84882 6548 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 1366 19 0 0
t$perf-norm:sp_client_order 20077 20077 0 0.00 0 10403 38 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 3252 16 0 0
t$perf-norm:sp_pay_from_customer 6712 6712 0 0.00 0 5332 19 0 0
t$perf-norm:sp_pay_to_supplier 5698 5698 0 0.00 0 8573 21 0 0
t$perf-norm:sp_reserve_write_off 9515 9515 0 0.00 0 20689 32 0 0
t$perf-norm:sp_supplier_invoice 5243 5243 0 0.00 0 14477 33 0 0
t$perf-norm:sp_supplier_order 5184 5184 0 0.00 0 10420 34 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 4349 49 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 526 14 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 3327 24 0 0
t$perf-norm:srv_make_money_saldo 837 837 0 0.00 0 1210 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 243 65 0 0
v_add_invoice_to_stock 9622 9388 234 2.43 0 14327 28 0 234
v_all_customers 36664 36664 0 0.00 0 84 3 0 0
v_all_suppliers 29660 29660 0 0.00 0 108 4 0 0
v_all_wares 40152 40152 0 0.00 0 73 4 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 1708 17 0 0
v_cancel_client_order 3312 3312 0 0.00 0 23092 17 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 18444 51 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 5496 17 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 513 10 0 0
v_cancel_supplier_invoice 882 868 14 1.58 0 2564 25 0 14
v_cancel_supplier_order 874 874 0 0.00 0 1695 12 0 0
v_cancel_write_off 1986 1986 0 0.00 0 1570 21 0 0
v_max_id_clo_ord 5180 5180 0 0.00 0 4372 5 0 0
v_max_id_clo_res 974 974 0 0.00 0 5062 15 0 0
v_max_id_ord_sup 5235 5235 0 0.00 0 6072 8 0 0
v_max_non_paid_invoice 1283 1283 0 0.00 0 2788 10 0 0
v_max_non_paid_realizn 3471 3471 0 0.00 0 2398 7 0 0
v_min_id_clo_ord 5180 5180 0 0.00 0 3032 9 0 0
v_min_id_clo_res 974 974 0 0.00 0 2729 19 0 0
v_min_id_ord_sup 5235 5235 0 0.00 0 4399 7 0 0
v_min_non_paid_invoice 5698 5698 0 0.00 0 4689 12 0 0
v_min_non_paid_realizn 6712 6712 0 0.00 0 7408 19 0 0
v_our_firm 9970 9970 0 0.00 0 50 4 0 0
v_reserve_write_off 19028 19028 0 0.00 0 23095 15 0 0
x_lock_depdocs_on_canc_invoice 12026 12026 0 0.00 0 12226 121 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 7335 332 0 0
x_make_qstorno_1000_1200 5184 5184 0 0.00 3 91988 1325 0 0
x_make_qstorno_1200_2000 5243 5243 0 0.00 5 90762 3104 0 0
x_make_qstorno_2100_3300 441460 441460 0 0.00 0 45670 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-05-2022 04:39:01 to 04-05-2022 04: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
02:07:21 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:24 23271 8192000000 9728248384 10028888064 806496336 8.962 100 86 172 0 834269280 7318928 20454016
02:09:50 15495 8192000000 9760031136 10070044672 834966448 9.249 100 87 174 0 867534512 11634208 20933856
02:11:15 19943 8192000000 9812273056 10133729280 871121040 9.611 100 84 168 0 913221792 22515296 19585456
02:12:53 9257 8192000000 9885825888 10224173056 929587664 10.191 100 80 160 0 998952352 52062912 17301776
02:14:05 4567 8192000000 9914295408 10264465408 962712848 10.516 100 81 156 0 1036881424 58088832 16079744
02:15:12 2500 8192000000 9954175776 10312220672 1000584160 10.884 100 94 184 0 1073890784 54274272 19032352
02:16:15 2010 8192000000 10026596688 10390597632 1027207488 11.142 100 86 168 1 1140113776 96501152 16405136
02:17:19 850 8192000000 10043829472 10416021504 1050650032 11.367 100 94 187 1 1164135296 94473856 19011408
02:18:20 767 8192000000 10044713712 10418405376 1062169760 11.477 100 95 186 0 1162186944 81505200 18511984
02:19:22 11781 8192000000 10012802496 10397831168 1066117680 11.515 100 74 142 1 1135611072 54992624 14500768
02:20:34 1355 8192000000 10022187888 10404712448 1071805744 11.569 100 86 164 1 1138419616 51236512 15377360
02:21:36 1073 8192000000 10037151040 10419965952 1077958704 11.628 100 88 175 1 1156007776 60644496 17404576
02:22:39 1065 8192000000 10046734272 10430447616 1077321008 11.622 100 97 184 1 1164576784 69133264 18122512
02:23:41 2962 8192000000 10046294928 10435698688 1079232864 11.640 100 82 152 2 1166670848 72712128 14725856
02:24:45 1872 8192000000 10034198304 10422779904 1089252960 11.736 100 82 159 0 1150995760 45903136 15839664
02:25:48 3336 8192000000 10033419744 10424389632 1089074512 11.734 100 69 121 2 1152330496 51266576 11989408
02:26:53 1093 8192000000 10041822848 10431926272 1091462752 11.757 100 89 168 1 1161578400 53467008 16648640
02:27:55 6801 8192000000 10035913232 10427932672 1091460784 11.757 100 66 126 2 1158512896 54161280 12890832
02:29:03 1147 8192000000 10043110128 10428035072 1081896032 11.666 100 95 178 1 1164923472 66253408 16774032
02:30:05 1343 8192000000 10033211328 10415046656 1076420064 11.613 100 83 152 1 1146675728 54707056 15548608
02:31:07 1218 8192000000 10036100096 10419851264 1081866864 11.665 100 95 181 1 1152115136 50537136 19711136
02:32:10 1109 8192000000 10028893136 10407313408 1057004944 11.428 100 97 183 0 1148502912 72900032 18597936
02:33:11 3152 8192000000 10020067584 10404012032 1058056272 11.438 100 75 151 0 1145111024 70876880 16177872
02:34:16 1106 8192000000 9974439680 10342858752 1026639888 11.136 99 84 163 1 1095047584 51229424 17178272
02:35:17 12052 8192000000 9965108240 10346553344 1031121104 11.179 99 60 101 2 1095877344 55284624 9471616
02:36:30 976 8192000000 9977628944 10352259072 1034327664 11.210 100 91 177 1 1097527200 43779152 19420384
02:37:33 5138 8192000000 9989383312 10376912896 1059530000 11.452 100 66 131 0 1120114320 46943888 13640432
02:38:44 27236 8192000000 10021985184 10410491904 1064428272 11.499 100 60 118 1 1150788992 74037920 12322800
02:40:24 1549 8192000000 10048423424 10425380864 1087128688 11.715 100 86 164 0 1159702608 55977104 16596816
02:41:28 1879 8192000000 10072939792 10464595968 1093169360 11.773 100 81 152 0 1193504496 82066960 18268176
02:42:34 11812 8192000000 10106338624 10505801728 1113475696 11.965 100 59 113 1 1214191408 88425600 12290112
02:43:48 1492 8192000000 10134693312 10539401216 1132789232 12.148 100 54 100 1 1256853792 112846800 11217760
02:44:56 3687 8192000000 10186436544 10579685376 1140197264 12.217 100 77 148 2 1299708160 142630160 16880736
02:46:02 1023 8192000000 10244614160 10653945856 1153921424 12.346 100 92 178 1 1358828224 185330160 19576640
02:47:06 6460 8192000000 10258309744 10671493120 1170863088 12.505 100 74 149 0 1378739888 191163456 16713344
02:48:17 4076 8192000000 10286139168 10691145728 1183045200 12.619 100 73 142 0 1394625264 195703712 15876352
02:49:24 1131 8192000000 10317378064 10719031296 1184531920 12.632 99 92 177 0 1426552688 223332384 18688384
02:50:27 858 8192000000 10368672048 10778013696 1184547376 12.633 99 97 191 2 1479529696 273542976 21439344
02:51:31 866 8192000000 10403580512 10815524864 1195007280 12.730 100 92 177 0 1513595072 297762544 20825248
02:52:37 899 8192000000 10444553200 10851860480 1200733424 12.783 99 93 180 0 1553764224 331562096 21468704
02:53:42 789 8192000000 10485697264 10901893120 1210620192 12.875 100 95 188 1 1594541104 362146848 21774064
02:54:45 2150 8192000000 10519716864 10938859520 1202831168 12.803 99 92 182 1 1619934976 395520448 21583360
02:55:52 1625 8192000000 10556467024 10964545536 1197758080 12.756 100 98 193 0 1663847008 443790608 22298320
02:56:57 1742 8192000000 10592738272 11037769728 1204435456 12.818 100 94 182 2 1711071696 484795728 21840512
02:58:01 1452 8192000000 10596274944 11040542720 1192452976 12.706 100 94 180 5 1703169856 489662048 21054832
02:59:07 2990 8192000000 10546873552 11043414016 1209533840 12.865 100 65 129 0 1652439760 426297680 16608240
03:00:17 8371 8192000000 10578609648 11039563776 1202206448 12.797 100 42 82 0 1684697776 470759968 11731360
03:01:45 16803 8192000000 10529217968 11003957248 1179103312 12.582 99 42 82 2 1655414400 465046848 11264240
03:03:10 16069 8192000000 10456493280 10950414336 1176117024 12.554 100 35 68 1 1589523456 404559984 8846448
03:04:37 8005 8192000000 10366289312 10893430784 1189417536 12.678 100 46 93 0 1506438112 305706448 11314128
03:05:57 16608 8192000000 10302896352 10805944320 1183565936 12.623 100 35 70 2 1408572896 216131312 8875648
03:07:18 11099 8192000000 10346459872 10830716928 1185618688 12.643 100 28 55 1 1448581232 255786912 7175632
03:08:30 58510 8192000000 10229645600 10675798016 1156700432 12.372 99 16 32 0 1325558640 165559696 3298512
03:10:29 54552 8192000000 10183069408 10710196224 1139187584 12.208 98 15 30 2 1351054624 208295792 3571248
03:12:29 67714 8192000000 10088141360 10528985088 1111434368 11.946 96 30 58 1 1158755520 40885008 6436144
03:14:41 101777 8192000000 10072227792 10519793664 1115131680 11.981 97 13 24 2 1154805936 37829152 1845104
03:17:24 87815 8192000000 10101284208 10554548224 1107706880 11.911 97 25 47 1 1161444832 49344128 4393824
03:19:53 114524 8192000000 10092352208 10532560896 1134777408 12.166 100 39 75 3 1181854928 39253856 7823664
03:22:48 49860 8192000000 10096993552 10549067776 1127284688 12.096 99 42 83 1 1199018720 63225120 8508912
03:24:49 3361 8192000000 10089622528 10536275968 1129132176 12.113 100 42 79 4 1199739904 62041920 8565808
03:25:56 20438 8192000000 10113462832 10551316480 1123482992 12.060 100 38 76 0 1235609152 104203472 7922688
03:27:22 32564 8192000000 10141930976 10591358976 1132992128 12.150 100 43 86 0 1241166976 98382064 9792784
03:28:57 31397 8192000000 10133059840 10610896896 1132497024 12.145 100 33 64 2 1249577472 110268480 6811968
03:30:37 14653 8192000000 10103557152 10565255168 1134782224 12.166 100 18 35 2 1202334160 63781392 3770544
03:31:55 54486 8192000000 10145104592 10580701184 1107403632 11.908 98 12 23 1 1215589616 105870128 2315856
03:33:54 83820 8192000000 10149331824 10595938304 1107072272 11.905 98 14 26 2 1207531344 96516912 3942160
03:36:18 49884 8192000000 10081930240 10519347200 1096533968 11.805 97 35 69 2 1153739344 49421696 7783680
03:38:08 32549 8192000000 10143351984 10586804224 1130179568 12.123 100 36 72 0 1227016672 89848480 6988624
03:39:41 55314 8192000000 10138058272 10605989888 1127597344 12.099 99 18 37 0 1250397264 118606976 4192944
03:41:37 86477 8192000000 10072556672 10534125568 1085222176 11.697 95 29 56 2 1167870656 77115248 5533232
03:44:04 9356 8192000000 10150689264 10563022848 1149911024 12.309 100 83 161 0 1242127280 75055856 17160400
03:45:13 66644 8192000000 10087861840 10531467264 1145643328 12.269 100 25 50 0 1213204352 61966016 5595008
03:47:33 111420 8192000000 10093744512 10554658816 1143994432 12.253 99 38 75 1 1189687488 37737792 7955264
03:50:25 8717 8192000000 10067834416 10517385216 1146003216 12.272 100 17 30 0 1176605808 26999872 3602720
03:51:36 32355 8192000000 10107113104 10590457856 1136704704 12.185 99 28 56 0 1235735696 92888288 6142704
03:53:47 35813 8192000000 10113078768 10554699776 1148042560 12.291 99 27 54 0 1200251104 45764160 6444384
03:55:24 117577 8192000000 10162248240 10628481024 1140503552 12.220 98 25 50 1 1256018400 108763632 6751216
03:58:22 84626 8192000000 10106667872 10556170240 1105099536 11.886 97 21 42 0 1247292208 137043568 5149104
04:00:52 36587 8192000000 10062359200 10511478784 1109950048 11.932 99 38 76 1 1175183456 56792320 8441088
04:02:30 19166 8192000000 10130686672 10562633728 1120124640 12.028 99 41 82 0 1200776448 72418560 8233248
04:03:49 51703 8192000000 10069600336 10533154816 1124640480 12.071 100 21 38 1 1171645008 42989648 4014880
04:05:41 8123 8192000000 10145658272 10579861504 1130328976 12.124 100 51 102 0 1231410656 90743296 10338384
04:06:50 36310 8192000000 10102093728 10582474752 1123728752 12.062 99 27 54 1 1193183520 63488688 5966080
04:08:27 87457 8192000000 10087083136 10593058816 1114030496 11.971 98 14 28 0 1180833440 63542960 3259984
04:10:56 111890 8192000000 10087902528 10528137216 1100085744 11.838 96 25 48 0 1158872320 53944400 4842176
04:13:48 47797 8192000000 10069096320 10536321024 1102154000 11.858 97 22 38 2 1159569152 52602096 4813056
04:15:38 84190 8192000000 10070402016 10544558080 1099901424 11.837 97 15 30 1 1139531440 36295280 3334736
04:18:04 53174 8192000000 10092362752 10544394240 1124655648 12.071 100 18 36 0 1181885712 53359008 3871056
04:20:08 42158 8192000000 10106268256 10560110592 1113188816 11.963 98 21 42 0 1201519456 83771424 4559216
04:21:51 33448 8192000000 10125435568 10566852608 1109895728 11.931 98 27 54 2 1219836352 103494496 6446128
04:23:25 25235 8192000000 10119470672 10552303616 1103245200 11.868 99 34 68 1 1214999472 104302048 7452224
04:24:52 27881 8192000000 10160840960 10611228672 1114589888 11.976 99 43 86 3 1262459376 137739888 10129600
04:26:21 12902 8192000000 10183328208 10611134464 1127352064 12.096 100 50 100 0 1274385712 135518928 11514720
04:27:35 23564 8192000000 10199509744 10664439808 1103390592 11.870 98 45 90 0 1265045904 151396512 10258800
04:29:06 41757 8192000000 10126351696 10659491840 1117503920 12.003 100 24 48 1 1283231328 159978896 5748512
04:30:49 65044 8192000000 10056545904 10511036416 1088135744 11.725 97 22 44 1 1118575904 26243648 4196512
04:33:44 29685 8192000000 10093192176 10530557952 1129828640 12.120 100 45 86 1 1189655024 50964512 8861872
04:35:26 80126 8192000000 10047341104 10519523328 1108623616 11.919 99 12 22 1 1145738384 35125504 1989264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-05-2022 04:39:24 to 04-05-2022 04: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          214774
MON$OLDEST_ACTIVE               215812
MON$OLDEST_SNAPSHOT             215812
MON$NEXT_TRANSACTION            215812
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                       2087456
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2762 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2762 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2762 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2762 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 ---
:89: 
:90: mscs-test-161.budzdorov.ru    Wed May  4 04:37:21 2022
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Wed May  4 04:37:22 2022
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:96: 
:97: 
:98: mscs-test-161.budzdorov.ru    Wed May  4 04:37:25 2022
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:100: 
:101: 
:102: mscs-test-161.budzdorov.ru    Wed May  4 04:37:26 2022
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:104: 
--- end of diff output ---

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

04.05.22 04: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-04 04:39:30 No severe PSQL-related problems occured
04.05.22 04:39:31. End of report.