Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.12.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 14749324 kB
MemAvailable 30086884 kB
Buffers 0 kB
Cached 16962848 kB
SwapCached 3320 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-12-16 01:38:31
DTS_END                         2022-12-16 01:38:31.1860
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/44528
TRN_ID                          243282
ATT_ID                          706
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: 9313.71 27773 1117645
customer order: creation 169.69 343 20363
customer order: refuse 14.62 333 1754
order to supplier: creation 45.53 1303 5464
order to supplier: removal 3.41 96366 409
invoice (draft): creation 46.54 3418 5585
invoice (draft): removal 53.05 760 6366
invoice accept: apply 42.18 17954 5062
invoice accept: cancel 47.73 921 5727
customer reserve: creation 3896.37 176 467564
customer reserve: removal 4695.90 54 563508
realization accept: apply 84.13 187 10095
realization accept: cancel 85.42 100 10250
payment from customer: creation 58.87 115 7064
payment from customer: removal 5.59 114 671
payment to supplier: creation 49.09 141 5891
payment to supplier: removal 3.31 86 397
service: total inventory turnovers 4.78 1123 574
service: total monetary turnovers 6.72 45 806
service: aggregate perf. data 0.76 26242 91
service: refresh index statistics 0.03 405671 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-12-2022 01:39:56 to 16-12-2022 01:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2638
TEST_TIME 2 5139
TEST_TIME 3 4683
TEST_TIME 4 5541
TEST_TIME 5 5494
TEST_TIME 6 5784
TEST_TIME 7 6172
TEST_TIME 8 6251
TEST_TIME 9 6208
TEST_TIME 10 6570
TEST_TIME 11 6276
TEST_TIME 12 6367
TEST_TIME 13 6885
TEST_TIME 14 7008
TEST_TIME 15 7098
TEST_TIME 16 7240
TEST_TIME 17 7784
TEST_TIME 18 8055
TEST_TIME 19 7996
TEST_TIME 20 8098
TEST_TIME 21 8360
TEST_TIME 22 8494
TEST_TIME 23 8885
TEST_TIME 24 9027
TEST_TIME 25 9063
TEST_TIME 26 9185
TEST_TIME 27 9208
TEST_TIME 28 9319
TEST_TIME 29 9623
TEST_TIME 30 9848
TEST_TIME 31 10580
TEST_TIME 32 10812
TEST_TIME 33 10612
TEST_TIME 34 10573
TEST_TIME 35 10574
TEST_TIME 36 10700
TEST_TIME 37 10557
TEST_TIME 38 10384
TEST_TIME 39 10403
TEST_TIME 40 10345
TEST_TIME 41 10373
TEST_TIME 42 10217
TEST_TIME 43 10241
TEST_TIME 44 10220
TEST_TIME 45 10121
TEST_TIME 46 10031
TEST_TIME 47 9980
TEST_TIME 48 10010
TEST_TIME 49 10113
TEST_TIME 50 10128
TEST_TIME 51 10061
TEST_TIME 52 9951
TEST_TIME 53 10013
TEST_TIME 54 9849
TEST_TIME 55 9798
TEST_TIME 56 9765
TEST_TIME 57 9751
TEST_TIME 58 9689
TEST_TIME 59 9614
TEST_TIME 60 9552
TEST_TIME 61 9559
TEST_TIME 62 9652
TEST_TIME 63 9600
TEST_TIME 64 9528
TEST_TIME 65 9457
TEST_TIME 66 9525
TEST_TIME 67 9542
TEST_TIME 68 9585
TEST_TIME 69 9589
TEST_TIME 70 9614
TEST_TIME 71 9654
TEST_TIME 72 9616
TEST_TIME 73 9673
TEST_TIME 74 9610
TEST_TIME 75 9721
TEST_TIME 76 9683
TEST_TIME 77 9639
TEST_TIME 78 9664
TEST_TIME 79 9750
TEST_TIME 80 9741
TEST_TIME 81 9688
TEST_TIME 82 9612
TEST_TIME 83 9624
TEST_TIME 84 9554
TEST_TIME 85 9556
TEST_TIME 86 9539
TEST_TIME 87 9569
TEST_TIME 88 9501
TEST_TIME 89 9518
TEST_TIME 90 9499
TEST_TIME 91 9416
TEST_TIME 92 9434
TEST_TIME 93 9478
TEST_TIME 94 9477
TEST_TIME 95 9514
TEST_TIME 96 9531
TEST_TIME 97 9486
TEST_TIME 98 9532
TEST_TIME 99 9539
TEST_TIME 100 9502
TEST_TIME 101 9548
TEST_TIME 102 9569
TEST_TIME 103 9607
TEST_TIME 104 9631
TEST_TIME 105 9604
TEST_TIME 106 9666
TEST_TIME 107 9600
TEST_TIME 108 9536
TEST_TIME 109 9531
TEST_TIME 110 9522
TEST_TIME 111 9504
TEST_TIME 112 9485
TEST_TIME 113 9447
TEST_TIME 114 9408
TEST_TIME 115 9384
TEST_TIME 116 9338
TEST_TIME 117 9358
TEST_TIME 118 9348
TEST_TIME 119 9341
TEST_TIME 120 9314

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-12-2022 01:39:59 to 16-12-2022 01:40:03.

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 1550123 1550123 0 0.00 0 13951 6 0 0
doc_list_biud 1550123 1550123 0 0.00 0 34947 26 0 0
sp_add_invoice_to_stock 5147 5062 85 1.65 46 171107 17954 0 85
sp_cancel_adding_invoice 5727 5727 0 0.00 5 61330 922 0 0
sp_cancel_client_order 1754 1754 0 0.00 3 13428 334 0 0
sp_cancel_customer_reserve 563508 563508 0 0.00 0 24613 54 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 2 7820 114 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 3853 86 0 0
sp_cancel_supplier_invoice 6373 6366 7 0.10 2 14387 760 0 7
sp_cancel_supplier_order 409 409 0 0.00 4 1016634 96366 0 0
sp_cancel_write_off 10250 10250 0 0.00 1 9964 100 0 0
sp_client_order 20363 20363 0 0.00 3 37352 344 0 0
sp_customer_reserve 467564 467564 0 0.00 0 111976 177 0 0
sp_fill_shopping_cart 31396 31396 0 0.00 1 17044 43 0 0
sp_fill_shopping_cart_clo_res 467562 467562 0 0.00 0 14034 11 0 0
sp_get_clo_for_invoice 5060 5060 0 0.00 0 20357 291 0 0
sp_kill_cost_storno 17045 17045 0 0.00 0 9450 54 0 0
sp_kill_qstorno_ret_qs2qd 1144041 1144041 0 0.00 0 27927 29 0 0
sp_kill_qty_storno 603166 603166 0 0.00 0 34943 65 0 0
sp_lock_selected_doc 20942 20942 0 0.00 0 10418 7 0 0
sp_make_cost_storno 28107 28107 0 0.00 0 13054 61 0 0
sp_multiply_rows_for_pdistr 28107 28107 0 0.00 0 8691 24 0 0
sp_multiply_rows_for_qdistr 20363 20363 0 0.00 0 37343 143 0 0
sp_pay_from_customer 7064 7064 0 0.00 4 13653 115 0 0
sp_pay_to_supplier 5891 5891 0 0.00 4 13171 141 0 0
sp_payment_common 12955 12955 0 0.00 1 13646 25 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 13420 304 0 0
sp_qd_handle_on_invoice_upd_sts 10784 10784 0 0.00 0 34937 367 0 0
sp_qd_handle_on_reserve_upd_sts 10095 10095 0 0.00 0 14118 64 0 0
sp_reserve_write_off 10095 10095 0 0.00 6 15337 187 0 0
sp_supplier_invoice 5585 5585 0 0.00 7 103927 3419 0 0
sp_supplier_order 5464 5464 0 0.00 5 39670 1304 0 0
srv_aggregate_perf_data 91 91 0 0.00 2465 322557 26243 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 841 154019 48595 0 0
srv_find_qd_qs_mism 1069641 1069641 0 0.00 0 13950 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 22 71083 1124 0 0
srv_make_money_saldo 806 806 0 0.00 0 6537 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 196446 578923 405671 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 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 5062 5062 0 0.00 0 16018 186 0 0
t$perf-norm:sp_cancel_adding_invoice 461 461 0 0.00 0 4053 116 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 4760 14 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 948 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 7756 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 1542 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 2375 23 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 34769 1714 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 5055 14 0 0
t$perf-norm:sp_client_order 20363 20363 0 0.00 0 6084 19 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 2855 9 0 0
t$perf-norm:sp_pay_from_customer 7064 7064 0 0.00 0 5710 9 0 0
t$perf-norm:sp_pay_to_supplier 5891 5891 0 0.00 0 5261 8 0 0
t$perf-norm:sp_reserve_write_off 10095 10095 0 0.00 0 7706 12 0 0
t$perf-norm:sp_supplier_invoice 5585 5585 0 0.00 0 10954 24 0 0
t$perf-norm:sp_supplier_order 5464 5464 0 0.00 0 4758 15 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 364 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 178 5 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 10965 44 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 2860 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10284 10114 170 1.65 0 4134 19 0 170
v_all_customers 37046 37046 0 0.00 0 24 1 0 0
v_all_suppliers 30658 30658 0 0.00 0 32 1 0 0
v_all_wares 40724 40724 0 0.00 0 121 1 0 0
v_cancel_adding_invoice 922 922 0 0.00 0 1704 8 0 0
v_cancel_client_order 3508 3508 0 0.00 0 3114 7 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 2660 12 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 4100 10 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1443 7 0 0
v_cancel_supplier_invoice 888 874 14 1.57 0 613 13 0 14
v_cancel_supplier_order 818 818 0 0.00 0 2997 11 0 0
v_cancel_write_off 1926 1926 0 0.00 0 2868 17 0 0
v_max_id_clo_ord 5462 5462 0 0.00 0 474 1 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 2340 17 0 0
v_max_id_ord_sup 5570 5570 0 0.00 0 3905 5 0 0
v_max_non_paid_invoice 1594 1594 0 0.00 0 9598 11 0 0
v_max_non_paid_realizn 3904 3904 0 0.00 0 4210 5 0 0
v_min_id_clo_ord 5462 5462 0 0.00 0 5803 9 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 5275 13 0 0
v_min_id_ord_sup 5570 5570 0 0.00 0 7802 12 0 0
v_min_non_paid_invoice 5891 5891 0 0.00 0 9026 17 0 0
v_min_non_paid_realizn 7064 7064 0 0.00 0 13641 17 0 0
v_our_firm 9998 9998 0 0.00 0 43 1 0 0
v_reserve_write_off 20190 20190 0 0.00 0 4835 8 0 0
x_lock_depdocs_on_canc_invoice 11656 11656 0 0.00 0 25006 142 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 9146 417 0 0
x_make_qstorno_1000_1200 5464 5464 0 0.00 2 39619 1212 0 0
x_make_qstorno_1200_2000 5585 5585 0 0.00 3 103896 3265 0 0
x_make_qstorno_2100_3300 467564 467564 0 0.00 0 111974 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-12-2022 01:40:04 to 16-12-2022 01:40:08.

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:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:36 22318 8192000000 9902104672 10211221504 981988752 10.704 100 91 182 0 1011824928 6294064 23542112
23:11:09 21620 8192000000 9935299808 10253058048 1016623504 11.039 100 91 182 0 1048754080 8762448 23368128
23:12:39 10527 8192000000 9958227072 10288902144 1045362352 11.316 100 82 164 0 1076103472 9835712 20905408
23:13:52 8207 8192000000 10015126288 10358984704 1101610192 11.853 100 83 166 0 1131715840 10173968 19931680
23:15:07 6762 8192000000 10083179472 10439426048 1165620304 12.456 100 75 149 0 1194645760 12229376 16796080
23:16:17 6533 8192000000 10116656288 10484297728 1206342016 12.835 100 72 143 0 1234798768 12478016 15978736
23:17:26 2200 8192000000 10150221184 10526969856 1245014240 13.192 100 79 156 0 1273663264 10945216 17703808
23:18:30 999 8192000000 10193191200 10571825152 1278736448 13.501 100 94 185 2 1313003824 13951584 20315792
23:19:32 1033 8192000000 10216809056 10598989824 1297769600 13.675 100 94 188 0 1332338368 14116800 20451968
23:20:33 1025 8192000000 10216634560 10605461504 1303776400 13.730 100 71 137 0 1328375920 7977120 16622400
23:21:35 7224 8192000000 10196466672 10611843072 1314200320 13.824 100 20 40 0 1324079904 5643728 4235856
23:22:43 14381 8192000000 10205968176 10624090112 1324051568 13.913 100 18 36 0 1333485200 5220512 4213120
23:23:59 61525 8192000000 10222338432 10639249408 1332550624 13.990 100 35 66 2 1345938336 4913632 8474080
23:26:01 2966 8192000000 10222509696 10645667840 1333957712 14.003 100 25 48 0 1349335328 10182656 5194960
23:27:05 7631 8192000000 10210722272 10626502656 1312604784 13.810 99 25 50 0 1324378992 6005696 5768512
23:28:14 11006 8192000000 10205040544 10622992384 1300152448 13.697 98 27 54 0 1312590464 5964976 6473040
23:29:28 45297 8192000000 10151369632 10558234624 1169666240 12.494 89 28 56 0 1181444976 4302096 7476640
23:31:17 5712 8192000000 10122454432 10514898944 1179165552 12.582 95 26 52 3 1190644896 5452896 6026448
23:32:23 6786 8192000000 10148612304 10546585600 1244037680 13.183 98 26 47 1 1255405168 4884432 6483056
23:33:34 43609 8192000000 10178179952 10576089088 1256080064 13.294 98 30 61 1 1268069584 4466640 7522880
23:35:30 5567 8192000000 10183809152 10592407552 1290261312 13.607 100 28 56 1 1302282112 5817184 6203616
23:36:37 9939 8192000000 10191460944 10607894528 1302074128 13.714 100 22 45 1 1313182880 5614528 5494224
23:37:47 6126 8192000000 10195738144 10614013952 1308036752 13.768 100 12 24 0 1315826832 5272224 2517856
23:38:55 35045 8192000000 10234539984 10645065728 1330926368 13.976 100 39 79 0 1345715264 5791680 8997216
23:40:37 5502 8192000000 10248337344 10665873408 1349991312 14.147 100 43 86 0 1368206976 8356128 9859536
23:41:45 16555 8192000000 10266156992 10683973632 1355844400 14.200 100 42 81 2 1374649600 9791456 9013744
23:43:08 11227 8192000000 10279232224 10696523776 1366059392 14.292 100 36 73 1 1384701952 9799920 8842640
23:44:25 26543 8192000000 10286676304 10710224896 1352126240 14.167 98 35 64 0 1374063424 13791392 8145792
23:45:55 4181 8192000000 10305711680 10727301120 1389147024 14.498 100 54 104 0 1422429616 19930512 13352080
23:47:02 16831 8192000000 10286626304 10709159936 1360383872 14.241 98 42 84 0 1385482272 15787296 9311104
23:48:28 5161 8192000000 10283751808 10707996672 1368891184 14.317 100 49 99 0 1398327808 18139824 11296800
23:49:39 9851 8192000000 10270830208 10691293184 1335579504 14.018 98 30 60 0 1362746640 19828800 7338336
23:50:51 8430 8192000000 10292467856 10698698752 1339437424 14.052 97 62 120 1 1383690272 29299360 14953488
23:52:06 4778 8192000000 10311991568 10715176960 1364325296 14.276 100 80 160 1 1414820848 30376176 20119376
23:53:17 10093 8192000000 10328138800 10738806784 1380990208 14.425 100 68 135 0 1434193344 37058688 16144448
23:54:32 3501 8192000000 10320542992 10730856448 1368556976 14.314 99 59 117 1 1423179104 39037712 15584416
23:55:39 4189 8192000000 10321022880 10727407616 1359009680 14.228 100 75 146 1 1425768464 48012432 18746352
23:56:47 1023 8192000000 10313549840 10721112064 1365253952 14.285 100 68 135 1 1417805632 36520992 16030688
23:57:57 2315 8192000000 10311803168 10716258304 1346356000 14.115 99 72 142 1 1407306832 42822736 18128096
23:59:05 841 8192000000 10336052720 10734772224 1364405664 14.277 100 95 189 2 1433488208 46946592 22135952
00:00:14 4638 8192000000 10350577968 10754674688 1373659440 14.360 99 80 158 0 1447792384 53711392 20421552
00:01:24 5232 8192000000 10350097664 10765930496 1393173872 14.534 100 54 107 1 1452663296 46351472 13137952
00:02:37 9870 8192000000 10365616656 10790662144 1405755264 14.646 100 52 103 0 1470904080 51665488 13483328
00:04:02 13317 8192000000 10356512096 10791608320 1407031472 14.658 100 37 70 2 1465561760 48643888 9886400
00:05:19 19398 8192000000 10357591552 10800406528 1403324656 14.625 100 25 50 0 1463600784 52948368 7327760
00:06:48 20748 8192000000 10361032096 10802561024 1402200896 14.615 100 25 50 0 1463740368 54180912 7358560
00:08:21 36126 8192000000 10352682032 10791915520 1403101568 14.623 100 18 36 0 1458706416 49890896 5713952
00:10:00 56421 8192000000 10342643408 10777190400 1388483696 14.492 98 35 63 2 1412520848 17606064 6431088
00:12:00 78243 8192000000 10341610688 10793971712 1413906400 14.719 100 37 74 1 1446852944 23987856 8958688
00:14:20 34753 8192000000 10319119600 10778345472 1407470944 14.661 100 20 39 0 1433151952 21278784 4402224
00:17:05 120025 8192000000 10312333184 10768740352 1366777904 14.298 97 13 26 0 1387015440 17016400 3221136
00:20:22 36819 8192000000 10324152384 10771304448 1406893552 14.656 100 31 60 3 1428336624 14353488 7089584
00:22:14 100338 8192000000 10306230896 10766340096 1385959504 14.470 99 17 35 0 1406899728 17378768 3561456
00:25:03 40783 8192000000 10320466512 10770890752 1401375872 14.607 100 20 40 1 1414654272 9048816 4229584
00:26:44 143429 8192000000 10299511968 10760663040 1351189200 14.158 96 10 20 0 1371107552 17618032 2300320
00:30:08 49394 8192000000 10306297808 10756329472 1379615232 14.413 99 15 30 0 1396662720 13653328 3394160
00:32:03 102230 8192000000 10320320752 10775027712 1392530320 14.528 99 20 38 0 1407610576 10970352 4109904
00:34:46 48910 8192000000 10314235744 10771128320 1410381168 14.687 100 39 77 0 1428054544 9664336 8009040
00:36:50 56619 8192000000 10289813888 10742312960 1327535568 13.945 94 15 28 0 1345357648 14463136 3358944
00:38:47 53485 8192000000 10296068128 10756198400 1382461472 14.439 99 35 65 0 1407435648 16350320 8623856
00:40:51 110948 8192000000 10215473552 10657759232 1209839232 12.868 88 19 38 0 1227084944 13136128 4109584
00:43:43 104945 8192000000 10239694352 10702901248 1282668256 13.537 94 12 24 1 1304501072 19212096 2620720
00:46:36 62928 8192000000 10255350064 10698817536 1287959824 13.586 95 12 20 1 1302050272 11826496 2263952
00:48:41 49909 8192000000 10278730240 10717429760 1321874352 13.894 97 22 44 0 1336929504 10168624 4886528
00:50:33 65787 8192000000 10266844096 10722430976 1303660672 13.729 95 13 26 0 1321721552 15183888 2876992
00:52:41 56199 8192000000 10272057312 10720526336 1342820352 14.083 98 20 33 0 1360196432 13630272 3745808
00:54:38 65375 8192000000 10295196048 10734751744 1353732784 14.181 99 24 49 0 1367795920 8646688 5416448
00:56:46 52964 8192000000 10288382384 10730913792 1359894048 14.236 99 20 40 0 1377232944 12910256 4428640
00:58:45 105396 8192000000 10284804448 10738708480 1379962016 14.416 100 15 30 0 1397612288 14301200 3349072
01:01:37 68570 8192000000 10297092688 10740486144 1380781824 14.424 99 30 60 0 1398438448 10992816 6663808
01:04:14 87288 8192000000 10295456240 10759368704 1379899648 14.416 99 12 24 0 1393690224 10985872 2804704
01:06:43 60090 8192000000 10297951056 10763493376 1394696688 14.548 100 16 28 1 1415008320 17421376 2890256
01:08:44 69558 8192000000 10281525584 10727632896 1346782752 14.119 97 15 29 0 1359078464 9316720 2978992
01:10:55 66347 8192000000 10287995920 10752192512 1376266576 14.383 99 9 18 0 1392959200 14761152 1931472
01:13:12 69116 8192000000 10301530656 10753085440 1373421248 14.358 98 21 40 0 1385108944 7196608 4491088
01:15:21 43785 8192000000 10307236176 10760298496 1377321312 14.393 98 41 83 0 1396839408 9838672 9679424
01:17:05 76416 8192000000 10322445632 10771992576 1389031552 14.497 99 19 38 1 1403719984 10321536 4366896
01:19:32 70207 8192000000 10311965904 10771566592 1399299328 14.589 100 24 48 1 1416414400 12203632 4911440
01:21:45 81921 8192000000 10306648944 10766966784 1352934848 14.174 96 6 12 0 1369596032 15544720 1116464
01:24:12 154018 8192000000 10309627776 10755252224 1337486960 14.035 95 18 36 0 1356295664 14245968 4562736
01:27:47 25857 8192000000 10297751136 10736857088 1373131280 14.355 99 47 94 1 1391092144 7500192 10460672
01:29:16 81654 8192000000 10265634416 10725580800 1335272320 14.015 97 18 36 0 1349537744 10073376 4192048
01:31:38 61762 8192000000 10277788512 10733854720 1367618112 14.306 99 7 10 1 1375265520 6051408 1596000
01:34:58 59509 8192000000 10269361488 10732040192 1364589920 14.279 99 16 32 0 1376793840 8822464 3381456
01:37:01 65400 8192000000 10244844448 10691362816 1271942624 13.439 93 22 44 0 1289850560 12912368 4995568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-12-2022 01:40:10 to 16-12-2022 01:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 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 16-12-2022 01:40:26 to 16-12-2022 01:40:28.

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          243123
MON$OLDEST_ACTIVE               243974
MON$OLDEST_SNAPSHOT             243974
MON$NEXT_TRANSACTION            243974
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                       2062880
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:101: 
:102: mscs-test-161.budzdorov.ru    Fri Dec 16 01:38:39 2022
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Fri Dec 16 01:38:39 2022
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:108: 
:109: 
:110: mscs-test-161.budzdorov.ru    Fri Dec 16 01:38:40 2022
:111:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:112: 
:113: 
:114: mscs-test-161.budzdorov.ru    Fri Dec 16 01:38:43 2022
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:116: 
--- end of diff output ---

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

16.12.22 01:40:29. 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-12-16 01:40:32 No severe PSQL-related problems occured
16.12.22 01:40:33. End of report.