Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.07.2022 01:40
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 16265760 kB
MemAvailable 31174904 kB
Buffers 0 kB
Cached 15484736 kB
SwapCached 2544 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.11
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-07-07 01:38:28
DTS_END                         2022-07-07 01:38:28.9510
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(57:17)
IP                              ::1/54570
TRN_ID                          238349
ATT_ID                          634
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: 9218.15 19738 1106178
customer order: creation 172.69 352 20723
customer order: refuse 14.90 366 1788
order to supplier: creation 45.39 1272 5447
order to supplier: removal 3.43 90725 411
invoice (draft): creation 46.12 3608 5534
invoice (draft): removal 51.33 740 6160
invoice accept: apply 41.97 17168 5036
invoice accept: cancel 47.05 737 5646
customer reserve: creation 3867.60 170 464112
customer reserve: removal 4637.20 52 556464
realization accept: apply 82.28 195 9873
realization accept: cancel 80.63 99 9675
payment from customer: creation 58.85 138 7062
payment from customer: removal 5.75 78 690
payment to supplier: creation 48.05 152 5766
payment to supplier: removal 3.26 87 391
service: total inventory turnovers 4.60 1177 552
service: total monetary turnovers 6.21 29 745
service: aggregate perf. data 0.83 21307 99
service: refresh index statistics 0.03 256313 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-07-2022 01:40:28 to 07-07-2022 01:40:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7148
TEST_TIME 2 5194
TEST_TIME 3 4356
TEST_TIME 4 5486
TEST_TIME 5 6446
TEST_TIME 6 6269
TEST_TIME 7 6273
TEST_TIME 8 6186
TEST_TIME 9 6215
TEST_TIME 10 6012
TEST_TIME 11 5839
TEST_TIME 12 5896
TEST_TIME 13 5854
TEST_TIME 14 6086
TEST_TIME 15 6156
TEST_TIME 16 6207
TEST_TIME 17 6699
TEST_TIME 18 6943
TEST_TIME 19 7209
TEST_TIME 20 7372
TEST_TIME 21 7464
TEST_TIME 22 8149
TEST_TIME 23 8378
TEST_TIME 24 9092
TEST_TIME 25 9738
TEST_TIME 26 9740
TEST_TIME 27 9702
TEST_TIME 28 9952
TEST_TIME 29 9738
TEST_TIME 30 9847
TEST_TIME 31 9592
TEST_TIME 32 9594
TEST_TIME 33 9594
TEST_TIME 34 9711
TEST_TIME 35 9509
TEST_TIME 36 9496
TEST_TIME 37 9459
TEST_TIME 38 9488
TEST_TIME 39 9490
TEST_TIME 40 9560
TEST_TIME 41 9474
TEST_TIME 42 9312
TEST_TIME 43 9209
TEST_TIME 44 9253
TEST_TIME 45 9146
TEST_TIME 46 9161
TEST_TIME 47 9084
TEST_TIME 48 9115
TEST_TIME 49 9220
TEST_TIME 50 9187
TEST_TIME 51 9177
TEST_TIME 52 9241
TEST_TIME 53 9369
TEST_TIME 54 9344
TEST_TIME 55 9441
TEST_TIME 56 9466
TEST_TIME 57 9502
TEST_TIME 58 9550
TEST_TIME 59 9741
TEST_TIME 60 9649
TEST_TIME 61 9564
TEST_TIME 62 9530
TEST_TIME 63 9600
TEST_TIME 64 9535
TEST_TIME 65 9537
TEST_TIME 66 9511
TEST_TIME 67 9481
TEST_TIME 68 9443
TEST_TIME 69 9365
TEST_TIME 70 9326
TEST_TIME 71 9297
TEST_TIME 72 9237
TEST_TIME 73 9226
TEST_TIME 74 9187
TEST_TIME 75 9229
TEST_TIME 76 9243
TEST_TIME 77 9226
TEST_TIME 78 9276
TEST_TIME 79 9359
TEST_TIME 80 9347
TEST_TIME 81 9315
TEST_TIME 82 9412
TEST_TIME 83 9391
TEST_TIME 84 9443
TEST_TIME 85 9440
TEST_TIME 86 9484
TEST_TIME 87 9422
TEST_TIME 88 9341
TEST_TIME 89 9354
TEST_TIME 90 9331
TEST_TIME 91 9297
TEST_TIME 92 9298
TEST_TIME 93 9286
TEST_TIME 94 9351
TEST_TIME 95 9325
TEST_TIME 96 9353
TEST_TIME 97 9398
TEST_TIME 98 9400
TEST_TIME 99 9431
TEST_TIME 100 9469
TEST_TIME 101 9430
TEST_TIME 102 9425
TEST_TIME 103 9499
TEST_TIME 104 9523
TEST_TIME 105 9520
TEST_TIME 106 9600
TEST_TIME 107 9574
TEST_TIME 108 9522
TEST_TIME 109 9503
TEST_TIME 110 9484
TEST_TIME 111 9439
TEST_TIME 112 9419
TEST_TIME 113 9418
TEST_TIME 114 9419
TEST_TIME 115 9416
TEST_TIME 116 9364
TEST_TIME 117 9324
TEST_TIME 118 9307
TEST_TIME 119 9266
TEST_TIME 120 9220

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-07-2022 01:40:31 to 07-07-2022 01:40:34.

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 1536661 1536661 0 0.00 0 25340 6 0 0
doc_list_biud 1536660 1536660 0 0.00 0 65083 24 0 0
sp_add_invoice_to_stock 5177 5036 141 2.72 29 145869 17168 0 141
sp_cancel_adding_invoice 5646 5646 0 0.00 5 77630 738 0 0
sp_cancel_client_order 1788 1788 0 0.00 4 7845 367 0 0
sp_cancel_customer_reserve 556464 556464 0 0.00 0 65084 53 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 2 5574 78 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 6144 88 0 0
sp_cancel_supplier_invoice 6167 6160 7 0.11 2 17774 741 0 7
sp_cancel_supplier_order 411 411 0 0.00 4 1100196 90725 0 0
sp_cancel_write_off 9675 9675 0 0.00 1 12518 99 0 0
sp_client_order 20723 20723 0 0.00 3 17490 353 0 0
sp_customer_reserve 464112 464112 0 0.00 0 64128 171 0 0
sp_fill_shopping_cart 31693 31693 0 0.00 1 12165 38 0 0
sp_fill_shopping_cart_clo_res 464109 464109 0 0.00 0 20426 13 0 0
sp_get_clo_for_invoice 5031 5031 0 0.00 0 36893 321 0 0
sp_kill_cost_storno 16402 16402 0 0.00 0 15821 47 0 0
sp_kill_qstorno_ret_qs2qd 1129174 1129174 0 0.00 0 65080 29 0 0
sp_kill_qty_storno 595048 595048 0 0.00 0 65082 63 0 0
sp_lock_selected_doc 20665 20665 0 0.00 0 11156 6 0 0
sp_make_cost_storno 27732 27732 0 0.00 0 24655 70 0 0
sp_multiply_rows_for_pdistr 27732 27732 0 0.00 0 14195 27 0 0
sp_multiply_rows_for_qdistr 20723 20723 0 0.00 0 17475 162 0 0
sp_pay_from_customer 7062 7062 0 0.00 4 19603 138 0 0
sp_pay_to_supplier 5766 5766 0 0.00 5 16383 152 0 0
sp_payment_common 12828 12828 0 0.00 1 9986 37 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 7749 336 0 0
sp_qd_handle_on_invoice_upd_sts 10677 10677 0 0.00 0 33121 304 0 0
sp_qd_handle_on_reserve_upd_sts 9873 9873 0 0.00 0 27035 58 0 0
sp_reserve_write_off 9873 9873 0 0.00 5 27163 195 0 0
sp_supplier_invoice 5534 5534 0 0.00 6 88556 3608 0 0
sp_supplier_order 5447 5447 0 0.00 4 44860 1272 0 0
srv_aggregate_perf_data 99 99 0 0.00 2741 289633 21307 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2000 110732 45574 0 0
srv_find_qd_qs_mism 1058907 1058907 0 0.00 0 9549 5 0 0
srv_make_invnt_saldo 552 552 0 0.00 34 62932 1177 0 0
srv_make_money_saldo 745 745 0 0.00 0 2592 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 108247 414371 256313 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 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 5036 5036 0 0.00 0 21530 169 0 0
t$perf-norm:sp_cancel_adding_invoice 457 457 0 0.00 0 2551 70 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 3359 12 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 1839 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 4102 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 73 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 844 13 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 28738 1545 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 4376 12 0 0
t$perf-norm:sp_client_order 20723 20723 0 0.00 0 8970 17 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 5012 10 0 0
t$perf-norm:sp_pay_from_customer 7062 7062 0 0.00 0 8332 13 0 0
t$perf-norm:sp_pay_to_supplier 5766 5766 0 0.00 0 6583 13 0 0
t$perf-norm:sp_reserve_write_off 9873 9873 0 0.00 0 7225 14 0 0
t$perf-norm:sp_supplier_invoice 5534 5534 0 0.00 0 6478 20 0 0
t$perf-norm:sp_supplier_order 5447 5447 0 0.00 0 9018 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 789 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 752 12 0 0
t$perf-norm:srv_make_invnt_saldo 552 552 0 0.00 0 2992 19 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 1803 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10342 10060 282 2.72 0 8099 19 0 282
v_all_customers 37234 37234 0 0.00 0 15 0 0 0
v_all_suppliers 30496 30496 0 0.00 0 129 1 0 0
v_all_wares 41446 41446 0 0.00 0 65 1 0 0
v_cancel_adding_invoice 914 914 0 0.00 0 3160 13 0 0
v_cancel_client_order 3576 3576 0 0.00 0 4562 6 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 1889 6 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 1332 6 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 4759 18 0 0
v_cancel_supplier_invoice 752 738 14 1.86 0 2224 16 0 14
v_cancel_supplier_order 822 822 0 0.00 0 2032 8 0 0
v_cancel_write_off 1980 1980 0 0.00 0 6087 15 0 0
v_max_id_clo_ord 5446 5446 0 0.00 0 1503 3 0 0
v_max_id_clo_res 939 939 0 0.00 0 2201 19 0 0
v_max_id_ord_sup 5523 5523 0 0.00 0 5427 4 0 0
v_max_non_paid_invoice 1487 1487 0 0.00 0 1285 4 0 0
v_max_non_paid_realizn 3935 3935 0 0.00 0 2598 6 0 0
v_min_id_clo_ord 5446 5446 0 0.00 0 5299 12 0 0
v_min_id_clo_res 939 939 0 0.00 0 10195 26 0 0
v_min_id_ord_sup 5523 5523 0 0.00 0 8231 7 0 0
v_min_non_paid_invoice 5766 5766 0 0.00 0 9983 26 0 0
v_min_non_paid_realizn 7062 7062 0 0.00 0 9617 27 0 0
v_our_firm 10466 10466 0 0.00 0 129 1 0 0
v_reserve_write_off 19744 19744 0 0.00 0 4755 7 0 0
x_lock_depdocs_on_canc_invoice 11437 11437 0 0.00 0 14667 105 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 12910 333 0 0
x_make_qstorno_1000_1200 5447 5447 0 0.00 1 44839 1191 0 0
x_make_qstorno_1200_2000 5534 5534 0 0.00 3 87109 3470 0 0
x_make_qstorno_2100_3300 464112 464112 0 0.00 0 64128 155 0 0

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

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
23:08:32 3 8192000000 8798485104 8809644032 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:34 15864 8192000000 9906859328 10216235008 985530960 10.738 100 87 174 0 1014251872 5765216 22955696
23:10:57 19867 8192000000 9945335264 10265919488 1030122240 11.170 100 92 183 0 1061528992 7487184 23919568
23:12:22 18380 8192000000 9978131888 10306658304 1059627552 11.453 100 88 176 0 1091372320 9231120 22513648
23:13:53 9154 8192000000 10022360512 10368593920 1107778624 11.911 100 79 156 0 1136556272 9893392 18884256
23:15:09 8052 8192000000 10074594096 10433089536 1164584736 12.446 100 72 143 0 1191925616 11016448 16324432
23:16:19 4731 8192000000 10122076272 10490634240 1207187056 12.843 100 79 152 0 1237334576 13176432 16971088
23:17:25 2450 8192000000 10164952304 10539610112 1249892672 13.237 100 89 179 0 1284062832 14695840 19474320
23:18:30 3910 8192000000 10181827616 10561024000 1268659600 13.409 100 82 162 1 1301112944 15297472 17155872
23:19:36 1138 8192000000 10210191456 10598846464 1301505760 13.709 100 89 179 2 1334547360 12694800 20346800
23:20:39 10897 8192000000 10220354336 10614722560 1307532560 13.764 100 69 138 0 1335185888 12532752 15120576
23:21:50 7000 8192000000 10193124128 10610761728 1312273968 13.807 100 22 44 1 1323583104 6412272 4896864
23:22:58 12323 8192000000 10201581408 10620411904 1320010688 13.877 100 19 38 1 1330245632 6190064 4044880
23:24:12 16024 8192000000 10211229584 10632282112 1328791744 13.956 100 13 26 0 1337753168 5563120 3398304
23:25:31 64807 8192000000 10224384656 10648317952 1335921440 14.021 100 27 54 0 1347916448 5893056 6101952
23:27:36 5271 8192000000 10228909008 10651111424 1331445712 13.980 100 26 52 0 1348940832 11487808 6007312
23:28:52 8252 8192000000 10215856528 10629816320 1302348448 13.717 98 17 35 0 1311072272 4934560 3789264
23:30:03 63705 8192000000 10152299136 10555105280 1182658672 12.615 92 32 62 0 1195196976 4523472 8014832
23:32:10 5779 8192000000 10159486848 10562007040 1263308272 13.360 100 25 50 1 1276370928 7139392 5923264
23:33:16 3443 8192000000 10137350416 10531115008 1237587504 13.124 100 38 76 0 1253529696 7574640 8367552
23:34:22 10487 8192000000 10164989584 10575413248 1273615664 13.455 100 23 47 0 1288407792 9581824 5210304
23:35:33 36254 8192000000 10178335632 10591641600 1289054464 13.596 100 29 55 0 1300412736 5270304 6087968
23:37:11 5848 8192000000 10194906560 10607677440 1300378960 13.699 100 35 70 0 1315551824 6756368 8416496
23:38:18 8384 8192000000 10207697888 10625568768 1318514128 13.863 100 23 47 2 1328720448 4684160 5522160
23:39:33 11930 8192000000 10249104800 10657644544 1339332400 14.051 100 47 93 1 1358010528 8450496 10227632
23:40:46 98805 8192000000 10254932560 10681937920 1358169088 14.221 100 35 70 2 1374849424 8550208 8130128
23:43:29 17529 8192000000 10281432352 10698244096 1364652288 14.279 100 58 116 1 1387563776 9599856 13311632
23:44:49 5853 8192000000 10290637856 10708324352 1376400304 14.384 100 48 93 0 1398422272 11509408 10512560
23:45:57 8222 8192000000 10298358352 10719969280 1383014752 14.443 100 52 101 0 1408749920 13860816 11874352
23:47:10 39204 8192000000 10296961584 10728157184 1388653856 14.494 100 39 73 1 1411730480 14195376 8881248
23:48:53 9935 8192000000 10317250688 10746978304 1390700656 14.512 99 40 75 0 1415973840 15609152 9664032
23:50:11 6174 8192000000 10339739520 10767831040 1418763472 14.762 100 48 91 0 1451833248 21947808 11121968
23:51:29 2613 8192000000 10338325488 10754023424 1400908384 14.603 99 73 146 0 1443250288 24716944 17624960
23:52:38 5644 8192000000 10334284672 10750898176 1405668128 14.645 100 58 112 0 1444819344 25242560 13908656
23:53:50 8877 8192000000 10311929840 10724278272 1365377968 14.286 99 59 117 1 1409786688 28750048 15658672
23:55:08 1988 8192000000 10323476624 10729607168 1374789216 14.370 100 73 144 2 1430224864 37091040 18344608
23:56:20 2786 8192000000 10330169616 10736877568 1378408352 14.402 99 78 156 1 1434553520 36290224 19854944
23:57:44 11000 8192000000 10324601504 10742136832 1383423840 14.447 100 45 90 1 1432131088 36780320 11926928
23:59:01 8757 8192000000 10329312880 10742497280 1375107392 14.373 100 47 94 1 1428235536 40774688 12353456
00:00:34 21888 8192000000 10280742912 10716483584 1339175472 14.050 99 37 74 0 1395146736 45625056 10346208
00:02:09 22851 8192000000 10273270912 10702581760 1339553872 14.053 100 28 54 0 1389064208 41704192 7806144
00:03:33 39004 8192000000 10254750016 10703249408 1306736592 13.756 97 7 14 0 1329355712 20898768 1720352
00:05:20 30878 8192000000 10256907280 10696605696 1336394960 14.025 99 12 25 0 1356588688 16708416 3485312
00:06:56 50560 8192000000 10255020752 10691559424 1313157664 13.815 97 25 51 0 1331050928 11811008 6082256
00:09:15 69805 8192000000 10269687216 10703888384 1354931600 14.192 100 34 68 0 1373827200 10704608 8190992
00:11:32 72198 8192000000 10279441584 10726846464 1354317920 14.186 99 28 54 0 1379642656 19082384 6242352
00:13:48 20307 8192000000 10286049680 10724659200 1358499344 14.224 99 23 46 2 1383835488 20034672 5301472
00:15:12 22680 8192000000 10295292000 10731302912 1374877600 14.371 100 39 76 0 1399698624 15633936 9187088
00:17:03 50098 8192000000 10270804240 10730639360 1360005600 14.237 99 11 22 0 1379419744 16792672 2621472
00:18:54 64772 8192000000 10267071760 10720157696 1361589296 14.252 99 11 22 0 1377014880 13074208 2351376
00:21:03 78427 8192000000 10274735392 10732511232 1367096496 14.301 99 18 34 0 1381301888 9832128 4373264
00:23:29 43260 8192000000 10287271392 10743529472 1389980880 14.506 100 18 36 0 1401927968 7975792 3971296
00:25:33 25307 8192000000 10302846000 10752290816 1394790224 14.549 100 31 61 0 1412044512 9600416 7653872
00:27:02 52800 8192000000 10282694384 10745999360 1369049856 14.319 98 19 38 0 1382786192 9296512 4439824
00:28:56 60752 8192000000 10293603696 10744344576 1342031104 14.076 96 28 56 0 1365885072 17205776 6648192
00:31:00 46962 8192000000 10295455216 10756485120 1394163232 14.543 100 18 36 0 1409247808 10756400 4328176
00:32:47 26525 8192000000 10316689568 10751942656 1361061424 14.247 97 45 90 2 1381350240 9806816 10482000
00:34:15 73311 8192000000 10322191072 10776948736 1393222752 14.535 100 16 32 0 1415671632 18702384 3746496
00:36:29 71476 8192000000 10312138272 10783567872 1382129520 14.436 99 9 18 0 1409052528 24639072 2283936
00:38:46 110732 8192000000 10287568208 10735935488 1353146112 14.176 98 33 66 0 1377589920 15909824 8533984
00:41:39 15059 8192000000 10263210992 10730909696 1352434736 14.169 99 12 24 0 1371600944 17053472 2112736
00:42:55 91076 8192000000 10260772480 10715922432 1320608624 13.882 96 9 18 0 1331078032 8484160 1985248
00:45:29 30901 8192000000 10250918496 10718101504 1347152080 14.122 99 12 24 0 1362862976 12758608 2952288
00:47:02 77843 8192000000 10241420768 10695876608 1335464544 14.016 98 14 27 1 1345604432 7302160 2837728
00:49:28 62589 8192000000 10232061520 10685775872 1293289632 13.634 95 13 26 1 1303044256 6809760 2944864
00:51:39 71445 8192000000 10250673008 10708500480 1343960256 14.093 100 8 16 0 1357731808 12112624 1658928
00:53:53 49577 8192000000 10275584496 10718515200 1348862256 14.137 99 18 36 0 1368516416 15115536 4538624
00:55:45 52835 8192000000 10254592640 10698813440 1320854640 13.884 97 20 38 1 1337855104 12892048 4108416
00:57:40 61403 8192000000 10257499168 10695901184 1310909888 13.794 97 29 58 0 1326184496 9480704 5793904
00:59:45 35431 8192000000 10279521104 10721521664 1350919520 14.156 99 42 84 0 1378191424 17653568 9618336
01:02:04 23739 8192000000 10292202368 10730864640 1369286928 14.321 100 44 88 0 1390981376 12311904 9382544
01:03:31 81192 8192000000 10295206048 10752106496 1369338800 14.321 99 15 30 0 1387397376 14358080 3700496
01:06:40 76280 8192000000 10285235488 10741092352 1359438096 14.232 98 17 32 0 1377017344 13659520 3919728
01:09:06 61104 8192000000 10286592352 10744733696 1390137888 14.507 100 12 24 0 1400174704 7483344 2553472
01:11:07 94311 8192000000 10295784544 10755649536 1322181552 13.896 94 12 22 0 1335681696 10957088 2543056
01:14:05 74576 8192000000 10305124384 10769522688 1377087792 14.391 98 11 22 1 1392654496 13033568 2533136
01:16:21 36843 8192000000 10315285360 10759491584 1384117280 14.453 99 33 66 1 1406652576 15300336 7234960
01:18:01 73113 8192000000 10317932384 10769711104 1402151136 14.614 100 19 38 0 1416489568 10092112 4246320
01:20:19 25191 8192000000 10335871808 10779766784 1399949104 14.595 99 41 82 0 1424758624 15469328 9340192
01:21:47 31513 8192000000 10302191552 10741223424 1339233568 14.050 95 25 50 0 1353621360 8627536 5760256
01:23:19 19888 8192000000 10312124096 10757287936 1393347152 14.536 100 25 48 0 1406723504 8147168 5229184
01:24:55 101105 8192000000 10283867520 10747621376 1338707936 14.046 97 7 14 0 1360195808 19576928 1910944
01:28:18 52397 8192000000 10297756640 10759225344 1377392112 14.393 99 12 24 0 1389359664 9438368 2529184
01:30:32 93754 8192000000 10276094416 10735968256 1356798672 14.209 98 10 20 0 1373570736 14314000 2458064
01:33:10 79227 8192000000 10267108016 10723115008 1341356224 14.070 98 12 24 0 1356026208 11910096 2759888
01:35:38 45246 8192000000 10256514400 10712653824 1356380384 14.205 100 21 42 0 1370644960 9564080 4700496
01:37:45 59557 8192000000 9171465040 9523445760 307157200 3.613 23 5 10 0 310030672 2050512 822960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-07-2022 01:40:41 to 07-07-2022 01:40:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 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 07-07-2022 01:40:57 to 07-07-2022 01:40:59.

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          238464
MON$OLDEST_ACTIVE               239116
MON$OLDEST_SNAPSHOT             239116
MON$NEXT_TRANSACTION            239116
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                       2020928
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:63: 
:64: mscs-test-161.budzdorov.ru    Thu Jul  7 01:38:49 2022
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Thu Jul  7 01:38:50 2022
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Thu Jul  7 01:38:50 2022
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:74: 
--- end of diff output ---

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

07.07.22 01:41:00. 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-07-07 01:41:02 No severe PSQL-related problems occured
07.07.22 01:41:04. End of report.