Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.03.2023 22: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 15446036 kB
MemAvailable 30428288 kB
Buffers 0 kB
Cached 16284360 kB
SwapCached 3028 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 2023-03-06 22:38:46
DTS_END                         2023-03-06 22:38:46.4670
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/40720
TRN_ID                          241715
ATT_ID                          698
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.39 19257 1106207
customer order: creation 173.23 384 20788
customer order: refuse 14.98 311 1798
order to supplier: creation 46.42 1211 5570
order to supplier: removal 3.48 91418 417
invoice (draft): creation 45.99 2944 5519
invoice (draft): removal 53.39 688 6407
invoice accept: apply 41.80 16951 5016
invoice accept: cancel 48.11 715 5773
customer reserve: creation 3827.42 170 459290
customer reserve: removal 4660.78 54 559294
realization accept: apply 82.07 173 9848
realization accept: cancel 92.93 102 11151
payment from customer: creation 58.91 96 7069
payment from customer: removal 5.82 99 698
payment to supplier: creation 47.74 134 5729
payment to supplier: removal 3.38 74 405
service: total inventory turnovers 4.68 1216 562
service: total monetary turnovers 6.48 34 778
service: aggregate perf. data 0.76 21721 91
service: refresh index statistics 0.03 246647 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-03-2023 22:40:12 to 06-03-2023 22:40:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3058
TEST_TIME 2 3299
TEST_TIME 3 3369
TEST_TIME 4 3625
TEST_TIME 5 3619
TEST_TIME 6 4002
TEST_TIME 7 4285
TEST_TIME 8 4463
TEST_TIME 9 4864
TEST_TIME 10 5329
TEST_TIME 11 5408
TEST_TIME 12 5544
TEST_TIME 13 6008
TEST_TIME 14 6309
TEST_TIME 15 6390
TEST_TIME 16 6936
TEST_TIME 17 7396
TEST_TIME 18 7913
TEST_TIME 19 8095
TEST_TIME 20 8258
TEST_TIME 21 9063
TEST_TIME 22 9190
TEST_TIME 23 9695
TEST_TIME 24 10565
TEST_TIME 25 10655
TEST_TIME 26 10695
TEST_TIME 27 10486
TEST_TIME 28 10457
TEST_TIME 29 10418
TEST_TIME 30 10443
TEST_TIME 31 10187
TEST_TIME 32 10002
TEST_TIME 33 9954
TEST_TIME 34 9986
TEST_TIME 35 9991
TEST_TIME 36 9866
TEST_TIME 37 9762
TEST_TIME 38 9626
TEST_TIME 39 9662
TEST_TIME 40 9980
TEST_TIME 41 9909
TEST_TIME 42 9757
TEST_TIME 43 9692
TEST_TIME 44 9667
TEST_TIME 45 9593
TEST_TIME 46 9578
TEST_TIME 47 9414
TEST_TIME 48 9249
TEST_TIME 49 9259
TEST_TIME 50 9238
TEST_TIME 51 9270
TEST_TIME 52 9315
TEST_TIME 53 9255
TEST_TIME 54 9321
TEST_TIME 55 9385
TEST_TIME 56 9318
TEST_TIME 57 9441
TEST_TIME 58 9390
TEST_TIME 59 9514
TEST_TIME 60 9524
TEST_TIME 61 9615
TEST_TIME 62 9621
TEST_TIME 63 9532
TEST_TIME 64 9425
TEST_TIME 65 9438
TEST_TIME 66 9382
TEST_TIME 67 9343
TEST_TIME 68 9269
TEST_TIME 69 9234
TEST_TIME 70 9173
TEST_TIME 71 9211
TEST_TIME 72 9261
TEST_TIME 73 9302
TEST_TIME 74 9295
TEST_TIME 75 9362
TEST_TIME 76 9338
TEST_TIME 77 9296
TEST_TIME 78 9312
TEST_TIME 79 9302
TEST_TIME 80 9397
TEST_TIME 81 9441
TEST_TIME 82 9461
TEST_TIME 83 9441
TEST_TIME 84 9342
TEST_TIME 85 9349
TEST_TIME 86 9316
TEST_TIME 87 9281
TEST_TIME 88 9273
TEST_TIME 89 9258
TEST_TIME 90 9238
TEST_TIME 91 9204
TEST_TIME 92 9179
TEST_TIME 93 9314
TEST_TIME 94 9322
TEST_TIME 95 9330
TEST_TIME 96 9339
TEST_TIME 97 9341
TEST_TIME 98 9330
TEST_TIME 99 9376
TEST_TIME 100 9387
TEST_TIME 101 9385
TEST_TIME 102 9481
TEST_TIME 103 9436
TEST_TIME 104 9449
TEST_TIME 105 9463
TEST_TIME 106 9518
TEST_TIME 107 9436
TEST_TIME 108 9428
TEST_TIME 109 9425
TEST_TIME 110 9399
TEST_TIME 111 9414
TEST_TIME 112 9362
TEST_TIME 113 9376
TEST_TIME 114 9325
TEST_TIME 115 9304
TEST_TIME 116 9279
TEST_TIME 117 9257
TEST_TIME 118 9272
TEST_TIME 119 9271
TEST_TIME 120 9230

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-03-2023 22:40:16 to 06-03-2023 22:40:19.

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 1531385 1531385 0 0.00 0 31245 6 0 0
doc_list_biud 1531381 1531381 0 0.00 0 28389 25 0 0
sp_add_invoice_to_stock 5096 5016 80 1.56 23 164048 16951 0 80
sp_cancel_adding_invoice 5773 5773 0 0.00 4 59374 715 0 0
sp_cancel_client_order 1798 1798 0 0.00 4 6980 311 0 0
sp_cancel_customer_reserve 559294 559294 0 0.00 1 28391 55 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 2 15094 100 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 2 3458 74 0 0
sp_cancel_supplier_invoice 6412 6407 5 0.07 2 14104 688 0 5
sp_cancel_supplier_order 417 417 0 0.00 9 1125410 91418 0 0
sp_cancel_write_off 11151 11151 0 0.00 1 16183 102 0 0
sp_client_order 20788 20788 0 0.00 3 21711 384 0 0
sp_customer_reserve 459290 459290 0 0.00 0 70108 170 0 0
sp_fill_shopping_cart 31865 31865 0 0.00 1 14371 34 0 0
sp_fill_shopping_cart_clo_res 459274 459274 0 0.00 0 21374 15 0 0
sp_get_clo_for_invoice 4996 4996 0 0.00 0 28279 295 0 0
sp_kill_cost_storno 18027 18027 0 0.00 0 8082 48 0 0
sp_kill_qstorno_ret_qs2qd 1136563 1136563 0 0.00 0 28385 30 0 0
sp_kill_qty_storno 599682 599682 0 0.00 0 28389 63 0 0
sp_lock_selected_doc 20791 20791 0 0.00 0 9192 9 0 0
sp_make_cost_storno 27642 27642 0 0.00 0 31239 56 0 0
sp_multiply_rows_for_pdistr 27640 27640 0 0.00 0 8033 26 0 0
sp_multiply_rows_for_qdistr 20787 20787 0 0.00 0 18058 174 0 0
sp_pay_from_customer 7069 7069 0 0.00 4 15443 97 0 0
sp_pay_to_supplier 5729 5729 0 0.00 5 22890 135 0 0
sp_payment_common 12797 12797 0 0.00 1 22339 29 0 0
sp_qd_handle_on_cancel_clo 1798 1798 0 0.00 0 6972 277 0 0
sp_qd_handle_on_invoice_upd_sts 10769 10769 0 0.00 0 13655 268 0 0
sp_qd_handle_on_reserve_upd_sts 9846 9846 0 0.00 0 13241 57 0 0
sp_reserve_write_off 9848 9848 0 0.00 6 25016 174 0 0
sp_supplier_invoice 5519 5519 0 0.00 6 77786 2945 0 0
sp_supplier_order 5570 5570 0 0.00 5 35735 1211 0 0
srv_aggregate_perf_data 91 91 0 0.00 2349 192064 21722 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2479 123918 45395 0 0
srv_find_qd_qs_mism 1058595 1058595 0 0.00 0 11138 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 27 48792 1217 0 0
srv_make_money_saldo 778 778 0 0.00 0 7519 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 119446 429068 246647 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5016 5016 0 0.00 0 6558 135 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 3464 95 0 0
t$perf-norm:sp_cancel_client_order 1798 1798 0 0.00 0 2290 11 0 0
t$perf-norm:sp_cancel_customer_reserve 757 757 0 0.00 0 2470 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 15631 33 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 717 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 2063 21 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 21666 1363 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 7284 22 0 0
t$perf-norm:sp_client_order 20788 20788 0 0.00 0 19285 18 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 2739 7 0 0
t$perf-norm:sp_pay_from_customer 7069 7069 0 0.00 0 20724 13 0 0
t$perf-norm:sp_pay_to_supplier 5729 5729 0 0.00 0 15624 16 0 0
t$perf-norm:sp_reserve_write_off 9848 9848 0 0.00 0 16438 16 0 0
t$perf-norm:sp_supplier_invoice 5519 5519 0 0.00 0 15291 21 0 0
t$perf-norm:sp_supplier_order 5570 5570 0 0.00 0 1860 12 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 765 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 810 14 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 1506 17 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 2528 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10148 9988 160 1.57 0 7428 19 0 160
v_all_customers 38286 38286 0 0.00 0 181 0 0 0
v_all_suppliers 30890 30890 0 0.00 0 26 1 0 0
v_all_wares 41568 41568 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 501 7 0 0
v_cancel_client_order 3596 3596 0 0.00 0 3273 6 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 132 2 0 0
v_cancel_customer_reserve 1514 1514 0 0.00 0 736 4 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 3399 11 0 0
v_cancel_supplier_invoice 876 866 10 1.14 0 660 14 0 10
v_cancel_supplier_order 834 834 0 0.00 0 3341 14 0 0
v_cancel_write_off 2034 2034 0 0.00 0 4463 23 0 0
v_max_id_clo_ord 5568 5568 0 0.00 0 942 1 0 0
v_max_id_clo_res 979 979 0 0.00 0 5807 18 0 0
v_max_id_ord_sup 5513 5513 0 0.00 0 4340 4 0 0
v_max_non_paid_invoice 1365 1365 0 0.00 0 1493 4 0 0
v_max_non_paid_realizn 3623 3623 0 0.00 0 942 4 0 0
v_min_id_clo_ord 5568 5568 0 0.00 0 2103 4 0 0
v_min_id_clo_res 979 979 0 0.00 0 6513 12 0 0
v_min_id_ord_sup 5513 5513 0 0.00 0 7149 9 0 0
v_min_non_paid_invoice 5729 5729 0 0.00 0 22333 28 0 0
v_min_non_paid_realizn 7068 7068 0 0.00 0 6894 16 0 0
v_our_firm 10172 10172 0 0.00 0 16 1 0 0
v_reserve_write_off 19690 19690 0 0.00 0 3844 7 0 0
x_lock_depdocs_on_canc_invoice 11747 11747 0 0.00 0 12583 119 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 24843 386 0 0
x_make_qstorno_1000_1200 5570 5570 0 0.00 1 35726 1151 0 0
x_make_qstorno_1200_2000 5519 5519 0 0.00 3 77778 2814 0 0
x_make_qstorno_2100_3300 459290 459290 0 0.00 0 70107 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-03-2023 22:40:20 to 06-03-2023 22:40:24.

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
20:08:50 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:51 49665 8192000000 9899999744 10210799616 970789088 10.594 100 84 168 0 999580528 6080624 22710816
20:11:44 18537 8192000000 9938009968 10257301504 1021294416 11.085 100 95 191 0 1054641664 8623216 24724032
20:13:21 4947 8192000000 9978183232 10310590464 1062556032 11.481 100 83 166 0 1092749584 9696112 20497440
20:14:35 7223 8192000000 10032889440 10381807616 1123346048 12.059 100 83 166 1 1154532368 11598080 19588240
20:15:47 5584 8192000000 10090638352 10449436672 1177949568 12.571 100 84 166 0 1209623040 13077952 18595520
20:16:54 4410 8192000000 10129928656 10499031040 1218695872 12.950 100 80 158 2 1247964592 11699536 17569184
20:18:01 1718 8192000000 10168155408 10544013312 1250532080 13.243 100 86 171 0 1283147248 13828624 18786544
20:19:05 1476 8192000000 10196061312 10578485248 1281215792 13.524 100 86 169 1 1312055856 12199136 18640928
20:20:07 10722 8192000000 10197087360 10591035392 1295469264 13.654 100 56 111 1 1316116928 7823216 12824448
20:21:19 7026 8192000000 10195222352 10607054848 1309164352 13.778 100 30 58 0 1322662016 6982496 6515168
20:22:27 9958 8192000000 10209296480 10625159168 1326234400 13.933 100 27 54 0 1346325392 14635824 5455168
20:23:37 27486 8192000000 10216330928 10633175040 1323172640 13.905 100 25 48 2 1338099472 9851968 5074864
20:25:05 10580 8192000000 10224457040 10644824064 1332656832 13.991 100 18 36 0 1342325696 6032016 3636848
20:26:17 10980 8192000000 10228146272 10650185728 1336345744 14.024 100 21 41 0 1346887184 5902080 4639360
20:27:31 57467 8192000000 10206665200 10621829120 1256820080 13.301 94 27 54 0 1268032976 4653264 6559632
20:29:37 7254 8192000000 10185333872 10588241920 1233313312 13.085 95 24 47 1 1244179488 5884304 4981872
20:30:45 8454 8192000000 10142736528 10542403584 1227206432 13.028 98 13 26 1 1235195840 5037072 2952336
20:31:56 48439 8192000000 10136123216 10532364288 1223512576 12.994 97 26 50 0 1234443696 5235648 5695472
20:33:55 6261 8192000000 10173548320 10581700608 1280747872 13.520 100 35 68 1 1296049744 7575296 7726576
20:35:02 13714 8192000000 10193447136 10606960640 1300157776 13.697 100 29 58 0 1312550144 5423120 6969248
20:36:16 23568 8192000000 10204228704 10619183104 1306747232 13.757 100 21 42 1 1317136448 5408752 4980464
20:37:41 9221 8192000000 10200865184 10620960768 1310618624 13.792 100 21 42 0 1321976736 6581568 4776544
20:38:52 29613 8192000000 10228988656 10644238336 1330688176 13.973 100 45 89 0 1347103680 6344272 10071232
20:40:28 11210 8192000000 10258836256 10672046080 1349555744 14.143 100 48 96 1 1372337952 12065952 10716256
20:41:49 27829 8192000000 10288575504 10702938112 1367469920 14.304 100 51 102 0 1400425376 21023328 11932128
20:43:39 51891 8192000000 10302453104 10729943040 1384071840 14.453 100 38 76 1 1415974720 22160464 9742416
20:45:38 5525 8192000000 10318704448 10745053184 1399775600 14.593 100 44 88 1 1433947264 22935952 11235712
20:46:49 4484 8192000000 10353948944 10769326080 1415225216 14.730 100 68 136 1 1462036800 31386144 15425440
20:48:02 8134 8192000000 10357173376 10777161728 1414444400 14.723 100 63 124 0 1465092944 35515392 15133152
20:49:14 7943 8192000000 10361117424 10778877952 1418297440 14.758 100 63 127 2 1469741520 35126128 16317952
20:50:26 7375 8192000000 10353316784 10768826368 1398238016 14.579 99 64 128 0 1449992416 36498320 15256080
20:51:39 8830 8192000000 10341360480 10756833280 1382264848 14.437 99 68 136 0 1440034288 40892944 16876496
20:52:59 11251 8192000000 10345813872 10753835008 1374957936 14.371 99 74 143 0 1442757328 50274336 17525056
20:54:18 2479 8192000000 10307226880 10714669056 1348749584 14.136 99 70 140 1 1411456128 44566512 18140032
20:55:26 16492 8192000000 10340333184 10773024768 1386737984 14.477 100 44 88 3 1452190688 53525616 11927088
20:57:05 23590 8192000000 10299507216 10724794368 1328415952 13.953 97 30 58 0 1377107600 40532096 8159552
20:58:31 15244 8192000000 10310084416 10728718336 1349632720 14.144 99 31 62 0 1400115120 41557488 8924912
20:59:50 29928 8192000000 10292806912 10728558592 1359194784 14.230 100 27 54 0 1407537488 40623856 7718848
21:01:42 24193 8192000000 10271329168 10704494592 1315085072 13.832 98 18 36 1 1358149328 38204400 4859856
21:03:11 25398 8192000000 10263902384 10687275008 1329475040 13.962 99 42 84 1 1355904880 16461568 9968272
21:04:50 22851 8192000000 10257461744 10696630272 1337207840 14.032 100 15 30 0 1357905328 17354464 3343024
21:06:29 60265 8192000000 10262705344 10696007680 1341440224 14.070 99 19 36 1 1360887008 14889504 4557280
21:08:43 76379 8192000000 10277623840 10718334976 1365015408 14.282 100 15 28 0 1374776288 6486288 3274592
21:11:11 83485 8192000000 10286302048 10735546368 1367056576 14.301 99 20 40 0 1385066208 13665088 4344544
21:13:38 36371 8192000000 10289978960 10732118016 1382088848 14.435 100 11 22 0 1394319168 9863216 2367104
21:15:35 25545 8192000000 10317482288 10749050880 1383635968 14.449 100 36 71 0 1407765344 15972304 8157072
21:17:02 38976 8192000000 10303112176 10756349952 1390387680 14.509 100 29 58 1 1415182080 17589424 7204976
21:18:55 92142 8192000000 10302127024 10753363968 1389660608 14.503 100 15 30 0 1413957504 20808048 3488848
21:21:37 61115 8192000000 10283722224 10734796800 1383749776 14.450 100 13 26 0 1396329712 9709536 2870400
21:23:43 18610 8192000000 10297728304 10750382080 1398165408 14.579 100 27 54 0 1410851904 6876688 5809808
21:25:05 43020 8192000000 10288982032 10750443520 1389936912 14.505 100 4 8 0 1398343440 7588784 817744
21:26:54 80668 8192000000 10298500976 10767544320 1399982832 14.595 100 14 28 0 1416024832 12993184 3048816
21:29:15 89130 8192000000 10311336944 10770472960 1408803216 14.673 100 17 34 0 1424758816 11555520 4400080
21:31:47 33225 8192000000 10326191040 10781036544 1414553248 14.724 100 29 57 2 1430530192 9239776 6737168
21:33:22 76112 8192000000 10327585968 10774269952 1384533744 14.457 98 14 28 0 1401798000 14246208 3018048
21:35:40 60789 8192000000 10325315136 10784247808 1408834768 14.674 100 10 20 0 1421172048 10129520 2207760
21:37:44 67830 8192000000 10279895488 10734669824 1317417456 13.853 95 5 10 0 1326741344 8539168 784720
21:39:54 84448 8192000000 10284622416 10724876288 1263069296 13.358 91 11 23 0 1273319440 8259008 1991136
21:42:24 111673 8192000000 10259204608 10711859200 1299280368 13.689 95 22 44 0 1314162464 10112224 4769872
21:45:22 46700 8192000000 10242582528 10695118848 1304272208 13.734 96 21 42 0 1318286624 9150096 4864320
21:47:14 44354 8192000000 10238330000 10690908160 1331627392 13.982 99 10 20 0 1345078272 10903424 2547456
21:48:59 62101 8192000000 10241674832 10690875392 1324446224 13.917 98 10 20 1 1340157648 13239264 2472160
21:51:03 54300 8192000000 10254039280 10695561216 1318597216 13.864 98 27 52 0 1334026320 9060272 6368832
21:53:05 58178 8192000000 10258514240 10712129536 1354732768 14.190 100 17 34 0 1374165904 15690416 3742720
21:55:08 14700 8192000000 10264102208 10693599232 1301513808 13.709 96 52 103 0 1320836304 7815904 11506592
21:56:25 24868 8192000000 10269562016 10695184384 1331127776 13.977 99 50 97 0 1351839904 9730160 10981968
21:57:56 123917 8192000000 10264951936 10721783808 1273403744 13.453 94 15 30 0 1297826752 20714544 3708464
22:01:04 41249 8192000000 10260061856 10699952128 1330856160 13.975 98 29 58 0 1346056080 8827120 6372800
22:02:49 71701 8192000000 10263854928 10710622208 1324124416 13.914 97 43 86 2 1342809984 8249280 10436288
22:05:27 30507 8192000000 10288593472 10732486656 1378280640 14.401 100 30 59 1 1394812208 9935024 6596544
22:07:04 38491 8192000000 10263806000 10720260096 1360475520 14.242 99 9 18 0 1373032464 10507632 2049312
22:08:53 45632 8192000000 10293111936 10749353984 1390012000 14.506 100 12 24 0 1405613968 12824608 2777360
22:10:51 61662 8192000000 10292820352 10770186240 1384555344 14.457 99 18 37 0 1409046480 19892048 4599088
22:12:53 31490 8192000000 10296697008 10749059072 1392433808 14.528 100 22 44 0 1405245424 7758800 5052816
22:14:26 60659 8192000000 10300651696 10751582208 1385007216 14.461 99 28 56 0 1400485424 8898112 6580096
22:16:27 53897 8192000000 10287103200 10752110592 1358768256 14.226 97 9 18 0 1374271200 13580320 1922624
22:18:21 19854 8192000000 10306058192 10749190144 1388147312 14.489 100 40 80 0 1407387824 10520864 8719648
22:19:44 102947 8192000000 10310149952 10753318912 1359779008 14.235 97 18 36 0 1379089824 15490768 3820048
22:22:30 47786 8192000000 10301651696 10749341696 1369355696 14.321 99 19 38 0 1384036560 10696096 3984768
22:24:23 73726 8192000000 10296774656 10764558336 1382432272 14.438 99 14 28 1 1400623536 14961248 3230016
22:26:45 89375 8192000000 10279955952 10739511296 1307502512 13.763 94 14 28 0 1320159200 9605792 3050896
22:29:20 51906 8192000000 10257804784 10718806016 1331919056 13.984 97 9 18 0 1347188464 13272624 1996784
22:31:12 37703 8192000000 10264643264 10728845312 1363873520 14.272 100 2 4 0 1374428288 10258720 296048
22:33:24 59726 8192000000 10246957104 10709401600 1329725248 13.965 97 16 32 0 1345975040 11719232 4530560
22:35:26 52673 8192000000 10250219184 10705842176 1332666320 13.991 98 10 20 0 1348763696 13975216 2122160
22:37:24 31006 8192000000 10271474848 10715312128 1341465488 14.071 99 22 42 0 1354957360 9210576 4281296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-03-2023 22:40:26 to 06-03-2023 22:40:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-03-2023 22:40:42 to 06-03-2023 22:40:44.

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          241187
MON$OLDEST_ACTIVE               243098
MON$OLDEST_SNAPSHOT             243098
MON$NEXT_TRANSACTION            243098
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                       2009728
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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'

06.03.23 22:40:46. 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
2023-03-06 22:40:48 No severe PSQL-related problems occured
06.03.23 22:40:49. End of report.