Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.09.2022 01: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 15723156 kB
MemAvailable 30568440 kB
Buffers 0 kB
Cached 15980604 kB
SwapCached 2052 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-09-28 01:38:27
DTS_END                         2022-09-28 01:38:27.3670
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/39316
TRN_ID                          238314
ATT_ID                          707
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: 9213.66 21215 1105639
customer order: creation 171.58 408 20589
customer order: refuse 14.44 373 1733
order to supplier: creation 44.85 1246 5382
order to supplier: removal 3.54 98092 425
invoice (draft): creation 44.74 3329 5369
invoice (draft): removal 52.17 821 6260
invoice accept: apply 41.12 17879 4934
invoice accept: cancel 48.02 717 5762
customer reserve: creation 3814.63 176 457756
customer reserve: removal 4682.68 58 561921
realization accept: apply 83.36 179 10003
realization accept: cancel 86.03 111 10323
payment from customer: creation 57.60 122 6912
payment from customer: removal 5.42 86 650
payment to supplier: creation 48.46 129 5815
payment to supplier: removal 3.63 65 436
service: total inventory turnovers 4.32 1281 518
service: total monetary turnovers 6.27 112 752
service: aggregate perf. data 0.79 23698 95
service: refresh index statistics 0.03 275423 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-09-2022 01:39:39 to 28-09-2022 01:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3864
TEST_TIME 2 4362
TEST_TIME 3 5610
TEST_TIME 4 5776
TEST_TIME 5 5419
TEST_TIME 6 5321
TEST_TIME 7 5504
TEST_TIME 8 5563
TEST_TIME 9 5605
TEST_TIME 10 5799
TEST_TIME 11 5892
TEST_TIME 12 5957
TEST_TIME 13 6056
TEST_TIME 14 6092
TEST_TIME 15 6623
TEST_TIME 16 6925
TEST_TIME 17 7147
TEST_TIME 18 7435
TEST_TIME 19 8086
TEST_TIME 20 8276
TEST_TIME 21 8837
TEST_TIME 22 9238
TEST_TIME 23 9666
TEST_TIME 24 10365
TEST_TIME 25 11038
TEST_TIME 26 10879
TEST_TIME 27 10905
TEST_TIME 28 10837
TEST_TIME 29 10638
TEST_TIME 30 10392
TEST_TIME 31 10304
TEST_TIME 32 10174
TEST_TIME 33 9978
TEST_TIME 34 9983
TEST_TIME 35 9973
TEST_TIME 36 9922
TEST_TIME 37 9752
TEST_TIME 38 9683
TEST_TIME 39 9827
TEST_TIME 40 9764
TEST_TIME 41 9692
TEST_TIME 42 9608
TEST_TIME 43 9413
TEST_TIME 44 9398
TEST_TIME 45 9527
TEST_TIME 46 9502
TEST_TIME 47 9384
TEST_TIME 48 9315
TEST_TIME 49 9239
TEST_TIME 50 9235
TEST_TIME 51 9384
TEST_TIME 52 9306
TEST_TIME 53 9446
TEST_TIME 54 9381
TEST_TIME 55 9419
TEST_TIME 56 9559
TEST_TIME 57 9512
TEST_TIME 58 9545
TEST_TIME 59 9478
TEST_TIME 60 9600
TEST_TIME 61 9662
TEST_TIME 62 9636
TEST_TIME 63 9524
TEST_TIME 64 9438
TEST_TIME 65 9466
TEST_TIME 66 9454
TEST_TIME 67 9381
TEST_TIME 68 9346
TEST_TIME 69 9326
TEST_TIME 70 9294
TEST_TIME 71 9309
TEST_TIME 72 9370
TEST_TIME 73 9342
TEST_TIME 74 9346
TEST_TIME 75 9358
TEST_TIME 76 9402
TEST_TIME 77 9396
TEST_TIME 78 9460
TEST_TIME 79 9456
TEST_TIME 80 9484
TEST_TIME 81 9456
TEST_TIME 82 9368
TEST_TIME 83 9352
TEST_TIME 84 9347
TEST_TIME 85 9308
TEST_TIME 86 9275
TEST_TIME 87 9273
TEST_TIME 88 9225
TEST_TIME 89 9217
TEST_TIME 90 9281
TEST_TIME 91 9280
TEST_TIME 92 9329
TEST_TIME 93 9315
TEST_TIME 94 9328
TEST_TIME 95 9348
TEST_TIME 96 9425
TEST_TIME 97 9395
TEST_TIME 98 9408
TEST_TIME 99 9443
TEST_TIME 100 9475
TEST_TIME 101 9434
TEST_TIME 102 9529
TEST_TIME 103 9521
TEST_TIME 104 9551
TEST_TIME 105 9492
TEST_TIME 106 9430
TEST_TIME 107 9424
TEST_TIME 108 9411
TEST_TIME 109 9404
TEST_TIME 110 9395
TEST_TIME 111 9371
TEST_TIME 112 9354
TEST_TIME 113 9317
TEST_TIME 114 9277
TEST_TIME 115 9255
TEST_TIME 116 9219
TEST_TIME 117 9257
TEST_TIME 118 9240
TEST_TIME 119 9277
TEST_TIME 120 9217

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-09-2022 01:39:43 to 28-09-2022 01:39:46.

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 1529540 1529540 0 0.00 0 19603 6 0 0
doc_list_biud 1529540 1529540 0 0.00 0 82348 27 0 0
sp_add_invoice_to_stock 5107 4934 173 3.38 39 149567 17879 0 173
sp_cancel_adding_invoice 5762 5762 0 0.00 3 54412 718 0 0
sp_cancel_client_order 1733 1733 0 0.00 4 11512 374 0 0
sp_cancel_customer_reserve 561921 561921 0 0.00 0 24654 58 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 3 7455 86 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 4818 65 0 0
sp_cancel_supplier_invoice 6276 6260 16 0.25 1 82359 822 0 16
sp_cancel_supplier_order 425 425 0 0.00 5 1126745 98093 0 0
sp_cancel_write_off 10323 10323 0 0.00 1 16379 112 0 0
sp_client_order 20589 20589 0 0.00 3 24096 408 0 0
sp_customer_reserve 457756 457756 0 0.00 0 58020 177 0 0
sp_fill_shopping_cart 31323 31323 0 0.00 1 22884 39 0 0
sp_fill_shopping_cart_clo_res 457747 457747 0 0.00 0 29996 15 0 0
sp_get_clo_for_invoice 4925 4925 0 0.00 0 35662 309 0 0
sp_kill_cost_storno 17171 17171 0 0.00 0 9134 53 0 0
sp_kill_qstorno_ret_qs2qd 1140850 1140850 0 0.00 0 82345 32 0 0
sp_kill_qty_storno 601352 601352 0 0.00 0 82347 68 0 0
sp_lock_selected_doc 20690 20690 0 0.00 0 8409 7 0 0
sp_make_cost_storno 27655 27655 0 0.00 0 12754 48 0 0
sp_multiply_rows_for_pdistr 27655 27655 0 0.00 0 7962 29 0 0
sp_multiply_rows_for_qdistr 20589 20589 0 0.00 0 12987 181 0 0
sp_pay_from_customer 6912 6912 0 0.00 4 19454 122 0 0
sp_pay_to_supplier 5815 5815 0 0.00 4 19241 130 0 0
sp_payment_common 12727 12727 0 0.00 1 14453 24 0 0
sp_qd_handle_on_cancel_clo 1733 1733 0 0.00 0 11228 331 0 0
sp_qd_handle_on_invoice_upd_sts 10687 10687 0 0.00 0 22140 306 0 0
sp_qd_handle_on_reserve_upd_sts 10003 10003 0 0.00 0 20425 65 0 0
sp_reserve_write_off 10003 10003 0 0.00 6 20484 179 0 0
sp_supplier_invoice 5369 5369 0 0.00 6 87429 3330 0 0
sp_supplier_order 5382 5382 0 0.00 5 40495 1246 0 0
srv_aggregate_perf_data 95 95 0 0.00 2044 265495 23699 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1860 133389 49283 0 0
srv_find_qd_qs_mism 1058552 1058552 0 0.00 0 19602 5 0 0
srv_make_invnt_saldo 518 518 0 0.00 36 166401 1282 0 0
srv_make_money_saldo 752 752 0 0.00 1 44294 113 0 0
srv_recalc_idx_stat 4 4 0 0.00 178353 508726 275424 0 0
t$perf-abend:sp_add_invoice_to_stock 173 173 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4934 4934 0 0.00 0 12764 159 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 2983 65 0 0
t$perf-norm:sp_cancel_client_order 1733 1733 0 0.00 0 6355 15 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 1325 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 1049 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 640 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 769 10 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 23280 1474 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 1847 7 0 0
t$perf-norm:sp_client_order 20589 20589 0 0.00 0 8243 17 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 301 2 0 0
t$perf-norm:sp_pay_from_customer 6912 6912 0 0.00 0 10735 10 0 0
t$perf-norm:sp_pay_to_supplier 5815 5815 0 0.00 0 11594 11 0 0
t$perf-norm:sp_reserve_write_off 10003 10003 0 0.00 0 11303 11 0 0
t$perf-norm:sp_supplier_invoice 5369 5369 0 0.00 0 3197 13 0 0
t$perf-norm:sp_supplier_order 5382 5382 0 0.00 0 13541 16 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 1404 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 122 4 0 0
t$perf-norm:srv_make_invnt_saldo 518 518 0 0.00 0 4054 36 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 2750 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 6 4 0 0
v_add_invoice_to_stock 10196 9850 346 3.39 0 6645 20 0 346
v_all_customers 37652 37652 0 0.00 0 37 0 0 0
v_all_suppliers 29856 29856 0 0.00 0 150 1 0 0
v_all_wares 41178 41178 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 6107 19 0 0
v_cancel_client_order 3466 3466 0 0.00 0 7136 12 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 5279 17 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 6714 11 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 1397 5 0 0
v_cancel_supplier_invoice 782 750 32 4.09 0 5658 28 0 32
v_cancel_supplier_order 850 850 0 0.00 0 3132 8 0 0
v_cancel_write_off 2030 2030 0 0.00 0 5668 25 0 0
v_max_id_clo_ord 5382 5382 0 0.00 0 404 1 0 0
v_max_id_clo_res 965 965 0 0.00 0 1685 10 0 0
v_max_id_ord_sup 5352 5352 0 0.00 0 5750 6 0 0
v_max_non_paid_invoice 1621 1621 0 0.00 0 6305 7 0 0
v_max_non_paid_realizn 3553 3553 0 0.00 0 4889 6 0 0
v_min_id_clo_ord 5382 5382 0 0.00 0 4310 6 0 0
v_min_id_clo_res 965 965 0 0.00 0 4271 14 0 0
v_min_id_ord_sup 5352 5352 0 0.00 0 6113 10 0 0
v_min_non_paid_invoice 5815 5815 0 0.00 0 5129 14 0 0
v_min_non_paid_realizn 6912 6912 0 0.00 0 14444 18 0 0
v_our_firm 10244 10244 0 0.00 0 21 1 0 0
v_reserve_write_off 20006 20006 0 0.00 0 9374 8 0 0
x_lock_depdocs_on_canc_invoice 11647 11647 0 0.00 0 9958 105 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 12618 358 0 0
x_make_qstorno_1000_1200 5382 5382 0 0.00 1 40487 1185 0 0
x_make_qstorno_1200_2000 5369 5369 0 0.00 2 87403 3166 0 0
x_make_qstorno_2100_3300 457756 457756 0 0.00 0 57988 159 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 28-09-2022 01:39:47 to 28-09-2022 01:39:52.

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:31 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:31 19646 8192000000 9883856448 10187116544 965183216 10.540 100 88 176 0 994508528 5908656 23416656
23:10:55 15004 8192000000 9922681808 10239860736 1006048656 10.937 100 88 176 0 1036682800 7660400 22973744
23:12:18 14563 8192000000 9956127440 10284752896 1043530160 11.299 100 77 154 0 1071853648 8642720 19680768
23:13:44 5770 8192000000 10028331392 10374348800 1112528272 11.956 100 75 150 0 1140495472 9332704 18634496
23:14:58 9112 8192000000 10080183440 10435899392 1166054688 12.460 100 81 162 0 1196134880 11477024 18603168
23:16:20 3585 8192000000 10124038496 10495062016 1214567888 12.911 100 74 148 1 1243675152 12575808 16531456
23:17:25 2105 8192000000 10159222464 10532913152 1241658432 13.162 100 81 162 0 1273202256 13592400 17951424
23:18:29 1703 8192000000 10176226400 10557595648 1270724944 13.428 100 78 157 1 1300825648 13551312 16549392
23:19:33 1974 8192000000 10206476144 10592673792 1295682416 13.656 100 80 160 0 1324339104 11187040 17469648
23:20:37 14233 8192000000 10193756272 10604236800 1301898496 13.713 100 20 40 0 1316442976 9943600 4600880
23:21:55 5719 8192000000 10205852096 10616672256 1315437056 13.835 100 27 55 0 1329343600 7567936 6338608
23:23:03 5112 8192000000 10216148224 10628964352 1322237312 13.897 100 25 50 2 1337875968 10607376 5031280
23:24:13 48839 8192000000 10220715520 10640760832 1330409520 13.971 100 44 83 0 1344971712 5009312 9552880
23:26:05 6099 8192000000 10230248560 10651611136 1336214368 14.023 100 34 68 0 1351061872 6377024 8470480
23:27:14 9170 8192000000 10209367648 10626633728 1312301904 13.807 99 21 43 0 1322800176 5353600 5144672
23:28:26 59431 8192000000 10183709776 10582306816 1209099904 12.861 92 31 62 0 1221129424 4802112 7227408
23:30:29 5126 8192000000 10164087168 10571141120 1272608272 13.445 100 31 59 0 1286033904 7193440 6232192
23:31:35 6254 8192000000 10129720800 10528067584 1218343360 12.946 97 31 63 0 1231714352 6232752 7138240
23:32:45 7028 8192000000 10168920720 10570317824 1252364048 13.260 98 30 61 0 1265203040 5293344 7545648
23:33:53 32187 8192000000 10152383216 10562940928 1226514064 13.022 96 37 72 0 1240316032 6081456 7720512
23:35:25 5780 8192000000 10199154656 10603106304 1295181568 13.651 100 21 41 0 1305123408 5099744 4842096
23:36:32 9367 8192000000 10202589392 10616840192 1308782704 13.775 100 26 53 0 1321050912 6412208 5856000
23:37:44 8316 8192000000 10216133792 10630410240 1320376480 13.880 100 20 38 0 1330529440 5653248 4499712
23:38:56 47410 8192000000 10251820864 10666422272 1344051456 14.094 100 44 86 0 1362077968 8654416 9372096
23:40:47 15592 8192000000 10271079488 10684256256 1362343632 14.258 100 54 108 1 1386850832 12540576 11966624
23:42:09 34754 8192000000 10291012672 10715672576 1380771184 14.423 100 45 84 0 1404240960 13048816 10420960
23:43:50 13346 8192000000 10310377200 10729537536 1390962368 14.514 100 48 97 0 1421000304 19177952 10859984
23:45:07 6127 8192000000 10319496992 10744610816 1402367120 14.616 100 46 91 1 1437688832 24634816 10686896
23:46:16 10319 8192000000 10329262576 10759897088 1402314880 14.616 99 41 80 0 1438279808 26057440 9907488
23:47:30 14238 8192000000 10327627984 10748276736 1387224784 14.481 98 47 94 1 1432597488 33343552 12029152
23:48:48 1860 8192000000 10348856896 10766675968 1412284688 14.704 100 63 126 0 1460924528 33689296 14950544
23:49:56 2806 8192000000 10356439360 10777509888 1415961792 14.737 100 56 110 1 1471569568 42008688 13599088
23:51:02 3198 8192000000 10371132240 10786762752 1419254256 14.766 100 71 143 1 1480505936 44190624 17061056
23:52:10 5066 8192000000 10367095712 10779758592 1408325168 14.669 100 70 141 0 1473507168 47172528 18009472
23:53:20 5855 8192000000 10362313472 10774790144 1399309168 14.589 100 63 126 0 1469940272 53943360 16687744
23:54:36 1908 8192000000 10368540432 10772426752 1399140896 14.587 100 79 152 1 1474504400 55739808 19623696
23:55:43 2686 8192000000 10370097008 10779664384 1395699312 14.557 100 78 154 1 1473328688 58219264 19410112
23:56:52 1891 8192000000 10398554624 10810679296 1414415728 14.723 100 76 151 0 1499684464 65740432 19528304
23:57:58 6749 8192000000 10365184512 10787450880 1396944928 14.568 100 53 106 1 1469743520 58506992 14291600
23:59:26 32217 8192000000 10338721856 10768400384 1377316096 14.393 99 31 62 1 1433275520 47418976 8540448
00:01:03 12795 8192000000 10322621552 10757799936 1364463008 14.277 99 28 56 0 1418255600 45590976 8201616
00:02:20 54964 8192000000 10285902656 10731069440 1289323344 13.598 95 20 40 0 1340403696 45507856 5572496
00:04:16 64610 8192000000 10271371632 10726363136 1346322256 14.114 99 21 42 0 1371427696 20086800 5018640
00:06:26 38574 8192000000 10271485552 10719268864 1365579184 14.287 100 20 37 0 1380573104 10640656 4353264
00:08:05 120538 8192000000 10261235824 10709340160 1287223776 13.579 94 13 26 0 1299792144 9891504 2676864
00:11:06 92295 8192000000 10292818688 10738532352 1353504432 14.179 98 21 42 0 1378594160 20151376 4938352
00:15:12 14950 8192000000 10315405936 10753507328 1387787808 14.486 100 39 75 2 1409686352 13024864 8873680
00:16:28 35414 8192000000 10287794144 10734104576 1353967904 14.183 98 32 64 0 1385048320 23315792 7764624
00:18:08 58729 8192000000 10283727552 10741952512 1368025280 14.309 99 14 28 0 1386583840 15331904 3226656
00:20:46 93251 8192000000 10293245952 10744045568 1369492080 14.322 99 35 71 0 1391208928 12336192 9380656
00:24:49 51226 8192000000 10281702928 10739068928 1383868528 14.451 100 20 36 1 1395514096 7477840 4167728
00:26:41 56785 8192000000 10301643728 10765451264 1394689120 14.548 100 13 26 0 1411887552 14344832 2853600
00:28:38 42212 8192000000 10307509120 10786713600 1398465840 14.581 100 8 16 0 1420666064 19990352 2209872
00:30:29 62220 8192000000 10299698192 10760888320 1397682096 14.574 99 34 69 0 1419158208 13392272 8083840
00:32:32 75845 8192000000 10321694560 10784747520 1381218320 14.427 98 23 44 0 1404315968 17970032 5127616
00:34:54 83914 8192000000 10300993040 10753613824 1343433696 14.088 96 22 42 1 1357735024 9784720 4516608
00:37:20 39017 8192000000 10315984576 10760646656 1382360544 14.438 99 28 56 0 1409633904 21363984 5909376
00:39:03 89959 8192000000 10307261920 10758987776 1382920144 14.443 99 10 20 0 1395381392 10760688 1700560
00:41:38 74635 8192000000 10303945424 10751893504 1382612528 14.440 99 44 88 0 1405427664 12482416 10332720
00:45:03 44421 8192000000 10263931568 10729918464 1343571728 14.090 98 3 6 0 1353609984 9839584 198672
00:46:48 50983 8192000000 10285315296 10733166592 1354357504 14.187 98 23 45 0 1366546736 7199296 4989936
00:48:45 22295 8192000000 10283612320 10736992256 1378636816 14.404 100 34 68 0 1396311424 10479152 7195456
00:50:08 65088 8192000000 10260973920 10719125504 1309337216 13.780 96 15 30 0 1327056624 14536544 3182864
00:52:13 70978 8192000000 10239638080 10688561152 1268138720 13.405 94 14 28 0 1282832544 11489856 3203968
00:54:25 54737 8192000000 10252562096 10707812352 1343166016 14.086 100 18 35 0 1358335216 11735408 3433792
00:56:20 104652 8192000000 10267786000 10699669504 1275352416 13.471 94 27 54 0 1296405392 14964928 6088048
00:59:08 127371 8192000000 10276680528 10727981056 1369488176 14.322 100 35 70 0 1390238864 12281760 8468928
01:02:15 71996 8192000000 10278806576 10740006912 1367742128 14.307 99 20 40 0 1386792368 14319008 4731232
01:04:29 64801 8192000000 10274666336 10734424064 1351884032 14.164 98 14 28 0 1367649552 12500720 3264800
01:06:36 85883 8192000000 10270325504 10730881024 1348162464 14.131 98 15 30 0 1371192112 19298992 3730656
01:09:17 35755 8192000000 10286395504 10733780992 1350383088 14.151 98 27 52 1 1369221232 12819888 6018256
01:10:54 31554 8192000000 10288351664 10735144960 1369098400 14.319 99 32 62 0 1383932080 7352080 7481600
01:12:32 41252 8192000000 10291723328 10735419392 1368480608 14.313 98 44 88 1 1391831744 13276992 10074144
01:14:14 80665 8192000000 10294931200 10757140480 1383337392 14.446 100 14 29 0 1402072592 15736672 2998528
01:16:42 58572 8192000000 10294787776 10751803392 1366208000 14.293 98 19 38 0 1382388352 11691472 4488880
01:18:53 92700 8192000000 10308463856 10777825280 1394910944 14.550 100 14 27 0 1414503264 16393968 3198352
01:21:29 56408 8192000000 10300973808 10766630912 1385374400 14.465 99 27 54 0 1402021888 10833696 5813792
01:23:31 133389 8192000000 10263758464 10713792512 1327672400 13.946 95 26 52 0 1342146448 8515504 5958544
01:27:21 19684 8192000000 10271070832 10731253760 1371485184 14.340 100 15 31 0 1388750880 13617232 3648464
01:28:50 51570 8192000000 10259413168 10716291072 1321136608 13.887 96 18 35 0 1332627408 7629840 3860960
01:31:50 69580 8192000000 10245266448 10705051648 1354433792 14.187 100 12 23 1 1363610912 7075344 2101776
01:34:08 54335 8192000000 10247409376 10700541952 1280666992 13.519 94 8 16 1 1289925104 7647856 1610256
01:36:08 100274 8192000000 10275322512 10724368384 1319966112 13.876 97 20 40 2 1339382608 14448752 4967744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-09-2022 01:39:53 to 28-09-2022 01:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 173 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-09-2022 01:40:09 to 28-09-2022 01:40:11.

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          238297
MON$OLDEST_ACTIVE               240339
MON$OLDEST_SNAPSHOT             240339
MON$NEXT_TRANSACTION            240339
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                       2011328
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.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 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 ---
:145: 
:146: mscs-test-161.budzdorov.ru    Wed Sep 28 01:38:34 2022
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:148: 
:149: 
:150: mscs-test-161.budzdorov.ru    Wed Sep 28 01:38:35 2022
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:152: 
:153: 
:154: mscs-test-161.budzdorov.ru    Wed Sep 28 01:38:35 2022
:155:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:156: 
--- end of diff output ---

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

28.09.22 01:40:12. 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-09-28 01:40:15 No severe PSQL-related problems occured
28.09.22 01:40:16. End of report.