Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.12.2021 22:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16609340 kB
MemAvailable 31379320 kB
Buffers 0 kB
Cached 15040852 kB
SwapCached 444 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.9
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 2021-12-20 22:37:58
DTS_END                         2021-12-20 22:37:58.6810
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/60624
TRN_ID                          235399
ATT_ID                          655
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: 9502.53 22795 1140303
customer order: creation 165.34 366 19841
customer order: refuse 14.12 315 1694
order to supplier: creation 43.74 1249 5249
order to supplier: removal 3.62 93356 434
invoice (draft): creation 44.17 3046 5300
invoice (draft): removal 56.44 676 6773
invoice accept: apply 40.42 18821 4851
invoice accept: cancel 51.02 713 6122
customer reserve: creation 3794.81 184 455377
customer reserve: removal 4991.06 53 598927
realization accept: apply 81.63 183 9796
realization accept: cancel 90.98 97 10918
payment from customer: creation 58.74 114 7049
payment from customer: removal 5.51 100 661
payment to supplier: creation 45.95 153 5514
payment to supplier: removal 3.53 91 424
service: total inventory turnovers 4.51 1214 541
service: total monetary turnovers 6.13 52 736
service: aggregate perf. data 0.77 24509 92
service: refresh index statistics 0.03 310610 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-12-2021 22:38:45 to 20-12-2021 22:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3068
TEST_TIME 2 4310
TEST_TIME 3 5461
TEST_TIME 4 5240
TEST_TIME 5 5192
TEST_TIME 6 5273
TEST_TIME 7 5121
TEST_TIME 8 5387
TEST_TIME 9 5470
TEST_TIME 10 5934
TEST_TIME 11 6222
TEST_TIME 12 6063
TEST_TIME 13 6134
TEST_TIME 14 6242
TEST_TIME 15 6638
TEST_TIME 16 6982
TEST_TIME 17 7201
TEST_TIME 18 7702
TEST_TIME 19 7818
TEST_TIME 20 8448
TEST_TIME 21 8700
TEST_TIME 22 8655
TEST_TIME 23 8759
TEST_TIME 24 8895
TEST_TIME 25 9616
TEST_TIME 26 9725
TEST_TIME 27 10302
TEST_TIME 28 10230
TEST_TIME 29 10112
TEST_TIME 30 9816
TEST_TIME 31 9798
TEST_TIME 32 9884
TEST_TIME 33 9929
TEST_TIME 34 9827
TEST_TIME 35 9920
TEST_TIME 36 9854
TEST_TIME 37 9731
TEST_TIME 38 9591
TEST_TIME 39 9515
TEST_TIME 40 9455
TEST_TIME 41 9652
TEST_TIME 42 9743
TEST_TIME 43 9611
TEST_TIME 44 9456
TEST_TIME 45 9456
TEST_TIME 46 9399
TEST_TIME 47 9394
TEST_TIME 48 9426
TEST_TIME 49 9440
TEST_TIME 50 9505
TEST_TIME 51 9386
TEST_TIME 52 9316
TEST_TIME 53 9363
TEST_TIME 54 9310
TEST_TIME 55 9215
TEST_TIME 56 9116
TEST_TIME 57 9213
TEST_TIME 58 9160
TEST_TIME 59 9094
TEST_TIME 60 9002
TEST_TIME 61 9019
TEST_TIME 62 9065
TEST_TIME 63 9104
TEST_TIME 64 9074
TEST_TIME 65 9161
TEST_TIME 66 9291
TEST_TIME 67 9371
TEST_TIME 68 9348
TEST_TIME 69 9397
TEST_TIME 70 9421
TEST_TIME 71 9371
TEST_TIME 72 9395
TEST_TIME 73 9450
TEST_TIME 74 9527
TEST_TIME 75 9566
TEST_TIME 76 9521
TEST_TIME 77 9435
TEST_TIME 78 9468
TEST_TIME 79 9378
TEST_TIME 80 9375
TEST_TIME 81 9323
TEST_TIME 82 9255
TEST_TIME 83 9314
TEST_TIME 84 9269
TEST_TIME 85 9302
TEST_TIME 86 9297
TEST_TIME 87 9359
TEST_TIME 88 9377
TEST_TIME 89 9416
TEST_TIME 90 9422
TEST_TIME 91 9428
TEST_TIME 92 9457
TEST_TIME 93 9493
TEST_TIME 94 9529
TEST_TIME 95 9638
TEST_TIME 96 9618
TEST_TIME 97 9645
TEST_TIME 98 9621
TEST_TIME 99 9558
TEST_TIME 100 9519
TEST_TIME 101 9480
TEST_TIME 102 9474
TEST_TIME 103 9464
TEST_TIME 104 9447
TEST_TIME 105 9398
TEST_TIME 106 9448
TEST_TIME 107 9414
TEST_TIME 108 9360
TEST_TIME 109 9384
TEST_TIME 110 9365
TEST_TIME 111 9368
TEST_TIME 112 9369
TEST_TIME 113 9359
TEST_TIME 114 9366
TEST_TIME 115 9368
TEST_TIME 116 9388
TEST_TIME 117 9373
TEST_TIME 118 9506
TEST_TIME 119 9495
TEST_TIME 120 9508

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-12-2021 22:38:49 to 20-12-2021 22:38:52.

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 1561659 1561659 0 0.00 0 27678 6 0 0
doc_list_biud 1561659 1561659 0 0.00 0 58693 26 0 0
sp_add_invoice_to_stock 4969 4851 118 2.37 37 184816 18822 0 118
sp_cancel_adding_invoice 6122 6122 0 0.00 4 55037 714 0 0
sp_cancel_client_order 1694 1694 0 0.00 4 12053 315 0 0
sp_cancel_customer_reserve 598927 598927 0 0.00 1 58694 53 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 6998 100 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 6369 91 0 0
sp_cancel_supplier_invoice 6780 6773 7 0.10 1 39269 676 0 7
sp_cancel_supplier_order 434 434 0 0.00 6 910951 93356 0 0
sp_cancel_write_off 10918 10918 0 0.00 1 19094 98 0 0
sp_client_order 19841 19841 0 0.00 3 60244 366 0 0
sp_customer_reserve 455377 455377 0 0.00 0 94491 185 0 0
sp_fill_shopping_cart 30378 30378 0 0.00 1 24340 34 0 0
sp_fill_shopping_cart_clo_res 455359 455359 0 0.00 0 16386 13 0 0
sp_get_clo_for_invoice 4832 4832 0 0.00 0 22282 305 0 0
sp_kill_cost_storno 18125 18125 0 0.00 0 14085 54 0 0
sp_kill_qstorno_ret_qs2qd 1215979 1215979 0 0.00 0 58685 29 0 0
sp_kill_qty_storno 639496 639496 0 0.00 0 58690 62 0 0
sp_lock_selected_doc 20300 20300 0 0.00 0 6352 4 0 0
sp_make_cost_storno 27191 27191 0 0.00 0 27440 56 0 0
sp_multiply_rows_for_pdistr 27191 27191 0 0.00 0 9734 29 0 0
sp_multiply_rows_for_qdistr 19841 19841 0 0.00 0 60237 167 0 0
sp_pay_from_customer 7049 7049 0 0.00 4 28763 114 0 0
sp_pay_to_supplier 5514 5514 0 0.00 4 21031 154 0 0
sp_payment_common 12563 12563 0 0.00 2 28758 38 0 0
sp_qd_handle_on_cancel_clo 1694 1694 0 0.00 0 12046 285 0 0
sp_qd_handle_on_invoice_upd_sts 10954 10954 0 0.00 0 19715 308 0 0
sp_qd_handle_on_reserve_upd_sts 9796 9796 0 0.00 0 20790 72 0 0
sp_reserve_write_off 9796 9796 0 0.00 6 20832 184 0 0
sp_supplier_invoice 5300 5300 0 0.00 5 100312 3046 0 0
sp_supplier_order 5249 5249 0 0.00 5 62020 1249 0 0
srv_aggregate_perf_data 92 92 0 0.00 2865 433534 24509 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1737 142227 48436 0 0
srv_find_qd_qs_mism 1093763 1093763 0 0.00 0 11774 5 0 0
srv_make_invnt_saldo 541 541 0 0.00 23 67278 1215 0 0
srv_make_money_saldo 736 736 0 0.00 1 18147 52 0 0
srv_recalc_idx_stat 4 4 0 0.00 131695 499710 310610 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 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 4851 4851 0 0.00 0 13751 168 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 2791 75 0 0
t$perf-norm:sp_cancel_client_order 1694 1694 0 0.00 0 1468 10 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 3689 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 804 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 4641 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 3431 38 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 25165 1537 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 5238 19 0 0
t$perf-norm:sp_client_order 19841 19841 0 0.00 0 25283 23 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 1546 6 0 0
t$perf-norm:sp_pay_from_customer 7049 7049 0 0.00 0 25107 10 0 0
t$perf-norm:sp_pay_to_supplier 5514 5514 0 0.00 0 9799 16 0 0
t$perf-norm:sp_reserve_write_off 9796 9796 0 0.00 0 9438 12 0 0
t$perf-norm:sp_supplier_invoice 5300 5300 0 0.00 0 4281 21 0 0
t$perf-norm:sp_supplier_order 5249 5249 0 0.00 0 6941 17 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 63 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 33 1 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 736 22 0 0
t$perf-norm:srv_make_money_saldo 736 736 0 0.00 0 3679 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 5 0 0
v_add_invoice_to_stock 9893 9658 235 2.37 0 5740 18 0 235
v_all_customers 36786 36786 0 0.00 0 15 0 0 0
v_all_suppliers 29044 29044 0 0.00 0 19 1 0 0
v_all_wares 39680 39680 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 545 7 0 0
v_cancel_client_order 3388 3388 0 0.00 0 7515 9 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 1057 4 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 4310 9 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 4305 9 0 0
v_cancel_supplier_invoice 818 804 14 1.71 0 923 15 0 14
v_cancel_supplier_order 868 868 0 0.00 0 5730 9 0 0
v_cancel_write_off 1912 1912 0 0.00 0 4612 15 0 0
v_max_id_clo_ord 5247 5247 0 0.00 0 380 1 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 2660 17 0 0
v_max_id_ord_sup 5291 5291 0 0.00 0 2863 2 0 0
v_max_non_paid_invoice 1530 1530 0 0.00 0 1439 3 0 0
v_max_non_paid_realizn 3547 3547 0 0.00 0 22553 13 0 0
v_min_id_clo_ord 5247 5247 0 0.00 0 3744 6 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 5980 22 0 0
v_min_id_ord_sup 5291 5291 0 0.00 0 2631 3 0 0
v_min_non_paid_invoice 5514 5514 0 0.00 0 19890 25 0 0
v_min_non_paid_realizn 7049 7049 0 0.00 0 28755 31 0 0
v_our_firm 9898 9898 0 0.00 0 16 1 0 0
v_reserve_write_off 19592 19592 0 0.00 0 9424 6 0 0
x_lock_depdocs_on_canc_invoice 12493 12493 0 0.00 0 16497 107 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 6943 341 0 0
x_make_qstorno_1000_1200 5249 5249 0 0.00 1 62012 1169 0 0
x_make_qstorno_1200_2000 5300 5300 0 0.00 2 100297 2937 0 0
x_make_qstorno_2100_3300 455377 455377 0 0.00 0 94489 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-12-2021 22:38:53 to 20-12-2021 22:38:57.

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:02 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:03 18533 8192000000 9889924336 10198618112 976981952 10.655 100 90 181 0 1006677520 5598512 24097056
20:10:28 23321 8192000000 9936730768 10257780736 1020371232 11.076 100 89 178 0 1051543856 7882224 23290400
20:11:54 14547 8192000000 9973608080 10305138688 1057017424 11.428 100 80 160 0 1085539984 8729888 19792672
20:13:13 11035 8192000000 10038140400 10385281024 1124331504 12.068 100 82 164 1 1153897568 10476128 19089936
20:14:30 7882 8192000000 10089998848 10451025920 1175366320 12.547 100 81 162 1 1207265072 13322400 18576352
20:15:40 6269 8192000000 10122392208 10495430656 1213499808 12.902 100 81 159 2 1243146416 12122496 17524112
20:16:51 5766 8192000000 10154896752 10531524608 1243565568 13.179 100 80 158 0 1274408080 13080384 17762128
20:17:59 2217 8192000000 10193218096 10580238336 1287448224 13.581 100 90 178 0 1320315328 12533184 20333920
20:19:02 21451 8192000000 10212373360 10602115072 1305951664 13.749 100 73 144 1 1332113472 9370224 16791584
20:20:24 11009 8192000000 10200363984 10614697984 1313987856 13.822 100 36 72 1 1329682336 7058112 8636368
20:21:36 66074 8192000000 10218031664 10633244672 1323482688 13.908 100 37 72 1 1337023712 5013120 8527904
20:23:43 4592 8192000000 10228742496 10644160512 1331595424 13.982 100 26 52 1 1344450560 6552272 6302864
20:24:49 10343 8192000000 10228408304 10650386432 1335022032 14.013 100 29 58 1 1350810816 9320288 6468496
20:26:00 9716 8192000000 10234153968 10658099200 1342796576 14.083 100 21 42 2 1353474080 6111168 4566336
20:27:11 29970 8192000000 10228087936 10643783680 1301205840 13.706 97 17 34 0 1310719664 5691616 3822208
20:28:45 8196 8192000000 10198409520 10614378496 1293275920 13.634 98 14 28 0 1302227936 5379312 3572704
20:29:53 8686 8192000000 10171871200 10577182720 1253299248 13.269 98 29 56 2 1265622288 5533520 6789520
20:31:02 59398 8192000000 10146693776 10541031424 1215954992 12.924 96 47 96 1 1232994240 5670848 11368400
20:33:02 13725 8192000000 10160816128 10566926336 1271252624 13.433 100 22 44 2 1282166016 6056416 4856976
20:34:17 13908 8192000000 10182909008 10594144256 1290453584 13.608 100 26 52 3 1302343840 5721264 6168992
20:35:32 12562 8192000000 10189349568 10608746496 1303995856 13.732 100 11 22 0 1311530304 4857680 2676768
20:36:45 33817 8192000000 10195412368 10614583296 1306902688 13.758 100 27 55 1 1318829504 5460160 6466656
20:38:23 8483 8192000000 10235439936 10640781312 1332753728 13.992 100 61 118 2 1354470752 9285776 12431248
20:39:39 11456 8192000000 10266978992 10670223360 1347885040 14.128 100 72 144 1 1375694816 12187936 15621840
20:40:53 21165 8192000000 10268445472 10684813312 1360283152 14.240 100 46 90 1 1380792720 9937648 10571920
20:42:29 37614 8192000000 10276950944 10709889024 1375093904 14.373 100 28 56 0 1394244080 12383088 6767088
20:44:18 17739 8192000000 10310331360 10730991616 1392304096 14.526 100 58 114 1 1419875120 14581792 12989232
20:45:41 5174 8192000000 10333347840 10755629056 1409589184 14.680 100 60 116 1 1440866128 17807680 13469264
20:46:53 22357 8192000000 10330930000 10761015296 1386093888 14.471 98 50 100 1 1420486208 21537664 12854656
20:48:27 8240 8192000000 10348096160 10772852736 1406735136 14.655 99 56 112 2 1442636480 22062784 13838560
20:49:43 8365 8192000000 10348731120 10768228352 1413689152 14.717 100 70 138 0 1458862640 28286432 16887056
20:50:59 6024 8192000000 10341062272 10768130048 1405407328 14.643 99 57 113 1 1445141552 25364512 14369712
20:52:10 1911 8192000000 10347858112 10759774208 1398070880 14.578 99 84 167 0 1449151152 30829104 20251168
20:53:16 2585 8192000000 10341172784 10757910528 1397573456 14.573 100 70 140 3 1448001840 33356512 17071872
20:54:23 1735 8192000000 10338300096 10744897536 1384130016 14.453 100 88 178 3 1439507072 33445856 21931200
20:55:30 2925 8192000000 10347314032 10757246976 1393661408 14.539 100 84 166 0 1448644848 34310736 20672704
20:56:39 7282 8192000000 10316079920 10729385984 1362237936 14.257 99 67 134 0 1412007136 32950256 16818944
20:57:53 10639 8192000000 10303197616 10733805568 1359894192 14.236 99 29 58 3 1401063664 32916080 8253392
20:59:11 20183 8192000000 10291045392 10711314432 1352884160 14.173 99 34 68 1 1390721248 28642608 9194480
21:00:36 21777 8192000000 10307094160 10738434048 1369661088 14.324 100 30 59 0 1416280704 37992496 8627120
21:02:06 25031 8192000000 10273623984 10703630336 1324116688 13.914 98 25 50 1 1365292432 34385760 6789984
21:03:51 18783 8192000000 10276499792 10715217920 1345610032 14.108 99 31 62 1 1378355792 24760464 7985296
21:05:45 35121 8192000000 10265500880 10699759616 1334920176 14.012 98 34 67 1 1356077632 12804320 8353136
21:08:42 78504 8192000000 10248071520 10677948416 1300724912 13.702 96 21 43 1 1316947152 11260272 4961968
21:11:05 65879 8192000000 10280978592 10725543936 1364042560 14.274 100 34 67 0 1386730000 15152928 7534512
21:13:11 120142 8192000000 10291636608 10734587904 1358821088 14.227 98 22 44 2 1375945664 12089344 5035232
21:16:13 34085 8192000000 10312306080 10749927424 1393754224 14.539 100 29 58 0 1413957216 13123456 7079536
21:17:57 80601 8192000000 10299594832 10761498624 1374719568 14.369 99 27 54 0 1402301728 21149712 6432448
21:20:18 95956 8192000000 10308949200 10758549504 1395405024 14.554 100 14 27 0 1410929600 12651344 2873232
21:23:09 55017 8192000000 10302359072 10755379200 1388520272 14.493 99 27 54 1 1413638368 18624544 6493552
21:25:13 101693 8192000000 10316493200 10782687232 1407597744 14.663 100 12 24 0 1432302944 22119696 2585504
21:27:57 79508 8192000000 10312213856 10785353728 1407967408 14.666 100 22 44 1 1428394912 14866512 5560992
21:30:18 50105 8192000000 10321513856 10792439808 1418899632 14.763 100 13 26 0 1436207520 14465456 2842432
21:32:08 82959 8192000000 10325299184 10784624640 1396989344 14.568 98 17 34 3 1416461840 14998272 4474224
21:34:38 72233 8192000000 10306182480 10771484672 1387608448 14.485 98 6 12 0 1405663120 16941232 1113440
21:36:51 41184 8192000000 10297026304 10756882432 1376807968 14.388 98 25 50 1 1391228912 8688704 5732240
21:38:50 73250 8192000000 10290888656 10765017088 1375370592 14.375 98 5 9 2 1387153376 10669536 1113248
21:41:04 34812 8192000000 10296609456 10744799232 1367908480 14.308 98 34 67 0 1382631312 7303888 7418944
21:42:46 74265 8192000000 10280904064 10749804544 1336716272 14.028 96 13 26 2 1354347264 14767728 2863264
21:45:04 44179 8192000000 10291835600 10735865856 1346719136 14.118 98 30 60 0 1366533376 13242880 6571360
21:46:59 48316 8192000000 10271039216 10722660352 1348278608 14.132 99 15 30 0 1366090112 14575392 3236112
21:49:23 113303 8192000000 10275024352 10724499456 1266696432 13.391 92 10 20 1 1286978720 17756032 2526256
21:52:20 131935 8192000000 10255004208 10704207872 1274960592 13.467 93 18 36 1 1290956832 11566704 4429536
21:55:41 53242 8192000000 10263790496 10714759168 1351420864 14.160 100 18 36 1 1368913280 13286928 4205488
21:57:35 51678 8192000000 10253812208 10714558464 1343288208 14.087 99 9 18 0 1361581888 16152880 2140800
21:59:27 63610 8192000000 10268559072 10728697856 1330719584 13.974 98 15 30 0 1354538336 20090848 3727904
22:02:45 125115 8192000000 10252553040 10711646208 1276044064 13.477 94 16 32 0 1298665056 18848064 3772928
22:06:22 42548 8192000000 10257218736 10710011904 1331585472 13.981 98 17 34 0 1349345200 13907968 3851760
22:08:07 13580 8192000000 10276127200 10721906688 1362542352 14.260 100 45 89 3 1382251856 9687664 10021840
22:09:23 40697 8192000000 10279405344 10718773248 1343164176 14.086 98 22 44 0 1357164480 9617568 4382736
22:11:05 52642 8192000000 10316017696 10776129536 1380338480 14.420 100 10 20 1 1399665504 17506704 1820320
22:13:28 142227 8192000000 10291325952 10742337536 1337618448 14.036 96 9 18 1 1360408704 20860640 1929616
22:16:52 68317 8192000000 10301043984 10746822656 1391755072 14.522 100 25 47 1 1403908928 7113648 5040208
22:19:01 31105 8192000000 10326187088 10773819392 1400888400 14.603 100 50 100 0 1424421568 12561296 10971872
22:20:33 77726 8192000000 10311558016 10778038272 1396597008 14.565 99 25 50 1 1421028944 18472256 5959680
22:23:00 49706 8192000000 10305020688 10770669568 1402247584 14.615 100 15 30 0 1419861552 13834816 3779152
22:24:56 74171 8192000000 10296990624 10764976128 1402101456 14.614 100 8 16 0 1412012480 8005456 1905568
22:27:10 47789 8192000000 10285427088 10742910976 1354435520 14.187 97 8 16 0 1364012768 7863408 1713840
22:29:10 22176 8192000000 10283229024 10719981568 1345400080 14.106 98 38 75 2 1360491152 6096048 8995024
22:30:32 21012 8192000000 10287046544 10735058944 1364991040 14.282 99 24 48 0 1379300240 8733792 5575408
22:31:55 62895 8192000000 10307078672 10757083136 1379281696 14.410 99 16 32 1 1398094688 15065840 3747152
22:34:07 101764 8192000000 10257670928 10724982784 1260008704 13.330 91 17 34 0 1282816688 18026368 4781616
22:36:51 40033 8192000000 10271601168 10718732288 1339089760 14.049 98 30 59 3 1356349536 10437104 6822672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-12-2021 22:38:59 to 20-12-2021 22:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 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 20-12-2021 22:39:15 to 20-12-2021 22:39:17.

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          234179
MON$OLDEST_ACTIVE               235879
MON$OLDEST_SNAPSHOT             235879
MON$NEXT_TRANSACTION            235879
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                       2026144
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33546 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33546 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33546 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 ---
:192: 
:193: mscs-test-161.budzdorov.ru    Mon Dec 20 22:38:06 2021
:194:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:195: 
:196: 
:197: mscs-test-161.budzdorov.ru    Mon Dec 20 22:38:07 2021
:198:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:199: 
--- end of diff output ---

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

20.12.21 22:39:18. 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
2021-12-20 22:39:20 No severe PSQL-related problems occured
20.12.21 22:39:21. End of report.