Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.12.2022 22:40
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 15048236 kB
MemAvailable 30104108 kB
Buffers 0 kB
Cached 16688292 kB
SwapCached 3344 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-15 22:38:32
DTS_END                         2022-12-15 22:38:32.2150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42508
TRN_ID                          234973
ATT_ID                          682
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: 9361.80 21537 1123416
customer order: creation 165.74 341 19889
customer order: refuse 14.22 368 1706
order to supplier: creation 43.00 1261 5160
order to supplier: removal 3.25 117379 390
invoice (draft): creation 44.64 3212 5357
invoice (draft): removal 55.14 879 6617
invoice accept: apply 40.33 18942 4839
invoice accept: cancel 49.58 771 5950
customer reserve: creation 3798.34 183 455801
customer reserve: removal 4851.47 60 582176
realization accept: apply 80.90 223 9708
realization accept: cancel 89.08 118 10690
payment from customer: creation 57.97 164 6956
payment from customer: removal 5.62 111 674
payment to supplier: creation 47.63 199 5716
payment to supplier: removal 3.03 75 364
service: total inventory turnovers 4.57 1396 548
service: total monetary turnovers 6.48 42 777
service: aggregate perf. data 0.78 25739 94
service: refresh index statistics 0.03 259268 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-12-2022 22:40:39 to 15-12-2022 22:40:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4018
TEST_TIME 2 2587
TEST_TIME 3 3787
TEST_TIME 4 4449
TEST_TIME 5 4134
TEST_TIME 6 4511
TEST_TIME 7 5116
TEST_TIME 8 4989
TEST_TIME 9 5156
TEST_TIME 10 5562
TEST_TIME 11 5768
TEST_TIME 12 5916
TEST_TIME 13 6191
TEST_TIME 14 6248
TEST_TIME 15 6876
TEST_TIME 16 6941
TEST_TIME 17 7308
TEST_TIME 18 7320
TEST_TIME 19 7492
TEST_TIME 20 7647
TEST_TIME 21 8166
TEST_TIME 22 7988
TEST_TIME 23 8138
TEST_TIME 24 8554
TEST_TIME 25 8522
TEST_TIME 26 8580
TEST_TIME 27 8622
TEST_TIME 28 8552
TEST_TIME 29 8705
TEST_TIME 30 9144
TEST_TIME 31 9722
TEST_TIME 32 11042
TEST_TIME 33 10977
TEST_TIME 34 10879
TEST_TIME 35 10619
TEST_TIME 36 10433
TEST_TIME 37 10240
TEST_TIME 38 10383
TEST_TIME 39 10227
TEST_TIME 40 10120
TEST_TIME 41 10046
TEST_TIME 42 9884
TEST_TIME 43 9764
TEST_TIME 44 9832
TEST_TIME 45 9869
TEST_TIME 46 9779
TEST_TIME 47 9685
TEST_TIME 48 9681
TEST_TIME 49 9617
TEST_TIME 50 9567
TEST_TIME 51 9432
TEST_TIME 52 9386
TEST_TIME 53 9322
TEST_TIME 54 9313
TEST_TIME 55 9415
TEST_TIME 56 9420
TEST_TIME 57 9446
TEST_TIME 58 9409
TEST_TIME 59 9473
TEST_TIME 60 9459
TEST_TIME 61 9547
TEST_TIME 62 9643
TEST_TIME 63 9620
TEST_TIME 64 9646
TEST_TIME 65 9670
TEST_TIME 66 9670
TEST_TIME 67 9630
TEST_TIME 68 9766
TEST_TIME 69 9784
TEST_TIME 70 9659
TEST_TIME 71 9572
TEST_TIME 72 9534
TEST_TIME 73 9527
TEST_TIME 74 9492
TEST_TIME 75 9537
TEST_TIME 76 9493
TEST_TIME 77 9485
TEST_TIME 78 9415
TEST_TIME 79 9374
TEST_TIME 80 9374
TEST_TIME 81 9373
TEST_TIME 82 9359
TEST_TIME 83 9345
TEST_TIME 84 9335
TEST_TIME 85 9343
TEST_TIME 86 9346
TEST_TIME 87 9411
TEST_TIME 88 9428
TEST_TIME 89 9496
TEST_TIME 90 9590
TEST_TIME 91 9595
TEST_TIME 92 9617
TEST_TIME 93 9630
TEST_TIME 94 9626
TEST_TIME 95 9590
TEST_TIME 96 9517
TEST_TIME 97 9462
TEST_TIME 98 9475
TEST_TIME 99 9466
TEST_TIME 100 9446
TEST_TIME 101 9394
TEST_TIME 102 9364
TEST_TIME 103 9304
TEST_TIME 104 9265
TEST_TIME 105 9320
TEST_TIME 106 9307
TEST_TIME 107 9291
TEST_TIME 108 9303
TEST_TIME 109 9343
TEST_TIME 110 9323
TEST_TIME 111 9363
TEST_TIME 112 9341
TEST_TIME 113 9390
TEST_TIME 114 9406
TEST_TIME 115 9438
TEST_TIME 116 9465
TEST_TIME 117 9428
TEST_TIME 118 9397
TEST_TIME 119 9384
TEST_TIME 120 9364

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-12-2022 22:40:43 to 15-12-2022 22:40:46.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1543901 1543901 0 0.00 0 31225 7 0 0
doc_list_biud 1543901 1543901 0 0.00 0 39262 29 0 0
sp_add_invoice_to_stock 4923 4839 84 1.70 31 207017 18943 0 84
sp_cancel_adding_invoice 5950 5950 0 0.00 4 57442 772 0 0
sp_cancel_client_order 1706 1706 0 0.00 4 14277 369 0 0
sp_cancel_customer_reserve 582176 582176 0 0.00 0 20501 60 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 2 6505 112 0 0
sp_cancel_pay_to_supplier 364 364 0 0.00 3 5348 75 0 0
sp_cancel_supplier_invoice 6621 6617 4 0.06 2 39280 879 0 4
sp_cancel_supplier_order 390 390 0 0.00 5 1023923 117379 0 0
sp_cancel_write_off 10690 10690 0 0.00 1 21071 119 0 0
sp_client_order 19889 19889 0 0.00 3 21337 342 0 0
sp_customer_reserve 455801 455801 0 0.00 0 100715 183 0 0
sp_fill_shopping_cart 30403 30403 0 0.00 1 20977 42 0 0
sp_fill_shopping_cart_clo_res 455795 455795 0 0.00 0 26365 15 0 0
sp_get_clo_for_invoice 4833 4833 0 0.00 0 15068 319 0 0
sp_kill_cost_storno 17678 17678 0 0.00 0 20886 60 0 0
sp_kill_qstorno_ret_qs2qd 1182049 1182049 0 0.00 0 39253 33 0 0
sp_kill_qty_storno 622070 622070 0 0.00 0 39258 71 0 0
sp_lock_selected_doc 20078 20078 0 0.00 0 9063 6 0 0
sp_make_cost_storno 27213 27213 0 0.00 0 31218 74 0 0
sp_multiply_rows_for_pdistr 27213 27213 0 0.00 0 27376 40 0 0
sp_multiply_rows_for_qdistr 19889 19889 0 0.00 0 11755 147 0 0
sp_pay_from_customer 6956 6956 0 0.00 4 30649 165 0 0
sp_pay_to_supplier 5716 5716 0 0.00 4 30810 199 0 0
sp_payment_common 12672 12672 0 0.00 1 27168 68 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 0 14267 339 0 0
sp_qd_handle_on_invoice_upd_sts 10783 10783 0 0.00 0 24220 353 0 0
sp_qd_handle_on_reserve_upd_sts 9708 9708 0 0.00 0 13804 64 0 0
sp_reserve_write_off 9708 9708 0 0.00 6 31274 224 0 0
sp_supplier_invoice 5357 5357 0 0.00 6 70900 3212 0 0
sp_supplier_order 5160 5160 0 0.00 5 39774 1262 0 0
srv_aggregate_perf_data 94 94 0 0.00 2612 235243 25740 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1026 128243 43814 0 0
srv_find_qd_qs_mism 1076132 1076132 0 0.00 0 25449 5 0 0
srv_make_invnt_saldo 548 548 0 0.00 51 101638 1397 0 0
srv_make_money_saldo 777 777 0 0.00 1 10724 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 191375 335338 259269 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4839 4839 0 0.00 0 11416 203 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 0 13622 197 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 5231 21 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 3606 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 1564 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 364 364 0 0.00 0 1550 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 3601 27 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 20982 1789 0 0
t$perf-norm:sp_cancel_write_off 937 937 0 0.00 0 3271 13 0 0
t$perf-norm:sp_client_order 19889 19889 0 0.00 0 9743 17 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 4754 15 0 0
t$perf-norm:sp_pay_from_customer 6956 6956 0 0.00 0 5348 14 0 0
t$perf-norm:sp_pay_to_supplier 5716 5716 0 0.00 0 10473 12 0 0
t$perf-norm:sp_reserve_write_off 9708 9708 0 0.00 0 9961 11 0 0
t$perf-norm:sp_supplier_invoice 5357 5357 0 0.00 0 9185 24 0 0
t$perf-norm:sp_supplier_order 5160 5160 0 0.00 0 12482 25 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 156 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 3505 57 0 0
t$perf-norm:srv_make_invnt_saldo 548 548 0 0.00 0 10368 50 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 1098 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 18 8 0 0
v_add_invoice_to_stock 9834 9666 168 1.70 0 7888 22 0 168
v_all_customers 36778 36778 0 0.00 0 32 1 0 0
v_all_suppliers 29602 29602 0 0.00 0 68 1 0 0
v_all_wares 39778 39778 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 2075 10 0 0
v_cancel_client_order 3412 3412 0 0.00 0 4921 8 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 4349 11 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 6885 14 0 0
v_cancel_payment_to_supplier 728 728 0 0.00 0 5319 16 0 0
v_cancel_supplier_invoice 862 854 8 0.92 0 3835 29 0 8
v_cancel_supplier_order 780 780 0 0.00 0 1060 7 0 0
v_cancel_write_off 1874 1874 0 0.00 0 4422 21 0 0
v_max_id_clo_ord 5159 5159 0 0.00 0 1696 2 0 0
v_max_id_clo_res 993 993 0 0.00 0 6229 15 0 0
v_max_id_ord_sup 5355 5355 0 0.00 0 6477 5 0 0
v_max_non_paid_invoice 1429 1429 0 0.00 0 1577 6 0 0
v_max_non_paid_realizn 3478 3478 0 0.00 0 1696 5 0 0
v_min_id_clo_ord 5159 5159 0 0.00 0 9467 10 0 0
v_min_id_clo_res 993 993 0 0.00 0 6879 24 0 0
v_min_id_ord_sup 5355 5355 0 0.00 0 6126 7 0 0
v_min_non_paid_invoice 5716 5716 0 0.00 0 22868 62 0 0
v_min_non_paid_realizn 6956 6956 0 0.00 0 26365 59 0 0
v_our_firm 9956 9956 0 0.00 0 23 1 0 0
v_reserve_write_off 19416 19416 0 0.00 0 6948 8 0 0
x_lock_depdocs_on_canc_invoice 12140 12140 0 0.00 0 17885 127 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 7846 379 0 0
x_make_qstorno_1000_1200 5160 5160 0 0.00 1 39461 1180 0 0
x_make_qstorno_1200_2000 5357 5357 0 0.00 3 70866 3060 0 0
x_make_qstorno_2100_3300 455801 455801 0 0.00 0 100712 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-12-2022 22:40:47 to 15-12-2022 22:40:51.

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:36 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:36 24347 8192000000 9890907152 10196942848 972503744 10.611 100 87 174 0 1001247792 5970544 22773504
20:11:05 18689 8192000000 9944886224 10263592960 1025944864 11.129 100 91 182 0 1057669536 8151856 23572816
20:12:36 9088 8192000000 9971535840 10300907520 1054554224 11.404 100 88 176 0 1087148784 9747424 22847136
20:13:55 9635 8192000000 10031360736 10376114176 1112492496 11.956 100 84 168 1 1144178000 11283728 20401776
20:15:08 5202 8192000000 10068222000 10423164928 1153139136 12.339 100 83 166 1 1184960384 12556160 19265088
20:16:18 4629 8192000000 10108528048 10477998080 1199824192 12.775 100 73 146 0 1228610992 12247744 16539056
20:17:24 1529 8192000000 10157909664 10529275904 1246262784 13.204 100 89 179 0 1278273232 11556096 20454352
20:18:28 1230 8192000000 10190052864 10567479296 1272182704 13.442 100 84 168 1 1304345952 13685312 18477936
20:19:31 1072 8192000000 10210817728 10595586048 1295263568 13.652 100 89 176 0 1327134416 12942688 18928160
20:20:34 26680 8192000000 10210973200 10609160192 1303553952 13.728 100 51 104 0 1322221408 6037472 12629984
20:22:01 4486 8192000000 10202249216 10615902208 1317977568 13.858 100 28 56 1 1330744384 6705136 6061680
20:23:06 10420 8192000000 10210334480 10625445888 1323203872 13.906 100 16 32 0 1332355152 5955088 3196192
20:24:17 31185 8192000000 10222889312 10649894912 1334016960 14.003 100 17 35 0 1342607248 4941472 3648816
20:26:14 3871 8192000000 10239656656 10655686656 1341626384 14.072 100 33 66 0 1357115264 7947616 7541264
20:27:20 5967 8192000000 10236421712 10653675520 1340100672 14.058 100 16 32 1 1348902176 5084000 3717504
20:28:26 15662 8192000000 10220519360 10643955712 1322609648 13.900 99 16 30 1 1332483376 5605488 4268240
20:29:42 40767 8192000000 10161504896 10564911104 1162662832 12.428 88 36 72 1 1175922400 4761888 8497680
20:31:25 6738 8192000000 10157440784 10561548288 1248645184 13.226 98 18 36 1 1257979184 5053056 4280944
20:32:32 9050 8192000000 10108958096 10501029888 1172990096 12.525 94 28 57 0 1185978192 6543296 6444800
20:33:42 8398 8192000000 10153317968 10555998208 1247853376 13.218 98 10 20 1 1255095872 5033424 2209072
20:34:53 18356 8192000000 10157624864 10570231808 1270701488 13.428 100 16 32 1 1279726544 5335248 3689808
20:36:17 7965 8192000000 10187440048 10603425792 1297673312 13.674 100 25 51 2 1309339904 5680128 5986464
20:37:25 11957 8192000000 10199855792 10614517760 1306758832 13.757 100 20 40 1 1316447520 5132240 4556448
20:38:41 65504 8192000000 10244514832 10655932416 1335154720 14.014 100 39 77 0 1348896416 4856960 8884736
20:40:48 52879 8192000000 10255264976 10677207040 1351804736 14.164 100 30 60 1 1367820624 9361792 6654096
20:42:49 54296 8192000000 10283410896 10708070400 1376483280 14.385 100 35 69 2 1393258832 8307552 8468000
20:44:48 13125 8192000000 10302666608 10722521088 1383543456 14.448 100 51 101 1 1413039712 17949920 11546336
20:46:07 9145 8192000000 10308843040 10735951872 1398927536 14.585 100 41 81 1 1423194160 14478384 9788240
20:47:22 6971 8192000000 10321948368 10750058496 1408367712 14.669 100 43 86 0 1434736480 15969152 10399616
20:48:39 3104 8192000000 10327470256 10750414848 1405193216 14.641 100 59 118 2 1440000048 20574784 14232048
20:49:52 12353 8192000000 10310895232 10738929664 1387034336 14.479 99 42 84 1 1416390304 19043616 10312352
20:51:09 1018 8192000000 10339117616 10760527872 1411560032 14.698 100 65 130 0 1448858832 21870880 15427920
20:52:13 3495 8192000000 10338107472 10753785856 1383482064 14.448 98 74 143 0 1424866752 23856096 17528592
20:53:20 1782 8192000000 10331391680 10749550592 1391661232 14.521 100 65 126 0 1437369744 30160944 15547568
20:54:28 2756 8192000000 10343701248 10757996544 1395019872 14.551 100 71 142 0 1448291120 36137088 17134160
20:55:42 1303 8192000000 10353424912 10777616384 1395757664 14.557 99 63 126 0 1446102912 34558112 15787136
20:56:48 5399 8192000000 10363862608 10777862144 1401887024 14.612 100 71 141 0 1464342256 45179936 17275296
20:58:00 2096 8192000000 10359433696 10768568320 1386378272 14.474 100 86 173 0 1463201856 54998272 21825312
20:59:05 4920 8192000000 10341097792 10764300288 1377266544 14.392 100 56 112 0 1453563312 61639152 14657616
21:00:13 7789 8192000000 10329111056 10743595008 1348820464 14.137 98 47 93 0 1416034176 54744288 12469424
21:01:26 2644 8192000000 10359110640 10769244160 1379665616 14.414 100 64 126 1 1460655120 64629824 16359680
21:02:42 3590 8192000000 10355188016 10784210944 1385757248 14.468 100 41 82 0 1465280784 67976480 11547056
21:04:12 8178 8192000000 10361520560 10783793152 1393028832 14.533 100 39 77 1 1463498080 59801200 10668048
21:05:56 20093 8192000000 10369067136 10802716672 1386274288 14.473 99 29 58 1 1466944784 72400896 8269600
21:07:25 18761 8192000000 10350769536 10778529792 1367211456 14.302 100 31 62 0 1456227248 79627920 9387872
21:08:59 21042 8192000000 10349070208 10782859264 1363189424 14.266 100 25 50 0 1451252864 80393856 7669584
21:10:20 40209 8192000000 10277604992 10722168832 1358126672 14.221 99 17 34 0 1392123280 30425920 3570688
21:12:19 42887 8192000000 10263402800 10709344256 1362014576 14.255 100 16 32 0 1373887728 8244672 3628480
21:14:16 51336 8192000000 10296654960 10734931968 1383414016 14.447 100 40 78 0 1401960592 9812272 8734304
21:16:19 57249 8192000000 10284702160 10736218112 1380519120 14.421 100 21 44 0 1396216352 10785952 4911280
21:18:17 98569 8192000000 10287623472 10747346944 1381532576 14.430 99 23 46 1 1401094992 14482096 5080320
21:21:05 73867 8192000000 10300088608 10767712256 1387498832 14.484 99 19 38 0 1414049936 21974304 4576800
21:23:24 75766 8192000000 10284261616 10752598016 1378183632 14.400 99 11 23 0 1396920688 15905392 2831664
21:25:47 82167 8192000000 10301482288 10769846272 1398321968 14.580 100 26 51 0 1416228592 11838448 6068176
21:28:10 70249 8192000000 10290817712 10760785920 1386126848 14.471 99 12 24 1 1401752704 12286752 3339104
21:30:21 87194 8192000000 10293540176 10753204224 1366417040 14.295 97 15 31 0 1382293968 12070160 3806768
21:32:55 91045 8192000000 10285750288 10764578816 1355355312 14.196 97 13 26 0 1377370016 18458528 3556176
21:35:28 43802 8192000000 10317138800 10760941568 1397724864 14.575 100 36 73 0 1418106576 11963088 8418624
21:37:16 49858 8192000000 10291956704 10754465792 1371714864 14.342 98 15 30 0 1385794080 11037264 3041952
21:39:10 43913 8192000000 10310834160 10769641472 1391487440 14.519 99 24 48 0 1410604448 13928944 5188064
21:41:05 67809 8192000000 10283818896 10731311104 1335119984 14.013 95 10 20 1 1345286176 8557184 1609008
21:43:16 73496 8192000000 10308298000 10777845760 1372894208 14.353 98 12 24 0 1394216272 18555552 2766512
21:45:30 18729 8192000000 10324723632 10772148224 1399545968 14.591 99 46 92 2 1422574064 12760864 10267232
21:46:52 128243 8192000000 10296010480 10751549440 1333968800 14.003 95 17 34 0 1347648448 10225184 3454464
21:50:17 53023 8192000000 10307714160 10749038592 1366671888 14.297 98 46 88 1 1385764624 9339120 9753616
21:52:11 39707 8192000000 10291510736 10755686400 1373831328 14.361 99 15 27 0 1389860800 13146560 2882912
21:53:54 31841 8192000000 10286819728 10760011776 1384165456 14.454 100 7 14 0 1400836448 15139600 1531392
21:55:32 47593 8192000000 10261615472 10707857408 1319559840 13.873 95 14 28 0 1330325888 7341952 3424096
21:57:23 92152 8192000000 10272253696 10737717248 1369912272 14.326 100 10 20 0 1383155104 10834928 2407904
21:59:57 64788 8192000000 10263502720 10723704832 1338068688 14.040 98 9 18 0 1353183984 13064480 2050816
22:02:03 59536 8192000000 10254209120 10705014784 1311960208 13.804 96 9 19 0 1326244208 12259872 2024128
22:04:05 59098 8192000000 10262921184 10713939968 1335802608 14.020 97 22 44 0 1354087104 12948880 5335616
22:06:28 59988 8192000000 10257433344 10711818240 1321134816 13.887 97 21 43 1 1339342128 13429296 4778016
22:08:41 123793 8192000000 10284898240 10734563328 1311059216 13.796 95 6 10 0 1324495024 12480672 955136
22:11:49 23608 8192000000 10294987680 10726621184 1365495200 14.287 99 52 98 3 1386477280 10180400 10801680
22:13:17 19408 8192000000 10289988928 10725871616 1366192128 14.293 99 46 92 0 1386820176 9742784 10885264
22:14:38 83439 8192000000 10273499440 10723078144 1360539056 14.242 99 20 40 0 1375075024 10015440 4520528
22:17:02 82527 8192000000 10277319984 10735251456 1344740320 14.100 97 13 26 0 1356784992 9136320 2908352
22:19:28 33982 8192000000 10282078544 10738597888 1387564624 14.484 100 19 38 0 1398627328 6391808 4670896
22:21:06 52500 8192000000 10286962624 10746970112 1367575504 14.305 98 28 54 1 1383344560 9817568 5951488
22:23:13 70256 8192000000 10286495008 10756788224 1384454896 14.456 100 11 22 1 1398404896 11748720 2201280
22:25:23 32341 8192000000 10303981408 10754899968 1388432064 14.492 99 46 89 0 1410633280 12162112 10039104
22:27:06 50712 8192000000 10290887936 10744172544 1381437680 14.429 99 11 22 0 1400464208 16068848 2957680
22:28:58 68851 8192000000 10278874208 10736672768 1343513184 14.089 96 26 52 0 1364514640 15189376 5812080
22:31:10 29963 8192000000 10306511312 10753056768 1382140672 14.436 99 28 56 1 1400121952 11932176 6049104
22:32:56 39953 8192000000 10307725248 10764877824 1391714048 14.521 100 15 30 0 1407296480 12371136 3211296
22:34:36 87809 8192000000 10278729296 10733211648 1338976192 14.048 96 26 52 1 1357368976 12849488 5543296
22:37:28 22286 8192000000 10283066272 10741710848 1361582768 14.252 99 18 36 0 1382318880 16837600 3898512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-12-2022 22:40:53 to 15-12-2022 22:41:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-12-2022 22:41:10 to 15-12-2022 22:41:12.

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          235326
MON$OLDEST_ACTIVE               235327
MON$OLDEST_SNAPSHOT             235327
MON$NEXT_TRANSACTION            235327
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                       2028896
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 ---
:92: 
:93: mscs-test-161.budzdorov.ru    Thu Dec 15 22:38:49 2022
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:95: 
--- end of diff output ---

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

15.12.22 22:41:13. 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-15 22:41:15 No severe PSQL-related problems occured
15.12.22 22:41:17. End of report.