Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.06.2022 19:39
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 16380260 kB
MemAvailable 31323808 kB
Buffers 0 kB
Cached 15372196 kB
SwapCached 2444 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 3.0.10
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 19:38:24
DTS_END                         2022-06-13 19:38:24.2470
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(57:17)
IP                              ::1/38402
TRN_ID                          243317
ATT_ID                          664
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: 9259.04 21282 1111085
customer order: creation 180.02 315 21602
customer order: refuse 15.12 312 1814
order to supplier: creation 47.33 1313 5679
order to supplier: removal 3.54 87967 425
invoice (draft): creation 47.85 3298 5742
invoice (draft): removal 51.28 734 6153
invoice accept: apply 43.25 16580 5190
invoice accept: cancel 45.96 830 5515
customer reserve: creation 3987.46 164 478495
customer reserve: removal 4539.84 53 544781
realization accept: apply 86.93 157 10431
realization accept: cancel 74.79 99 8975
payment from customer: creation 62.64 108 7517
payment from customer: removal 5.98 72 717
payment to supplier: creation 50.68 111 6082
payment to supplier: removal 3.73 54 447
service: total inventory turnovers 4.93 1023 591
service: total monetary turnovers 6.85 27 822
service: aggregate perf. data 0.86 16931 103
service: refresh index statistics 0.03 295494 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-06-2022 19:40:07 to 13-06-2022 19:40:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5110
TEST_TIME 2 5069
TEST_TIME 3 5257
TEST_TIME 4 5871
TEST_TIME 5 5389
TEST_TIME 6 5446
TEST_TIME 7 6029
TEST_TIME 8 5894
TEST_TIME 9 6179
TEST_TIME 10 6984
TEST_TIME 11 6978
TEST_TIME 12 6990
TEST_TIME 13 6929
TEST_TIME 14 7452
TEST_TIME 15 7840
TEST_TIME 16 7970
TEST_TIME 17 8491
TEST_TIME 18 8520
TEST_TIME 19 8795
TEST_TIME 20 9013
TEST_TIME 21 9327
TEST_TIME 22 9205
TEST_TIME 23 9438
TEST_TIME 24 9434
TEST_TIME 25 9912
TEST_TIME 26 9966
TEST_TIME 27 10419
TEST_TIME 28 10361
TEST_TIME 29 10432
TEST_TIME 30 10299
TEST_TIME 31 10440
TEST_TIME 32 10458
TEST_TIME 33 10413
TEST_TIME 34 10126
TEST_TIME 35 9921
TEST_TIME 36 9963
TEST_TIME 37 10098
TEST_TIME 38 9963
TEST_TIME 39 9818
TEST_TIME 40 9731
TEST_TIME 41 9632
TEST_TIME 42 9558
TEST_TIME 43 9651
TEST_TIME 44 9552
TEST_TIME 45 9552
TEST_TIME 46 9554
TEST_TIME 47 9545
TEST_TIME 48 9559
TEST_TIME 49 9476
TEST_TIME 50 9580
TEST_TIME 51 9527
TEST_TIME 52 9592
TEST_TIME 53 9593
TEST_TIME 54 9659
TEST_TIME 55 9579
TEST_TIME 56 9705
TEST_TIME 57 9667
TEST_TIME 58 9664
TEST_TIME 59 9748
TEST_TIME 60 9791
TEST_TIME 61 9841
TEST_TIME 62 9754
TEST_TIME 63 9630
TEST_TIME 64 9580
TEST_TIME 65 9560
TEST_TIME 66 9508
TEST_TIME 67 9448
TEST_TIME 68 9377
TEST_TIME 69 9455
TEST_TIME 70 9363
TEST_TIME 71 9397
TEST_TIME 72 9343
TEST_TIME 73 9308
TEST_TIME 74 9289
TEST_TIME 75 9309
TEST_TIME 76 9312
TEST_TIME 77 9273
TEST_TIME 78 9261
TEST_TIME 79 9320
TEST_TIME 80 9345
TEST_TIME 81 9330
TEST_TIME 82 9356
TEST_TIME 83 9371
TEST_TIME 84 9349
TEST_TIME 85 9385
TEST_TIME 86 9406
TEST_TIME 87 9427
TEST_TIME 88 9388
TEST_TIME 89 9327
TEST_TIME 90 9261
TEST_TIME 91 9296
TEST_TIME 92 9229
TEST_TIME 93 9169
TEST_TIME 94 9145
TEST_TIME 95 9118
TEST_TIME 96 9290
TEST_TIME 97 9322
TEST_TIME 98 9281
TEST_TIME 99 9332
TEST_TIME 100 9313
TEST_TIME 101 9360
TEST_TIME 102 9340
TEST_TIME 103 9398
TEST_TIME 104 9421
TEST_TIME 105 9501
TEST_TIME 106 9481
TEST_TIME 107 9460
TEST_TIME 108 9484
TEST_TIME 109 9472
TEST_TIME 110 9435
TEST_TIME 111 9430
TEST_TIME 112 9395
TEST_TIME 113 9380
TEST_TIME 114 9389
TEST_TIME 115 9362
TEST_TIME 116 9338
TEST_TIME 117 9303
TEST_TIME 118 9295
TEST_TIME 119 9271
TEST_TIME 120 9264

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-06-2022 19:40:11 to 13-06-2022 19:40:14.

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 1554054 1554054 0 0.00 0 15246 6 0 0
doc_list_biud 1554051 1554051 0 0.00 0 41504 24 0 0
sp_add_invoice_to_stock 5341 5190 151 2.82 41 170239 16581 0 151
sp_cancel_adding_invoice 5515 5515 0 0.00 4 91128 830 0 0
sp_cancel_client_order 1814 1814 0 0.00 4 12438 313 0 0
sp_cancel_customer_reserve 544781 544781 0 0.00 1 31754 54 0 0
sp_cancel_pay_from_customer 717 717 0 0.00 2 3950 73 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 1614 55 0 0
sp_cancel_supplier_invoice 6162 6153 9 0.14 1 24131 734 0 9
sp_cancel_supplier_order 425 425 0 0.00 8 895447 87968 0 0
sp_cancel_write_off 8975 8975 0 0.00 1 10895 100 0 0
sp_client_order 21602 21602 0 0.00 3 23032 315 0 0
sp_customer_reserve 478495 478495 0 0.00 0 122330 165 0 0
sp_fill_shopping_cart 33011 33011 0 0.00 1 12954 37 0 0
sp_fill_shopping_cart_clo_res 478478 478478 0 0.00 0 29758 11 0 0
sp_get_clo_for_invoice 5173 5173 0 0.00 0 36040 268 0 0
sp_kill_cost_storno 15654 15654 0 0.00 0 7634 52 0 0
sp_kill_qstorno_ret_qs2qd 1105115 1105115 0 0.00 0 31535 29 0 0
sp_kill_qty_storno 583267 583267 0 0.00 0 41500 64 0 0
sp_lock_selected_doc 21594 21594 0 0.00 0 8045 5 0 0
sp_make_cost_storno 29203 29203 0 0.00 0 12333 47 0 0
sp_multiply_rows_for_pdistr 29203 29203 0 0.00 0 7094 26 0 0
sp_multiply_rows_for_qdistr 21602 21602 0 0.00 0 10944 138 0 0
sp_pay_from_customer 7517 7517 0 0.00 4 9535 109 0 0
sp_pay_to_supplier 6082 6082 0 0.00 5 9443 111 0 0
sp_payment_common 13599 13599 0 0.00 1 7263 23 0 0
sp_qd_handle_on_cancel_clo 1814 1814 0 0.00 0 12433 292 0 0
sp_qd_handle_on_invoice_upd_sts 10688 10688 0 0.00 0 41498 346 0 0
sp_qd_handle_on_reserve_upd_sts 10431 10431 0 0.00 0 17527 47 0 0
sp_reserve_write_off 10431 10431 0 0.00 6 17548 157 0 0
sp_supplier_invoice 5742 5742 0 0.00 7 100513 3298 0 0
sp_supplier_order 5679 5679 0 0.00 5 41854 1313 0 0
srv_aggregate_perf_data 103 103 0 0.00 2791 129204 16931 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 3092 127819 47034 0 0
srv_find_qd_qs_mism 1061284 1061284 0 0.00 0 15246 5 0 0
srv_make_invnt_saldo 591 591 0 0.00 49 56659 1023 0 0
srv_make_money_saldo 822 822 0 0.00 1 1079 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 116889 470899 295494 0 0
t$perf-abend:sp_add_invoice_to_stock 151 151 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5190 5190 0 0.00 0 16947 165 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 11362 105 0 0
t$perf-norm:sp_cancel_client_order 1814 1814 0 0.00 0 3735 16 0 0
t$perf-norm:sp_cancel_customer_reserve 757 757 0 0.00 0 3142 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 717 717 0 0.00 0 5205 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 7740 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 4462 23 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 28863 1713 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 746 4 0 0
t$perf-norm:sp_client_order 21602 21602 0 0.00 0 7066 16 0 0
t$perf-norm:sp_customer_reserve 1087 1087 0 0.00 0 2130 8 0 0
t$perf-norm:sp_pay_from_customer 7517 7517 0 0.00 0 9413 14 0 0
t$perf-norm:sp_pay_to_supplier 6082 6082 0 0.00 0 13746 11 0 0
t$perf-norm:sp_reserve_write_off 10431 10431 0 0.00 0 9390 13 0 0
t$perf-norm:sp_supplier_invoice 5742 5742 0 0.00 0 9498 17 0 0
t$perf-norm:sp_supplier_order 5679 5679 0 0.00 0 8844 19 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 227 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 3221 54 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 5477 43 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 810 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10648 10346 302 2.83 0 5111 16 0 302
v_all_customers 39054 39054 0 0.00 0 17 1 0 0
v_all_suppliers 31148 31148 0 0.00 0 15 1 0 0
v_all_wares 43198 43198 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 1939 11 0 0
v_cancel_client_order 3628 3628 0 0.00 0 2547 5 0 0
v_cancel_customer_prepayment 1434 1434 0 0.00 0 1640 4 0 0
v_cancel_customer_reserve 1514 1514 0 0.00 0 4374 10 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 1600 6 0 0
v_cancel_supplier_invoice 870 852 18 2.06 0 4761 24 0 18
v_cancel_supplier_order 850 850 0 0.00 0 5230 11 0 0
v_cancel_write_off 2002 2002 0 0.00 0 5904 17 0 0
v_max_id_clo_ord 5677 5677 0 0.00 0 367 1 0 0
v_max_id_clo_res 1087 1087 0 0.00 0 1489 12 0 0
v_max_id_ord_sup 5735 5735 0 0.00 0 7494 5 0 0
v_max_non_paid_invoice 1920 1920 0 0.00 0 1186 3 0 0
v_max_non_paid_realizn 4222 4222 0 0.00 0 1605 3 0 0
v_min_id_clo_ord 5677 5677 0 0.00 0 2385 6 0 0
v_min_id_clo_res 1087 1087 0 0.00 0 4108 14 0 0
v_min_id_ord_sup 5735 5735 0 0.00 0 10073 7 0 0
v_min_non_paid_invoice 6082 6082 0 0.00 0 5094 14 0 0
v_min_non_paid_realizn 7517 7517 0 0.00 0 6925 18 0 0
v_our_firm 10734 10734 0 0.00 0 94 1 0 0
v_reserve_write_off 20862 20862 0 0.00 0 5235 6 0 0
x_lock_depdocs_on_canc_invoice 11242 11242 0 0.00 0 15458 115 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 21396 357 0 0
x_make_qstorno_1000_1200 5679 5679 0 0.00 1 41850 1233 0 0
x_make_qstorno_1200_2000 5742 5742 0 0.00 3 100488 3166 0 0
x_make_qstorno_2100_3300 478495 478495 0 0.00 0 122328 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-06-2022 19:40:15 to 13-06-2022 19:40:19.

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
17:08:28 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:28 26185 8192000000 9896777232 10203693056 975044112 10.636 100 86 172 0 1003635360 5619760 22971488
17:10:57 40760 8192000000 9955290320 10276597760 1034765760 11.214 100 87 174 0 1065889472 8395936 22727776
17:12:41 20839 8192000000 9990941360 10322833408 1070100048 11.553 100 87 174 2 1102153712 10465488 21588176
17:14:06 8783 8192000000 10043667840 10393034752 1128235200 12.105 100 79 158 0 1158485664 11676096 18574368
17:15:18 6349 8192000000 10089330592 10449240064 1174674192 12.540 100 72 145 0 1202332496 10740256 16918048
17:16:28 5196 8192000000 10136230672 10510176256 1223626160 12.995 100 78 155 1 1256939568 15908016 17405392
17:17:34 3314 8192000000 10176843760 10553409536 1261616880 13.345 100 87 176 0 1295991632 15303776 19070976
17:18:40 3026 8192000000 10201052064 10581229568 1282856944 13.539 100 96 192 1 1318155568 13666896 21631728
17:19:44 1878 8192000000 10224360864 10609790976 1305676608 13.747 100 90 180 2 1338983968 12025184 21282176
17:20:50 2043 8192000000 10230174464 10620080128 1309951008 13.786 100 86 167 0 1341185600 12338976 18895616
17:21:53 7064 8192000000 10214129792 10616373248 1314299488 13.825 100 31 61 2 1328592656 6862768 7430400
17:23:02 18547 8192000000 10209189072 10625273856 1319978432 13.877 100 30 60 2 1333207344 6046928 7181984
17:24:26 5102 8192000000 10224908848 10638884864 1327506640 13.945 100 26 48 3 1343709408 10542336 5660432
17:25:31 7867 8192000000 10225765648 10639626240 1333252048 13.997 100 28 54 0 1346051232 6444352 6354832
17:26:40 15791 8192000000 10223047680 10637459456 1331653088 13.982 100 32 63 5 1345411968 5491472 8267408
17:27:57 24965 8192000000 10226018256 10638094336 1327457920 13.944 100 29 57 2 1338740512 5054992 6227600
17:29:24 4102 8192000000 10201297696 10604617728 1272933968 13.448 97 36 72 0 1290778640 9221776 8622896
17:30:32 8251 8192000000 10174690480 10579914752 1271661120 13.437 99 30 60 1 1284214272 5186432 7366720
17:31:42 67833 8192000000 10139894352 10545483776 1185791584 12.644 93 30 61 0 1197373952 4256720 7325648
17:33:50 7319 8192000000 10158607856 10556424192 1250482464 13.243 99 26 52 2 1263365104 5338240 7544400
17:34:59 10941 8192000000 10173305712 10581401600 1272523968 13.445 99 21 42 0 1282346480 5140672 4681840
17:36:11 7222 8192000000 10189194208 10601680896 1298725072 13.684 100 12 24 0 1307199296 5723312 2750912
17:37:20 37664 8192000000 10210603664 10622558208 1307506064 13.763 100 26 51 0 1323039520 10105120 5428336
17:39:02 16639 8192000000 10251259472 10663235584 1343527600 14.089 100 44 88 0 1361467600 8195264 9744736
17:40:23 8523 8192000000 10279516160 10691919872 1363091536 14.265 100 53 106 0 1390406272 15157952 12156784
17:41:32 6380 8192000000 10287647824 10702913536 1378875376 14.406 100 57 115 2 1403701568 11710960 13115232
17:42:51 39645 8192000000 10295559776 10719924224 1387006224 14.479 100 38 76 0 1407089680 10784688 9298768
17:44:48 6215 8192000000 10322640912 10746744832 1399788304 14.593 100 62 119 4 1432148624 17803040 14557280
17:45:58 7856 8192000000 10334679792 10753150976 1404089760 14.631 100 67 133 2 1441356128 21046800 16219568
17:47:12 22709 8192000000 10337375552 10767089664 1408008976 14.666 100 46 92 3 1444013504 23683088 12321440
17:48:39 14911 8192000000 10331466096 10758746112 1402176064 14.614 99 58 114 3 1438564112 22144896 14243152
17:50:06 3490 8192000000 10334948416 10758709248 1394199760 14.543 99 77 152 1 1431981760 20208096 17573904
17:51:15 11283 8192000000 10337259472 10764443648 1411702368 14.699 100 53 106 0 1447922752 23291392 12928992
17:52:30 7457 8192000000 10352444800 10770518016 1410123664 14.685 100 79 158 4 1455684912 26124768 19436480
17:53:40 8949 8192000000 10317789808 10744467456 1367117216 14.301 98 41 82 2 1398451648 20757504 10576928
17:54:53 4197 8192000000 10322559024 10730512384 1371734160 14.343 100 77 153 1 1420268160 30084016 18449984
17:56:01 6144 8192000000 10308503440 10730057728 1375405776 14.375 100 42 84 0 1413261696 26762144 11093776
17:57:16 3087 8192000000 10316764768 10721292288 1360652528 14.243 99 84 169 0 1410190752 29545584 19992640
17:58:23 3656 8192000000 10323999040 10737684480 1380836448 14.424 100 68 136 1 1429641120 31560384 17244288
17:59:30 6817 8192000000 10314441200 10730237952 1375709504 14.378 100 64 128 0 1420802784 29027280 16066000
18:00:49 3484 8192000000 10303877376 10717904896 1362047376 14.256 99 62 123 0 1402733808 24955504 15730928
18:01:56 9540 8192000000 10282390112 10715222016 1350578448 14.153 99 28 56 0 1384904480 26600848 7725184
18:03:14 32185 8192000000 10285526624 10722283520 1350284656 14.150 99 20 40 0 1384904208 29367968 5251584
18:04:52 3345 8192000000 10278478944 10697625600 1337910992 14.039 99 55 110 0 1375692336 24557808 13223536
18:05:58 37310 8192000000 10254525104 10693767168 1334491520 14.008 99 17 33 1 1353565344 14202432 4871392
18:08:22 67617 8192000000 10285727552 10716336128 1357388256 14.214 100 20 40 0 1378659872 16579936 4691680
18:10:55 35462 8192000000 10279295088 10723389440 1360729520 14.244 99 16 32 0 1371610864 7435936 3445408
18:14:12 72016 8192000000 10300848416 10747207680 1372126160 14.346 99 29 58 0 1392913648 14577440 6210048
18:16:25 116759 8192000000 10304094032 10750668800 1377772496 14.397 99 27 54 0 1394026912 9833472 6420944
18:19:30 34602 8192000000 10341321088 10784149504 1405847552 14.647 100 45 89 0 1440486304 23161984 11476768
18:21:07 41348 8192000000 10318948560 10782371840 1407462800 14.661 100 15 30 0 1436267408 24820480 3984128
18:23:51 50554 8192000000 10296342000 10768084992 1388272672 14.490 99 17 33 1 1408055472 15609584 4173216
18:25:46 82853 8192000000 10291803024 10758987776 1343228560 14.087 96 6 12 1 1360290768 15815504 1246704
18:28:09 88817 8192000000 10278589728 10736599040 1315825376 13.839 95 16 32 0 1333009152 13994048 3189728
18:30:45 76401 8192000000 10295010128 10747416576 1369569616 14.323 99 19 38 0 1389262416 15915568 3777232
18:33:16 45517 8192000000 10302632240 10749460480 1385731808 14.468 100 27 53 4 1404228960 11829632 6667520
18:35:02 81060 8192000000 10283414192 10739568640 1337178096 14.032 97 14 30 0 1354295728 13855584 3262048
18:37:28 55535 8192000000 10269307312 10728001536 1329456128 13.962 97 11 22 1 1346079648 13982384 2641136
18:40:03 96104 8192000000 10271223136 10715353088 1340166288 14.059 98 31 62 0 1358709424 11887904 6655232
18:42:41 34374 8192000000 10262389008 10715480064 1350353344 14.151 98 22 44 0 1368525136 13123456 5048336
18:44:16 83613 8192000000 10269461056 10730057728 1346916032 14.120 98 8 16 1 1359959728 10801408 2242288
18:46:40 66086 8192000000 10258298432 10722426880 1329618896 13.964 97 11 23 2 1348245248 16134704 2491648
18:48:51 66390 8192000000 10257786016 10721300480 1344876752 14.101 98 11 22 0 1360928976 13710912 2341312
18:51:24 59500 8192000000 10235528608 10687537152 1314777536 13.829 97 12 24 0 1325671904 8191392 2702976
18:53:25 66433 8192000000 10249850192 10708090880 1336790080 14.028 99 8 16 1 1349809344 11228048 1791216
18:55:32 73728 8192000000 10266930272 10707922944 1319701248 13.874 97 24 48 0 1338981792 14131104 5149440
18:57:53 71498 8192000000 10241964944 10682265600 1261504592 13.344 93 16 32 0 1274879264 10023568 3351104
19:00:07 42066 8192000000 10265350384 10709118976 1335680096 14.018 99 33 66 0 1356870416 14140848 7049472
19:01:51 70001 8192000000 10298963232 10739281920 1347484432 14.125 98 28 56 1 1363532992 9742576 6305984
19:04:04 107111 8192000000 10290054400 10741776384 1365452912 14.286 99 14 28 0 1385938032 17565312 2919808
19:06:52 127819 8192000000 10291791120 10752278528 1388578064 14.493 100 25 50 1 1406099824 12041712 5480048
19:10:00 34929 8192000000 10289434368 10753003520 1395018800 14.551 100 6 12 0 1404498544 7994368 1485376
19:11:36 77452 8192000000 10322610864 10786631680 1409636816 14.681 100 14 28 0 1428402320 15478944 3286560
19:13:54 38901 8192000000 10296104352 10762088448 1366654368 14.297 98 11 22 1 1389694432 20086656 2953408
19:15:34 46361 8192000000 10306603552 10756898816 1355598944 14.198 97 21 39 0 1369915904 9413680 4903280
19:17:22 71055 8192000000 10308228688 10769522688 1374665504 14.369 98 13 26 0 1390228880 12819936 2743440
19:19:41 49732 8192000000 10306036368 10753191936 1374779536 14.370 98 12 24 2 1386370864 8782736 2808592
19:21:35 73417 8192000000 10292388192 10747449344 1324142048 13.914 95 14 28 1 1339637920 12423360 3072512
19:23:49 124823 8192000000 10288257744 10740584448 1307262144 13.761 94 18 36 0 1324514880 13207888 4044848
19:27:00 89441 8192000000 10251130304 10698215424 1292568304 13.628 95 13 25 2 1306394848 10776352 3050192
19:30:08 39772 8192000000 10273444000 10728206336 1362677232 14.261 100 28 56 1 1382698896 13311808 6709856
19:31:49 58706 8192000000 10249571248 10711556096 1332757440 13.992 98 13 26 0 1344088512 8338624 2992448
19:33:47 50296 8192000000 10263321600 10718629888 1351454848 14.161 99 17 34 0 1367356432 11800496 4101088
19:35:38 75695 8192000000 10283564944 10740232192 1362582096 14.261 99 14 28 1 1384718496 18914736 3221664
19:38:22 8386 8192000000 8958288480 9149607936 132400112 1.590 10 2 4 0 135497632 3012448 85072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-06-2022 19:40:21 to 13-06-2022 19:40:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 151 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243973
MON$OLDEST_ACTIVE               243974
MON$OLDEST_SNAPSHOT             243974
MON$NEXT_TRANSACTION            243974
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-03 16:41:55.3470
MON$PAGES                       2029664
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
--- end of diff output ---

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

13.06.22 19:40:40. There are 3 logs in /var/tmp/logs-oltp30 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 19:40:42 No severe PSQL-related problems occured
13.06.22 19:40:44. End of report.