Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.03.2022 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 16894952 kB
MemAvailable 31610908 kB
Buffers 0 kB
Cached 14810184 kB
SwapCached 2148 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-03-26 22:38:12
DTS_END                         2022-03-26 22:38:12.9790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/32992
TRN_ID                          231793
ATT_ID                          671
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: 9243.86 22565 1109263
customer order: creation 166.18 384 19941
customer order: refuse 14.75 330 1770
order to supplier: creation 43.73 1298 5247
order to supplier: removal 3.49 97971 419
invoice (draft): creation 44.33 3162 5320
invoice (draft): removal 54.44 718 6533
invoice accept: apply 40.32 17911 4838
invoice accept: cancel 49.28 721 5913
customer reserve: creation 3722.38 178 446685
customer reserve: removal 4811.77 57 577412
realization accept: apply 80.46 173 9655
realization accept: cancel 87.93 101 10552
payment from customer: creation 57.22 115 6866
payment from customer: removal 5.76 91 691
payment to supplier: creation 46.75 122 5610
payment to supplier: removal 3.30 69 396
service: total inventory turnovers 4.65 945 558
service: total monetary turnovers 6.39 41 767
service: aggregate perf. data 0.72 21724 86
service: refresh index statistics 0.03 305183 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-03-2022 22:39:07 to 26-03-2022 22:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4011
TEST_TIME 2 4742
TEST_TIME 3 4764
TEST_TIME 4 4701
TEST_TIME 5 4900
TEST_TIME 6 5220
TEST_TIME 7 5483
TEST_TIME 8 5367
TEST_TIME 9 5503
TEST_TIME 10 5924
TEST_TIME 11 6512
TEST_TIME 12 6538
TEST_TIME 13 6495
TEST_TIME 14 7273
TEST_TIME 15 7567
TEST_TIME 16 7485
TEST_TIME 17 8028
TEST_TIME 18 8439
TEST_TIME 19 8422
TEST_TIME 20 8935
TEST_TIME 21 9184
TEST_TIME 22 9967
TEST_TIME 23 10641
TEST_TIME 24 10784
TEST_TIME 25 10426
TEST_TIME 26 10505
TEST_TIME 27 10420
TEST_TIME 28 10304
TEST_TIME 29 10216
TEST_TIME 30 9953
TEST_TIME 31 9848
TEST_TIME 32 9787
TEST_TIME 33 9784
TEST_TIME 34 9838
TEST_TIME 35 9886
TEST_TIME 36 9943
TEST_TIME 37 9885
TEST_TIME 38 9747
TEST_TIME 39 9576
TEST_TIME 40 9545
TEST_TIME 41 9509
TEST_TIME 42 9551
TEST_TIME 43 9457
TEST_TIME 44 9399
TEST_TIME 45 9379
TEST_TIME 46 9347
TEST_TIME 47 9352
TEST_TIME 48 9356
TEST_TIME 49 9494
TEST_TIME 50 9432
TEST_TIME 51 9402
TEST_TIME 52 9475
TEST_TIME 53 9538
TEST_TIME 54 9475
TEST_TIME 55 9555
TEST_TIME 56 9634
TEST_TIME 57 9630
TEST_TIME 58 9673
TEST_TIME 59 9595
TEST_TIME 60 9498
TEST_TIME 61 9413
TEST_TIME 62 9337
TEST_TIME 63 9334
TEST_TIME 64 9257
TEST_TIME 65 9265
TEST_TIME 66 9232
TEST_TIME 67 9234
TEST_TIME 68 9207
TEST_TIME 69 9234
TEST_TIME 70 9292
TEST_TIME 71 9445
TEST_TIME 72 9426
TEST_TIME 73 9543
TEST_TIME 74 9571
TEST_TIME 75 9576
TEST_TIME 76 9533
TEST_TIME 77 9559
TEST_TIME 78 9506
TEST_TIME 79 9406
TEST_TIME 80 9345
TEST_TIME 81 9413
TEST_TIME 82 9430
TEST_TIME 83 9396
TEST_TIME 84 9330
TEST_TIME 85 9284
TEST_TIME 86 9307
TEST_TIME 87 9280
TEST_TIME 88 9324
TEST_TIME 89 9303
TEST_TIME 90 9279
TEST_TIME 91 9335
TEST_TIME 92 9283
TEST_TIME 93 9348
TEST_TIME 94 9377
TEST_TIME 95 9418
TEST_TIME 96 9418
TEST_TIME 97 9389
TEST_TIME 98 9458
TEST_TIME 99 9460
TEST_TIME 100 9502
TEST_TIME 101 9510
TEST_TIME 102 9428
TEST_TIME 103 9363
TEST_TIME 104 9318
TEST_TIME 105 9318
TEST_TIME 106 9332
TEST_TIME 107 9280
TEST_TIME 108 9304
TEST_TIME 109 9261
TEST_TIME 110 9220
TEST_TIME 111 9264
TEST_TIME 112 9217
TEST_TIME 113 9220
TEST_TIME 114 9248
TEST_TIME 115 9267
TEST_TIME 116 9289
TEST_TIME 117 9280
TEST_TIME 118 9287
TEST_TIME 119 9317
TEST_TIME 120 9245

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-03-2022 22:39:10 to 26-03-2022 22:39:14.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1523339 1523339 0 0.00 0 22845 6 0 0
doc_list_biud 1523338 1523338 0 0.00 0 41326 27 0 0
sp_add_invoice_to_stock 4966 4838 128 2.57 43 193370 17911 0 128
sp_cancel_adding_invoice 5913 5913 0 0.00 4 102382 722 0 0
sp_cancel_client_order 1770 1770 0 0.00 3 7314 330 0 0
sp_cancel_customer_reserve 577412 577412 0 0.00 1 41328 57 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 2 4751 92 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 3216 70 0 0
sp_cancel_supplier_invoice 6549 6533 16 0.24 2 17563 719 0 16
sp_cancel_supplier_order 419 419 0 0.00 5 1040160 97971 0 0
sp_cancel_write_off 10552 10552 0 0.00 1 9332 101 0 0
sp_client_order 19941 19941 0 0.00 3 21785 385 0 0
sp_customer_reserve 446685 446685 0 0.00 0 152576 178 0 0
sp_fill_shopping_cart 30491 30491 0 0.00 1 13464 38 0 0
sp_fill_shopping_cart_clo_res 446682 446682 0 0.00 0 16495 14 0 0
sp_get_clo_for_invoice 4835 4835 0 0.00 0 31945 353 0 0
sp_kill_cost_storno 17552 17552 0 0.00 0 22087 52 0 0
sp_kill_qstorno_ret_qs2qd 1172328 1172328 0 0.00 0 41324 31 0 0
sp_kill_qty_storno 617089 617089 0 0.00 0 41326 66 0 0
sp_lock_selected_doc 20197 20197 0 0.00 0 8060 6 0 0
sp_make_cost_storno 26966 26966 0 0.00 0 18779 54 0 0
sp_multiply_rows_for_pdistr 26966 26966 0 0.00 0 6765 26 0 0
sp_multiply_rows_for_qdistr 19941 19941 0 0.00 0 21757 190 0 0
sp_pay_from_customer 6866 6866 0 0.00 4 18080 116 0 0
sp_pay_to_supplier 5610 5610 0 0.00 4 14452 123 0 0
sp_payment_common 12476 12476 0 0.00 1 18072 28 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 7303 310 0 0
sp_qd_handle_on_invoice_upd_sts 10748 10748 0 0.00 0 21266 309 0 0
sp_qd_handle_on_reserve_upd_sts 9655 9655 0 0.00 0 22618 55 0 0
sp_reserve_write_off 9655 9655 0 0.00 6 22633 174 0 0
sp_supplier_invoice 5320 5320 0 0.00 6 79521 3163 0 0
sp_supplier_order 5247 5247 0 0.00 5 42551 1299 0 0
srv_aggregate_perf_data 86 86 0 0.00 2584 312962 21724 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2229 149420 49477 0 0
srv_find_qd_qs_mism 1063442 1063442 0 0.00 0 22815 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 33 25416 946 0 0
srv_make_money_saldo 767 767 0 0.00 1 10220 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 172608 412917 305184 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 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 4838 4838 0 0.00 0 19063 157 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 1 2290 73 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 5322 18 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 8088 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 648 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 6033 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 2729 27 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 23906 1367 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 2992 9 0 0
t$perf-norm:sp_client_order 19941 19941 0 0.00 0 9168 18 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 3160 11 0 0
t$perf-norm:sp_pay_from_customer 6866 6866 0 0.00 0 14998 10 0 0
t$perf-norm:sp_pay_to_supplier 5610 5610 0 0.00 0 3759 11 0 0
t$perf-norm:sp_reserve_write_off 9655 9655 0 0.00 0 10027 11 0 0
t$perf-norm:sp_supplier_invoice 5320 5320 0 0.00 0 11607 18 0 0
t$perf-norm:sp_supplier_order 5247 5247 0 0.00 0 6162 15 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 213 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 147 3 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 10550 43 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 513 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9926 9670 256 2.57 0 8014 20 0 256
v_all_customers 36314 36314 0 0.00 0 283 1 0 0
v_all_suppliers 29250 29250 0 0.00 0 18 1 0 0
v_all_wares 39882 39882 0 0.00 0 273 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 1653 13 0 0
v_cancel_client_order 3540 3540 0 0.00 0 3947 4 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 452 4 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 972 4 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 839 3 0 0
v_cancel_supplier_invoice 770 738 32 4.15 0 3766 21 0 32
v_cancel_supplier_order 838 838 0 0.00 0 2559 6 0 0
v_cancel_write_off 1938 1938 0 0.00 0 8179 27 0 0
v_max_id_clo_ord 5247 5247 0 0.00 0 1170 2 0 0
v_max_id_clo_res 986 986 0 0.00 0 7531 23 0 0
v_max_id_ord_sup 5303 5303 0 0.00 0 4375 4 0 0
v_max_non_paid_invoice 1535 1535 0 0.00 0 3402 4 0 0
v_max_non_paid_realizn 3616 3616 0 0.00 0 16361 9 0 0
v_min_id_clo_ord 5247 5247 0 0.00 0 2794 5 0 0
v_min_id_clo_res 986 986 0 0.00 0 5872 28 0 0
v_min_id_ord_sup 5303 5303 0 0.00 0 4930 7 0 0
v_min_non_paid_invoice 5610 5610 0 0.00 0 7052 15 0 0
v_min_non_paid_realizn 6866 6866 0 0.00 0 13738 24 0 0
v_our_firm 10068 10068 0 0.00 0 14 1 0 0
v_reserve_write_off 19310 19310 0 0.00 0 6475 6 0 0
x_lock_depdocs_on_canc_invoice 12077 12077 0 0.00 0 13938 99 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 3450 291 0 0
x_make_qstorno_1000_1200 5247 5247 0 0.00 1 42527 1217 0 0
x_make_qstorno_1200_2000 5320 5320 0 0.00 2 79509 3025 0 0
x_make_qstorno_2100_3300 446685 446685 0 0.00 0 152549 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-03-2022 22:39:15 to 26-03-2022 22:39:19.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:16 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:17 20282 8192000000 9895022736 10203983872 978333216 10.668 100 87 174 0 1006988192 5337344 23317632
20:10:45 28771 8192000000 9941617136 10265939968 1027332016 11.143 100 87 173 1 1057655968 7776544 22547408
20:12:18 12533 8192000000 9988717936 10325258240 1079290352 11.641 100 85 170 0 1109831136 9351808 21188976
20:13:40 12984 8192000000 10043441504 10396241920 1133699952 12.156 100 80 158 2 1163216128 11223008 18293168
20:14:58 10292 8192000000 10086292416 10452275200 1180306528 12.593 100 75 151 0 1209761152 12666800 16787824
20:16:12 4967 8192000000 10133513584 10505342976 1222298624 12.983 100 81 161 1 1254029744 14448752 17282368
20:17:18 3216 8192000000 10155091328 10534518784 1247914848 13.219 100 81 159 1 1278259888 12723744 17621296
20:18:23 2603 8192000000 10198705376 10579230720 1282288560 13.534 100 93 189 4 1316800320 13759264 20752496
20:19:27 4891 8192000000 10209972848 10593062912 1298433552 13.681 100 90 179 0 1331174336 12247824 20492960
20:20:34 2062 8192000000 10201487888 10601205760 1303576512 13.728 100 40 78 1 1321446608 8588416 9281680
20:21:38 6142 8192000000 10209316112 10616033280 1312705392 13.811 100 43 86 1 1331116336 9098960 9311984
20:22:47 14179 8192000000 10235284816 10631397376 1319383840 13.871 100 72 148 2 1343071312 5669024 18018448
20:24:02 4123 8192000000 10210666176 10625765376 1320433232 13.881 100 27 53 0 1333558272 7168336 5956704
20:25:06 8300 8192000000 10230618560 10640752640 1328031968 13.949 100 25 48 1 1339188768 5203568 5953232
20:26:17 6969 8192000000 10227425168 10648973312 1339767408 14.055 100 22 44 1 1350497088 5992160 4737520
20:27:28 33237 8192000000 10219756512 10648821760 1329502448 13.963 99 27 53 0 1341838560 6278784 6057328
20:29:01 5800 8192000000 10211235616 10625216512 1284836592 13.557 97 20 40 0 1298684032 9260352 4587088
20:30:08 5065 8192000000 10169111968 10571710464 1232854544 13.080 95 31 62 0 1246318224 6445440 7018240
20:31:16 10258 8192000000 10146042896 10544443392 1212359552 12.891 96 18 35 1 1222515936 5577696 4578688
20:32:28 30614 8192000000 10151702848 10555424768 1213113136 12.898 95 29 58 1 1225512016 5894624 6504256
20:34:01 6333 8192000000 10169135728 10574520320 1276048720 13.477 100 31 62 1 1289632160 6221424 7362016
20:35:09 5251 8192000000 10192293120 10596184064 1290618704 13.610 100 31 60 2 1302674208 5087136 6968368
20:36:15 6504 8192000000 10190394720 10604539904 1302020320 13.714 100 24 48 1 1314406544 6210608 6175616
20:37:23 40566 8192000000 10195704160 10607493120 1306037968 13.750 100 29 59 0 1318544992 5555392 6951632
20:39:15 23004 8192000000 10243405776 10654658560 1334853696 14.011 100 44 88 1 1354263920 9851728 9558496
20:40:41 22824 8192000000 10279409856 10693132288 1361707424 14.253 100 47 94 1 1385945024 12879520 11358080
20:42:15 17677 8192000000 10294651840 10713464832 1373923808 14.362 100 53 104 3 1409403984 22949296 12530880
20:43:36 14716 8192000000 10308131888 10729852928 1387663392 14.485 100 54 106 1 1422649344 20935056 14050896
20:44:58 8866 8192000000 10300623648 10729496576 1391152112 14.516 100 41 81 0 1420779488 19727648 9899728
20:46:12 8544 8192000000 10315305424 10748219392 1400949168 14.603 100 37 72 1 1431837536 21659216 9229152
20:47:24 3250 8192000000 10342923984 10765713408 1410508064 14.688 100 64 125 1 1448764976 24235248 14021664
20:48:33 2307 8192000000 10347348608 10765279232 1395928528 14.559 99 73 145 2 1451427600 38294512 17204560
20:49:40 2229 8192000000 10346069760 10763964416 1392032000 14.524 99 69 134 2 1442623792 34137200 16454592
20:50:48 3082 8192000000 10349258528 10762534912 1394342464 14.545 100 75 149 1 1456988592 44415296 18230832
20:51:57 9151 8192000000 10360600704 10786840576 1408204128 14.668 100 63 126 1 1472558304 48133424 16220752
20:53:16 2891 8192000000 10371343920 10788487168 1411448384 14.697 100 69 137 4 1475384656 46691952 17244320
20:54:24 2337 8192000000 10355372448 10764238848 1393056160 14.533 100 88 176 1 1458230480 43671248 21503072
20:55:33 3708 8192000000 10354915808 10762977280 1381898832 14.434 99 76 152 0 1456470912 54999872 19572208
20:56:41 14664 8192000000 10340067872 10771542016 1395563360 14.555 100 43 85 0 1450724912 44142208 11019344
20:58:03 7969 8192000000 10343480832 10770595840 1391365712 14.518 100 43 86 1 1449391488 46274928 11750848
20:59:15 31623 8192000000 10329359648 10776387584 1390099280 14.507 100 28 56 2 1444202608 46636752 7466576
21:00:49 42219 8192000000 10285771936 10731917312 1336867440 14.029 98 15 30 2 1381114576 39815120 4432016
21:02:32 15888 8192000000 10276509776 10714677248 1357624400 14.216 99 39 78 0 1374229968 6226032 10379536
21:03:55 58792 8192000000 10263654784 10711003136 1326878288 13.939 97 25 50 0 1344380464 11680496 5821680
21:06:05 57819 8192000000 10268643312 10709020672 1339785840 14.055 98 33 66 2 1356828160 9696736 7345584
21:08:07 72767 8192000000 10238180592 10660843520 1282962864 13.540 93 47 93 0 1306979344 12148096 11868384
21:10:27 23865 8192000000 10292521360 10731855872 1371503072 14.341 100 38 74 1 1403817200 22382032 9932096
21:11:58 73192 8192000000 10249304224 10706018304 1336079968 14.022 98 16 32 1 1359313520 19205056 4028496
21:14:13 110848 8192000000 10250404096 10695872512 1318187104 13.860 97 16 30 0 1329884608 8522496 3175008
21:17:08 49091 8192000000 10270249552 10723241984 1337639264 14.036 98 29 58 0 1362766496 18565824 6561408
21:19:00 38148 8192000000 10267228256 10731433984 1363780080 14.271 100 13 26 0 1385863744 18477664 3606000
21:20:39 33404 8192000000 10257291056 10713219072 1368123616 14.310 100 16 32 0 1379092672 7277872 3691184
21:22:13 47902 8192000000 10266788992 10727038976 1372567824 14.350 100 16 31 0 1387116656 11094800 3454032
21:24:03 94478 8192000000 10278617424 10740883456 1339378384 14.052 97 14 28 1 1354325296 11789072 3157840
21:26:38 69408 8192000000 10279382912 10739134464 1367951360 14.309 99 12 24 0 1382803808 12039584 2812864
21:28:49 66401 8192000000 10290446720 10735161344 1373045824 14.354 99 22 43 2 1390352864 12507008 4800032
21:31:00 49180 8192000000 10304383856 10755522560 1390890288 14.514 100 26 52 0 1407590832 11178992 5521552
21:32:51 21995 8192000000 10314161776 10761543680 1385015952 14.461 99 35 70 0 1404781824 12021904 7743968
21:34:17 80684 8192000000 10275822496 10733682688 1310577920 13.791 94 18 36 1 1331141152 16837520 3725712
21:36:42 74758 8192000000 10267377088 10723758080 1355672336 14.198 99 23 42 3 1372429184 12299360 4457488
21:38:57 73201 8192000000 10292416576 10738638848 1371496464 14.340 99 28 53 2 1384637040 7680032 5460544
21:41:16 53875 8192000000 10263488288 10715283456 1350175888 14.149 98 18 36 0 1365835680 11966752 3693040
21:43:11 57716 8192000000 10281704640 10731184128 1343221424 14.086 98 20 40 2 1361507232 13129728 5156080
21:45:09 56422 8192000000 10293954880 10741686272 1358757952 14.226 98 25 50 1 1379790512 14531168 6501392
21:47:12 34884 8192000000 10305109792 10755506176 1373546592 14.359 98 29 58 1 1398514624 17594000 7374032
21:49:01 58718 8192000000 10295024752 10759032832 1350423424 14.151 97 17 34 0 1372701936 17665248 4613264
21:51:10 89341 8192000000 10289094112 10741649408 1348216016 14.131 97 19 38 0 1373314256 20833616 4264624
21:54:00 43034 8192000000 10301912240 10740637696 1350069232 14.148 98 25 50 0 1366460512 10975840 5415440
21:55:50 37852 8192000000 10302225248 10731655168 1339260384 14.051 97 37 73 1 1360604112 12468704 8875024
21:57:38 117129 8192000000 10299264992 10755706880 1369571408 14.323 99 35 70 1 1401766608 24554592 7640608
22:00:36 60405 8192000000 10276695776 10736746496 1362812704 14.263 99 15 30 0 1384904864 18916864 3175296
22:02:43 59941 8192000000 10295108592 10744889344 1383818384 14.451 100 17 34 1 1401494432 13624592 4051456
22:04:43 105621 8192000000 10289082592 10750640128 1336522720 14.026 96 16 32 1 1361112512 20778816 3810976
22:07:29 32840 8192000000 10286587744 10727841792 1351397360 14.160 97 40 80 0 1367829264 7340064 9091840
22:09:08 82349 8192000000 10289634560 10719891456 1265671760 13.382 92 19 38 0 1278120816 8383024 4066032
22:11:31 80067 8192000000 10284159792 10734682112 1346308368 14.114 98 14 28 0 1368984432 19576144 3099920
22:13:51 68521 8192000000 10302699568 10741051392 1364330240 14.276 99 14 28 0 1378876704 11526912 3019552
22:16:05 89430 8192000000 10292810720 10747990016 1354078720 14.184 98 19 38 0 1374967360 16831600 4057040
22:18:35 149420 8192000000 10302743168 10765541376 1375051616 14.372 99 7 14 0 1398997152 22543696 1401840
22:22:20 43086 8192000000 10319505184 10769911808 1406640400 14.654 100 42 84 0 1430336384 13783408 9912576
22:24:03 43363 8192000000 10304703216 10772844544 1382406240 14.438 99 9 18 0 1404574000 20155280 2012480
22:26:45 49317 8192000000 10294975200 10749665280 1378045792 14.399 99 9 18 2 1387280000 7102480 2131728
22:28:39 67709 8192000000 10282439856 10733436928 1341789920 14.074 96 19 38 0 1362169408 15678368 4701120
22:30:51 85254 8192000000 10290527536 10742362112 1358485488 14.224 98 22 44 2 1387376384 23969328 4921568
22:33:57 89289 8192000000 10291688672 10748657664 1349275632 14.141 97 7 14 0 1360720176 9941392 1503152
22:36:27 79061 8192000000 10292813728 10742984704 1361061984 14.247 98 9 18 0 1378292912 15161872 2069056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-03-2022 22:39:20 to 26-03-2022 22:39:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 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 26-03-2022 22:39:36 to 26-03-2022 22:39:38.

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          230237
MON$OLDEST_ACTIVE               231913
MON$OLDEST_SNAPSHOT             231913
MON$NEXT_TRANSACTION            231913
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                       2008256
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:500: 
:501: mscs-test-161.budzdorov.ru    Sat Mar 26 22:38:15 2022
:502:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:503: 
:504: 
:505: mscs-test-161.budzdorov.ru    Sat Mar 26 22:38:15 2022
:506:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:507: 
:508: 
:509: mscs-test-161.budzdorov.ru    Sat Mar 26 22:38:17 2022
:510:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:511: 
:512: 
:513: mscs-test-161.budzdorov.ru    Sat Mar 26 22:38:18 2022
:514:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:515: 
:516: 
:517: mscs-test-161.budzdorov.ru    Sat Mar 26 22:38:18 2022
:518:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:519: 
:520: 
:521: mscs-test-161.budzdorov.ru    Sat Mar 26 22:38:19 2022
:522:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:523: 
:524: 
:525: mscs-test-161.budzdorov.ru    Sat Mar 26 22:38:21 2022
:526:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:527: 
--- end of diff output ---

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

26.03.22 22:39:39. 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-03-26 22:39:42 No severe PSQL-related problems occured
26.03.22 22:39:43. End of report.