Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.06.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 15713132 kB
MemAvailable 31252468 kB
Buffers 20 kB
Cached 15926364 kB
SwapCached 2440 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-06-13 04:37:18
DTS_END                         2022-06-13 04:37:18.8150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43694
TRN_ID                          220695
ATT_ID                          620
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: 9113.75 28824 1093650
customer order: creation 165.07 444 19808
customer order: refuse 14.18 377 1702
order to supplier: creation 44.75 1296 5370
order to supplier: removal 3.65 162402 438
invoice (draft): creation 44.86 3360 5383
invoice (draft): removal 53.86 937 6463
invoice accept: apply 39.21 25872 4705
invoice accept: cancel 48.16 1056 5779
customer reserve: creation 3689.90 242 442788
customer reserve: removal 4721.25 98 566550
realization accept: apply 79.83 288 9579
realization accept: cancel 82.57 164 9908
payment from customer: creation 57.30 186 6876
payment from customer: removal 5.82 131 698
payment to supplier: creation 47.71 192 5725
payment to supplier: removal 3.25 131 390
service: total inventory turnovers 3.88 4241 466
service: total monetary turnovers 7.58 327 909
service: aggregate perf. data 0.91 15543 109
service: refresh index statistics 0.03 359190 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-06-2022 04:38:42 to 13-06-2022 04:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6798
TEST_TIME 2 7194
TEST_TIME 3 6163
TEST_TIME 4 6282
TEST_TIME 5 6234
TEST_TIME 6 6308
TEST_TIME 7 6971
TEST_TIME 8 7169
TEST_TIME 9 7516
TEST_TIME 10 7543
TEST_TIME 11 7799
TEST_TIME 12 7705
TEST_TIME 13 7719
TEST_TIME 14 7833
TEST_TIME 15 7914
TEST_TIME 16 7854
TEST_TIME 17 7952
TEST_TIME 18 8058
TEST_TIME 19 8123
TEST_TIME 20 8034
TEST_TIME 21 8187
TEST_TIME 22 8244
TEST_TIME 23 8319
TEST_TIME 24 8346
TEST_TIME 25 8513
TEST_TIME 26 8478
TEST_TIME 27 8802
TEST_TIME 28 8960
TEST_TIME 29 9269
TEST_TIME 30 9415
TEST_TIME 31 9725
TEST_TIME 32 10151
TEST_TIME 33 10726
TEST_TIME 34 10958
TEST_TIME 35 11332
TEST_TIME 36 11187
TEST_TIME 37 11146
TEST_TIME 38 11034
TEST_TIME 39 10962
TEST_TIME 40 10853
TEST_TIME 41 10745
TEST_TIME 42 10600
TEST_TIME 43 10590
TEST_TIME 44 10661
TEST_TIME 45 10562
TEST_TIME 46 10505
TEST_TIME 47 10416
TEST_TIME 48 10587
TEST_TIME 49 10639
TEST_TIME 50 10574
TEST_TIME 51 10535
TEST_TIME 52 10478
TEST_TIME 53 10610
TEST_TIME 54 10431
TEST_TIME 55 10347
TEST_TIME 56 10269
TEST_TIME 57 10135
TEST_TIME 58 10109
TEST_TIME 59 10058
TEST_TIME 60 10002
TEST_TIME 61 9984
TEST_TIME 62 10013
TEST_TIME 63 10056
TEST_TIME 64 9989
TEST_TIME 65 9940
TEST_TIME 66 9841
TEST_TIME 67 9764
TEST_TIME 68 9735
TEST_TIME 69 9664
TEST_TIME 70 9654
TEST_TIME 71 9670
TEST_TIME 72 9660
TEST_TIME 73 9617
TEST_TIME 74 9604
TEST_TIME 75 9598
TEST_TIME 76 9587
TEST_TIME 77 9586
TEST_TIME 78 9611
TEST_TIME 79 9639
TEST_TIME 80 9661
TEST_TIME 81 9707
TEST_TIME 82 9681
TEST_TIME 83 9742
TEST_TIME 84 9689
TEST_TIME 85 9665
TEST_TIME 86 9616
TEST_TIME 87 9574
TEST_TIME 88 9560
TEST_TIME 89 9499
TEST_TIME 90 9431
TEST_TIME 91 9415
TEST_TIME 92 9330
TEST_TIME 93 9341
TEST_TIME 94 9365
TEST_TIME 95 9336
TEST_TIME 96 9322
TEST_TIME 97 9331
TEST_TIME 98 9365
TEST_TIME 99 9349
TEST_TIME 100 9329
TEST_TIME 101 9368
TEST_TIME 102 9432
TEST_TIME 103 9430
TEST_TIME 104 9476
TEST_TIME 105 9460
TEST_TIME 106 9454
TEST_TIME 107 9516
TEST_TIME 108 9461
TEST_TIME 109 9423
TEST_TIME 110 9364
TEST_TIME 111 9401
TEST_TIME 112 9350
TEST_TIME 113 9351
TEST_TIME 114 9315
TEST_TIME 115 9284
TEST_TIME 116 9219
TEST_TIME 117 9183
TEST_TIME 118 9151
TEST_TIME 119 9194
TEST_TIME 120 9138

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-06-2022 04:38:45 to 13-06-2022 04:38:49.

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 1503924 1503924 0 0.00 0 24250 13 0 0
doc_list_biud 1503916 1503916 0 0.00 0 39006 42 0 0
sp_add_invoice_to_stock 4835 4705 130 2.68 32 203991 25872 0 130
sp_cancel_adding_invoice 5779 5779 0 0.00 7 97346 1057 0 0
sp_cancel_client_order 1702 1702 0 0.00 6 8914 378 0 0
sp_cancel_customer_reserve 566550 566550 0 0.00 1 39022 98 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 4 3789 131 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 6896 132 0 0
sp_cancel_supplier_invoice 6474 6463 11 0.16 3 17210 937 0 11
sp_cancel_supplier_order 438 438 0 0.00 11 1516890 162403 0 0
sp_cancel_write_off 9908 9908 0 0.00 2 22408 164 0 0
sp_client_order 19808 19808 0 0.00 6 21173 444 0 0
sp_customer_reserve 442788 442788 0 0.00 1 49606 243 0 0
sp_fill_shopping_cart 30549 30549 0 0.00 2 16134 81 0 0
sp_fill_shopping_cart_clo_res 442756 442756 0 0.00 0 14773 18 0 0
sp_get_clo_for_invoice 4661 4661 0 0.00 0 28383 280 0 0
sp_kill_cost_storno 16775 16775 0 0.00 0 12225 73 0 0
sp_kill_qstorno_ret_qs2qd 1149909 1149909 0 0.00 0 39000 41 0 0
sp_kill_qty_storno 605079 605079 0 0.00 0 39004 96 0 0
sp_lock_selected_doc 19857 19857 0 0.00 0 8098 9 0 0
sp_make_cost_storno 26840 26840 0 0.00 0 19948 84 0 0
sp_multiply_rows_for_pdistr 26840 26840 0 0.00 0 11264 42 0 0
sp_multiply_rows_for_qdistr 19807 19807 0 0.00 0 14895 157 0 0
sp_pay_from_customer 6876 6876 0 0.00 5 21674 187 0 0
sp_pay_to_supplier 5725 5725 0 0.00 6 17928 193 0 0
sp_payment_common 12601 12601 0 0.00 2 11220 46 0 0
sp_qd_handle_on_cancel_clo 1702 1702 0 0.00 1 8904 302 0 0
sp_qd_handle_on_invoice_upd_sts 10439 10439 0 0.00 0 15181 508 0 0
sp_qd_handle_on_reserve_upd_sts 9579 9579 0 0.00 0 8810 58 0 0
sp_reserve_write_off 9579 9579 0 0.00 9 13014 289 0 0
sp_supplier_invoice 5383 5383 0 0.00 9 99663 3361 0 0
sp_supplier_order 5370 5370 0 0.00 8 23046 1296 0 0
srv_aggregate_perf_data 109 109 0 0.00 2719 163191 15544 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 834 126486 34699 0 0
srv_find_qd_qs_mism 1047666 1047666 0 0.00 0 19413 10 0 0
srv_make_invnt_saldo 466 466 0 0.00 46 154644 4242 0 0
srv_make_money_saldo 909 909 0 0.00 1 17261 328 0 0
srv_recalc_idx_stat 4 4 0 0.00 62149 647501 359190 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4711 4711 0 0.00 1 15275 988 0 0
t$perf-norm:sp_cancel_adding_invoice 363 363 0 0.00 1 7448 462 0 0
t$perf-norm:sp_cancel_client_order 1702 1702 0 0.00 0 14335 35 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 7050 50 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 3156 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 326 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 2523 43 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 87069 6172 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 5369 27 0 0
t$perf-norm:sp_client_order 19808 19808 0 0.00 0 15537 39 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 1540 17 0 0
t$perf-norm:sp_pay_from_customer 6876 6876 0 0.00 0 16235 24 0 0
t$perf-norm:sp_pay_to_supplier 5725 5725 0 0.00 0 8735 22 0 0
t$perf-norm:sp_reserve_write_off 9579 9579 0 0.00 0 17682 27 0 0
t$perf-norm:sp_supplier_invoice 5383 5383 0 0.00 0 16998 35 0 0
t$perf-norm:sp_supplier_order 5370 5370 0 0.00 0 4551 31 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 201 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 525 12 0 0
t$perf-norm:srv_make_invnt_saldo 466 466 0 0.00 0 579 11 0 0
t$perf-norm:srv_make_money_saldo 909 909 0 0.00 0 5216 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 183 60 0 0
v_add_invoice_to_stock 9570 9310 260 2.71 0 3381 25 0 260
v_all_customers 36352 36352 0 0.00 0 86 4 0 0
v_all_suppliers 30286 30286 0 0.00 0 158 4 0 0
v_all_wares 39608 39608 0 0.00 0 54 4 0 0
v_cancel_adding_invoice 726 726 0 0.00 0 231 12 0 0
v_cancel_client_order 3404 3404 0 0.00 0 6874 11 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 3752 13 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 1 836 12 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 6870 25 0 0
v_cancel_supplier_invoice 832 810 22 2.64 0 353 24 0 22
v_cancel_supplier_order 876 876 0 0.00 0 946 11 0 0
v_cancel_write_off 1938 1938 0 0.00 0 6665 30 0 0
v_max_id_clo_ord 5369 5369 0 0.00 0 833 5 0 0
v_max_id_clo_res 949 949 0 0.00 0 1868 11 0 0
v_max_id_ord_sup 5374 5374 0 0.00 0 5785 10 0 0
v_max_non_paid_invoice 1325 1325 0 0.00 0 2039 9 0 0
v_max_non_paid_realizn 3631 3631 0 0.00 0 2240 8 0 0
v_min_id_clo_ord 5369 5369 0 0.00 0 1635 8 0 0
v_min_id_clo_res 949 949 0 0.00 0 613 8 0 0
v_min_id_ord_sup 5374 5374 0 0.00 0 4011 9 0 0
v_min_non_paid_invoice 5725 5725 0 0.00 0 6252 15 0 0
v_min_non_paid_realizn 6875 6875 0 0.00 0 11210 18 0 0
v_our_firm 9746 9746 0 0.00 0 86 5 0 0
v_reserve_write_off 19154 19154 0 0.00 0 6878 12 0 0
x_lock_depdocs_on_canc_invoice 11837 11837 0 0.00 0 25756 117 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 3230 308 0 0
x_make_qstorno_1000_1200 5370 5370 0 0.00 3 22911 1130 0 0
x_make_qstorno_1200_2000 5382 5382 0 0.00 4 99649 3156 0 0
x_make_qstorno_2100_3300 442785 442785 0 0.00 0 46378 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-06-2022 04:38:50 to 13-06-2022 04:38:54.

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:22 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 29117 8192000000 9709349360 10007334912 787782400 8.772 100 81 162 0 815202832 7581232 19839200
02:09:58 27585 8192000000 9758511808 10067959808 831805648 9.217 100 86 172 0 864301728 12325328 20170752
02:11:33 17854 8192000000 9805524144 10124783616 870607888 9.606 100 84 168 0 911589968 21850880 19131200
02:12:56 10564 8192000000 9865255232 10198609920 913210304 10.029 100 82 164 0 972831008 41510688 18110016
02:14:08 4879 8192000000 9929217344 10271305728 962326784 10.512 100 92 179 0 1047646752 66983200 18336768
02:15:16 1358 8192000000 9972645536 10327678976 990109968 10.783 100 93 181 2 1086274720 77855936 18308816
02:16:21 1234 8192000000 9990047776 10351529984 1022330592 11.095 100 95 179 0 1111685168 71135088 18219488
02:17:24 1055 8192000000 10035288480 10407395328 1051531888 11.375 100 97 190 1 1157863584 86541904 19789792
02:18:26 1027 8192000000 10024527360 10402344960 1061611296 11.472 100 92 174 0 1144420912 65511056 17298560
02:19:28 10268 8192000000 10032078528 10416078848 1071725536 11.569 100 67 119 1 1162825424 80026352 11073536
02:20:39 1002 8192000000 10056380736 10441158656 1074766400 11.598 100 98 191 0 1180518864 87117744 18634720
02:21:40 1314 8192000000 10048776224 10430353408 1082046736 11.667 100 97 191 0 1165519280 64925472 18547072
02:22:42 1449 8192000000 10057790224 10450173952 1088022096 11.724 100 74 143 0 1171330416 69029712 14278608
02:23:45 1146 8192000000 10059747952 10445795328 1095769296 11.797 100 94 181 0 1179882416 65616864 18496256
02:24:47 1274 8192000000 10076808688 10464354304 1095856512 11.798 100 98 187 0 1195570128 81161216 18552400
02:25:50 1220 8192000000 10047672384 10440314880 1096732848 11.807 100 85 163 2 1169840928 55585856 17522224
02:26:52 1179 8192000000 10055607456 10441744384 1097395136 11.813 100 93 179 1 1177297216 62039520 17862560
02:27:55 1200 8192000000 10067810368 10455535616 1093239904 11.773 100 93 172 0 1186844480 75911312 17693264
02:28:57 1028 8192000000 10036030176 10421006336 1077078976 11.620 100 91 167 2 1156608688 62329584 17200128
02:29:58 1004 8192000000 10017675088 10397392896 1058486800 11.442 100 94 179 1 1137704448 60579552 18638096
02:31:00 1324 8192000000 10012658704 10387410944 1048389744 11.345 100 96 187 0 1129980752 63267696 18323312
02:32:03 1207 8192000000 9988078848 10359992320 1039867392 11.263 100 97 184 2 1108680880 48912592 19900896
02:33:05 1284 8192000000 10027471488 10401419264 1048777200 11.349 100 96 184 4 1150510704 82990752 18742752
02:34:07 1241 8192000000 9991769488 10361311232 1028418512 11.153 99 98 188 1 1113194000 66741568 18033920
02:35:09 7916 8192000000 9989207840 10386661376 1045115808 11.314 100 73 143 1 1119961536 60385424 14460304
02:36:18 1070 8192000000 9974722608 10351828992 1032451088 11.192 100 86 167 2 1095538976 46143360 16944528
02:37:20 1321 8192000000 10002915104 10380988416 1049796688 11.359 100 84 156 2 1121288992 56015776 15476528
02:38:28 4472 8192000000 10021335792 10406195200 1066414048 11.518 100 71 130 3 1151959312 72920704 12624560
02:39:39 9021 8192000000 10061442512 10450923520 1089998432 11.743 100 78 149 0 1195075952 89890624 15186896
02:40:51 1669 8192000000 10056149056 10438549504 1088272608 11.726 100 85 165 0 1173670400 68845168 16552624
02:41:56 6036 8192000000 10101725840 10493693952 1099300672 11.831 100 74 142 2 1222948144 108446960 15200512
02:43:07 2904 8192000000 10126079168 10528837632 1115777696 11.987 100 81 154 1 1241106400 108348080 16980624
02:44:13 969 8192000000 10145517296 10541117440 1115624720 11.986 100 93 183 1 1261413120 126839184 18949216
02:45:17 1114 8192000000 10169966512 10561515520 1129629664 12.118 100 95 185 3 1283425664 133813760 19982240
02:46:21 5623 8192000000 10162961536 10571902976 1129839152 12.120 100 80 156 2 1277043392 130609200 16595040
02:47:31 16933 8192000000 10196472656 10597597184 1146556800 12.277 100 68 128 2 1297780864 136539472 14684592
02:48:50 1145 8192000000 10236123872 10637627392 1168418128 12.482 100 97 184 4 1348313312 159324048 20571136
02:49:54 1482 8192000000 10280492416 10683711488 1186219584 12.648 100 88 175 0 1390135952 183877568 20038800
02:50:59 913 8192000000 10309148656 10729734144 1186992992 12.655 100 84 160 2 1416510640 209911488 19606160
02:52:03 933 8192000000 10363559312 10773966848 1184295824 12.630 100 100 196 1 1475875840 267690336 23889680
02:53:08 2403 8192000000 10364127216 10777362432 1180418160 12.594 100 88 169 0 1469108336 269281424 19408752
02:54:19 4593 8192000000 10389194704 10818547712 1178475728 12.576 100 78 155 1 1490243040 294212480 17554832
02:55:28 1038 8192000000 10414603248 10842550272 1187013920 12.656 99 89 175 0 1520001600 311370224 21617456
02:56:30 1035 8192000000 10470958384 10880700416 1197482592 12.753 100 97 192 1 1575716624 355756352 22477680
02:57:35 825 8192000000 10501792304 10918825984 1192440048 12.706 100 97 190 1 1607616400 391999744 23176608
02:58:37 869 8192000000 10534459168 10936713216 1190464272 12.688 100 98 195 1 1634803216 419826704 24512240
02:59:41 1177 8192000000 10531937024 10965757952 1184424832 12.631 100 78 153 0 1641388384 437033184 19930368
03:00:48 2253 8192000000 10574007088 11003420672 1199068432 12.768 100 62 124 0 1676484352 461033104 16382816
03:01:52 8348 8192000000 10615484976 11045527552 1180429728 12.594 98 59 118 0 1704193200 507908288 15855184
03:03:03 5020 8192000000 10628204688 11051442176 1176804896 12.560 100 68 134 0 1729683712 535004272 17874544
03:04:25 10521 8192000000 10648271552 11154776064 1181241904 12.602 100 51 101 2 1782523312 587527984 13753424
03:05:38 12845 8192000000 10662628032 11122163712 1180292160 12.593 100 46 92 1 1766039744 573250752 12496832
03:07:12 15694 8192000000 10575011248 11083276288 1162640400 12.428 100 38 74 1 1683128160 510352704 10135056
03:08:33 41455 8192000000 10467880048 10979373056 1151270992 12.321 100 30 60 0 1625980496 466541856 8167648
03:10:16 32772 8192000000 10316469456 10829930496 1126725136 12.090 98 23 46 2 1422805632 289749424 6331072
03:11:50 17716 8192000000 10163724368 10721640448 1135914000 12.177 99 17 34 0 1293518864 153566240 4038624
03:13:18 51382 8192000000 10073744688 10536837120 1133166096 12.151 99 37 65 2 1204262336 63772032 7324208
03:15:10 44083 8192000000 10092940736 10555019264 1099108560 11.829 96 9 18 0 1148879232 48178288 1592384
03:16:55 90642 8192000000 10084315328 10507407360 1124210288 12.067 98 41 77 2 1193466320 60253808 9002224
03:19:27 30825 8192000000 10146996480 10583486464 1147389872 12.285 99 40 76 1 1241839728 85542464 8907392
03:21:01 87228 8192000000 10115324928 10587770880 1102864032 11.865 96 27 50 4 1163258880 56059424 4335424
03:23:49 88124 8192000000 10181323936 10658394112 1138193184 12.199 99 14 28 0 1235802064 94547968 3060912
03:26:18 93040 8192000000 10104347520 10546339840 1124178176 12.066 98 10 19 1 1165974096 40117536 1678384
03:28:58 123792 8192000000 10073157056 10564919296 1082420944 11.671 94 10 20 0 1146464624 61417888 2625792
03:32:12 77635 8192000000 10076438736 10508431360 1095664848 11.796 96 35 66 1 1126773696 24070320 7038528
03:34:30 27528 8192000000 10124487648 10557145088 1137095744 12.188 100 49 97 0 1221055984 74110672 9849568
03:36:16 36185 8192000000 10108768880 10558242816 1140566400 12.221 100 39 77 2 1222537200 73565840 8404960
03:37:57 24400 8192000000 10098929792 10546442240 1128539008 12.108 99 47 89 1 1204284336 66434368 9310960
03:39:23 46940 8192000000 10119656592 10610581504 1145875632 12.271 100 21 42 2 1242074992 91999760 4199600
03:41:11 10507 8192000000 10061021312 10553049088 1121530336 12.041 98 15 30 1 1150888704 26572880 2785488
03:42:21 25538 8192000000 10050429808 10510520320 1119684144 12.024 99 12 24 1 1145304944 23128704 2492096
03:43:48 63943 8192000000 10095505840 10563551232 1139713616 12.213 100 15 28 2 1183691648 41414848 2563184
03:45:56 74159 8192000000 10070205040 10561400832 1112314096 11.954 97 12 24 0 1176305664 61278736 2712832
03:48:23 62472 8192000000 10064489744 10571485184 1112726976 11.958 98 15 30 0 1154614928 38519312 3368640
03:50:26 18029 8192000000 10062735840 10506309632 1116668912 11.996 99 30 60 2 1150672384 27876672 6126800
03:51:46 12917 8192000000 10073840288 10504577024 1088042784 11.724 98 37 74 0 1151882272 56624960 7214528
03:53:10 50980 8192000000 10113062176 10574426112 1097426768 11.813 97 24 48 0 1181303856 78085008 5792080
03:55:08 43706 8192000000 10103704720 10546548736 1105129040 11.886 99 25 49 0 1186848592 76186832 5532720
03:56:52 23444 8192000000 10082937872 10539102208 1116149120 11.991 100 33 66 1 1173225632 49842928 7233584
03:58:20 56622 8192000000 10093620128 10541600768 1126018224 12.084 100 22 44 0 1184867824 54132656 4716944
04:00:17 59403 8192000000 10088673184 10526744576 1132910384 12.149 100 25 48 1 1185555744 47792256 4853104
04:02:17 126485 8192000000 10069707680 10537295872 1121480656 12.041 99 31 62 0 1213851408 85995888 6374864
04:05:25 88347 8192000000 10073128976 10556612608 1112562128 11.957 98 22 44 1 1177123200 59886288 4674784
04:08:35 68690 8192000000 10101586160 10554163200 1110257984 11.935 97 13 26 0 1173802880 60471968 3072928
04:11:18 66726 8192000000 10074083392 10523004928 1109625136 11.929 98 15 30 0 1149551760 36506240 3420384
04:13:25 31762 8192000000 10118158096 10574696448 1150529312 12.314 100 31 62 1 1220190528 63066832 6594384
04:15:02 55530 8192000000 10082705728 10551095296 1107272672 11.907 98 27 52 1 1170546592 58158192 5115728
04:16:58 21165 8192000000 10117844896 10560352256 1142303968 12.237 100 38 76 0 1195906016 45628032 7974016
04:18:25 24585 8192000000 10143264304 10615357440 1136660800 12.184 100 42 84 0 1244082832 98670736 8751296
04:20:05 31153 8192000000 10107681328 10560978944 1135166096 12.170 99 44 88 1 1221544656 77114336 9264224
04:21:36 26533 8192000000 10094065920 10532741120 1126899232 12.092 98 40 80 0 1165870160 30466592 8504336
04:23:03 23967 8192000000 10138958880 10580733952 1148030832 12.291 100 41 82 1 1226218944 69979264 8208848
04:24:36 97568 8192000000 10072069552 10551103488 1135113296 12.170 100 14 28 0 1201960944 63692304 3155344
04:27:17 26622 8192000000 10089029280 10525716480 1133876720 12.158 100 40 80 1 1178203984 36567072 7760192
04:28:55 84555 8192000000 10073848256 10552967168 1132277424 12.143 99 9 18 1 1196837616 62455584 2104608
04:32:17 48044 8192000000 10066638640 10528952320 1139764048 12.213 100 7 13 0 1165450720 23792608 1894064
04:34:06 106389 8192000000 10129814192 10625507328 1141163856 12.226 100 17 32 3 1220172400 75247568 3760976
04:36:52 33296 8192000000 9221299280 9525112832 363738352 4.251 33 2 4 0 370407136 6440096 228688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-06-2022 04:38:56 to 13-06-2022 04:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-06-2022 04:39:13 to 13-06-2022 04:39:15.

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          220471
MON$OLDEST_ACTIVE               221017
MON$OLDEST_SNAPSHOT             221017
MON$NEXT_TRANSACTION            221017
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                       2095808
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2776 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2776 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2776 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2776 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 ---
:129: 
:130: mscs-test-161.budzdorov.ru    Mon Jun 13 04:37:28 2022
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:132: 
:133: 
:134: mscs-test-161.budzdorov.ru    Mon Jun 13 04:37:28 2022
:135:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:136: 
--- end of diff output ---

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

13.06.22 04:39:16. 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-06-13 04:39:18 No severe PSQL-related problems occured
13.06.22 04:39:20. End of report.