Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.02.2023 19: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 15419132 kB
MemAvailable 30420316 kB
Buffers 0 kB
Cached 16318744 kB
SwapCached 3560 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 2023-02-06 19:38:41
DTS_END                         2023-02-06 19:38:41.2640
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/59330
TRN_ID                          239473
ATT_ID                          652
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: 9141.14 19443 1096937
customer order: creation 170.89 341 20507
customer order: refuse 14.53 389 1744
order to supplier: creation 46.53 1190 5584
order to supplier: removal 3.46 106731 415
invoice (draft): creation 46.43 3314 5571
invoice (draft): removal 51.86 879 6223
invoice accept: apply 41.56 18050 4987
invoice accept: cancel 46.38 808 5566
customer reserve: creation 3857.60 179 462912
customer reserve: removal 4571.48 63 548577
realization accept: apply 83.11 154 9973
realization accept: cancel 77.64 116 9317
payment from customer: creation 59.65 87 7158
payment from customer: removal 5.57 93 668
payment to supplier: creation 48.43 126 5811
payment to supplier: removal 3.44 67 413
service: total inventory turnovers 5.01 1005 601
service: total monetary turnovers 6.75 31 810
service: aggregate perf. data 0.80 20429 96
service: refresh index statistics 0.03 234798 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-02-2023 19:40:27 to 06-02-2023 19:40:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6319
TEST_TIME 2 5786
TEST_TIME 3 5501
TEST_TIME 4 6145
TEST_TIME 5 5518
TEST_TIME 6 5365
TEST_TIME 7 5274
TEST_TIME 8 5576
TEST_TIME 9 5442
TEST_TIME 10 5664
TEST_TIME 11 5930
TEST_TIME 12 6102
TEST_TIME 13 6074
TEST_TIME 14 6134
TEST_TIME 15 6281
TEST_TIME 16 6623
TEST_TIME 17 6942
TEST_TIME 18 7396
TEST_TIME 19 7450
TEST_TIME 20 7736
TEST_TIME 21 7801
TEST_TIME 22 8214
TEST_TIME 23 8317
TEST_TIME 24 9188
TEST_TIME 25 9223
TEST_TIME 26 9285
TEST_TIME 27 9337
TEST_TIME 28 10032
TEST_TIME 29 11199
TEST_TIME 30 10918
TEST_TIME 31 10807
TEST_TIME 32 10931
TEST_TIME 33 10896
TEST_TIME 34 10682
TEST_TIME 35 10380
TEST_TIME 36 10372
TEST_TIME 37 10405
TEST_TIME 38 10324
TEST_TIME 39 10320
TEST_TIME 40 10181
TEST_TIME 41 10091
TEST_TIME 42 9993
TEST_TIME 43 9985
TEST_TIME 44 9962
TEST_TIME 45 9910
TEST_TIME 46 9789
TEST_TIME 47 9635
TEST_TIME 48 9716
TEST_TIME 49 9600
TEST_TIME 50 9535
TEST_TIME 51 9480
TEST_TIME 52 9537
TEST_TIME 53 9705
TEST_TIME 54 9667
TEST_TIME 55 9683
TEST_TIME 56 9678
TEST_TIME 57 9740
TEST_TIME 58 9781
TEST_TIME 59 9737
TEST_TIME 60 9777
TEST_TIME 61 9787
TEST_TIME 62 9784
TEST_TIME 63 9791
TEST_TIME 64 9811
TEST_TIME 65 9853
TEST_TIME 66 9888
TEST_TIME 67 9760
TEST_TIME 68 9711
TEST_TIME 69 9756
TEST_TIME 70 9651
TEST_TIME 71 9581
TEST_TIME 72 9624
TEST_TIME 73 9612
TEST_TIME 74 9545
TEST_TIME 75 9538
TEST_TIME 76 9491
TEST_TIME 77 9466
TEST_TIME 78 9361
TEST_TIME 79 9376
TEST_TIME 80 9373
TEST_TIME 81 9337
TEST_TIME 82 9382
TEST_TIME 83 9331
TEST_TIME 84 9306
TEST_TIME 85 9377
TEST_TIME 86 9361
TEST_TIME 87 9368
TEST_TIME 88 9446
TEST_TIME 89 9479
TEST_TIME 90 9439
TEST_TIME 91 9520
TEST_TIME 92 9536
TEST_TIME 93 9453
TEST_TIME 94 9442
TEST_TIME 95 9399
TEST_TIME 96 9373
TEST_TIME 97 9335
TEST_TIME 98 9291
TEST_TIME 99 9236
TEST_TIME 100 9229
TEST_TIME 101 9184
TEST_TIME 102 9160
TEST_TIME 103 9134
TEST_TIME 104 9140
TEST_TIME 105 9123
TEST_TIME 106 9155
TEST_TIME 107 9290
TEST_TIME 108 9275
TEST_TIME 109 9299
TEST_TIME 110 9305
TEST_TIME 111 9318
TEST_TIME 112 9303
TEST_TIME 113 9332
TEST_TIME 114 9350
TEST_TIME 115 9298
TEST_TIME 116 9268
TEST_TIME 117 9241
TEST_TIME 118 9250
TEST_TIME 119 9210
TEST_TIME 120 9153

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-02-2023 19:40:30 to 06-02-2023 19:40:34.

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 1524971 1524971 0 0.00 0 14373 6 0 0
doc_list_biud 1524969 1524969 0 0.00 0 43560 29 0 0
sp_add_invoice_to_stock 5087 4987 100 1.96 26 155657 18050 0 100
sp_cancel_adding_invoice 5566 5566 0 0.00 4 87624 809 0 0
sp_cancel_client_order 1744 1744 0 0.00 4 11955 390 0 0
sp_cancel_customer_reserve 548577 548577 0 0.00 0 43561 63 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 2 6351 94 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 2 3572 68 0 0
sp_cancel_supplier_invoice 6231 6223 8 0.12 2 16340 880 0 8
sp_cancel_supplier_order 415 415 0 0.00 7 1135401 106731 0 0
sp_cancel_write_off 9317 9317 0 0.00 1 11273 116 0 0
sp_client_order 20507 20507 0 0.00 3 32335 341 0 0
sp_customer_reserve 462912 462912 0 0.00 0 78663 179 0 0
sp_fill_shopping_cart 31657 31657 0 0.00 1 22494 37 0 0
sp_fill_shopping_cart_clo_res 462896 462896 0 0.00 0 20868 14 0 0
sp_get_clo_for_invoice 4959 4959 0 0.00 0 25709 346 0 0
sp_kill_cost_storno 15964 15964 0 0.00 0 7910 56 0 0
sp_kill_qstorno_ret_qs2qd 1113109 1113109 0 0.00 0 43557 35 0 0
sp_kill_qty_storno 586774 586774 0 0.00 0 43559 74 0 0
sp_lock_selected_doc 20631 20631 0 0.00 0 10997 5 0 0
sp_make_cost_storno 27901 27901 0 0.00 0 14366 47 0 0
sp_multiply_rows_for_pdistr 27901 27901 0 0.00 0 10855 27 0 0
sp_multiply_rows_for_qdistr 20507 20507 0 0.00 0 32327 161 0 0
sp_pay_from_customer 7158 7158 0 0.00 4 10864 88 0 0
sp_pay_to_supplier 5811 5811 0 0.00 4 13528 126 0 0
sp_payment_common 12968 12968 0 0.00 1 8760 23 0 0
sp_qd_handle_on_cancel_clo 1744 1744 0 0.00 0 11939 357 0 0
sp_qd_handle_on_invoice_upd_sts 10525 10525 0 0.00 0 18315 318 0 0
sp_qd_handle_on_reserve_upd_sts 9973 9973 0 0.00 0 12448 48 0 0
sp_reserve_write_off 9973 9973 0 0.00 6 13978 154 0 0
sp_supplier_invoice 5571 5571 0 0.00 6 77958 3314 0 0
sp_supplier_order 5584 5584 0 0.00 5 32585 1191 0 0
srv_aggregate_perf_data 96 96 0 0.00 3097 445006 20429 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2673 131542 42496 0 0
srv_find_qd_qs_mism 1048854 1048854 0 0.00 0 12199 6 0 0
srv_make_invnt_saldo 601 601 0 0.00 19 40468 1006 0 0
srv_make_money_saldo 810 810 0 0.00 0 4188 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 104022 487712 234799 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4988 4988 0 0.00 0 8124 151 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 7433 176 0 0
t$perf-norm:sp_cancel_client_order 1744 1744 0 0.00 0 2554 12 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 7051 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 6548 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 1647 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 531 7 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 18158 1722 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 1037 4 0 0
t$perf-norm:sp_client_order 20508 20508 0 0.00 0 7099 18 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 6630 21 0 0
t$perf-norm:sp_pay_from_customer 7158 7158 0 0.00 0 13598 12 0 0
t$perf-norm:sp_pay_to_supplier 5811 5811 0 0.00 0 7030 11 0 0
t$perf-norm:sp_reserve_write_off 9973 9973 0 0.00 0 5952 13 0 0
t$perf-norm:sp_supplier_invoice 5571 5571 0 0.00 0 7112 21 0 0
t$perf-norm:sp_supplier_order 5585 5585 0 0.00 0 4482 13 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 367 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 500 11 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 3503 22 0 0
t$perf-norm:srv_make_money_saldo 810 810 0 0.00 0 3604 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10118 9918 200 1.97 0 8596 23 0 200
v_all_customers 37680 37680 0 0.00 0 27 1 0 0
v_all_suppliers 30880 30880 0 0.00 0 48 1 0 0
v_all_wares 41014 41014 0 0.00 0 84 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 612 7 0 0
v_cancel_client_order 3488 3488 0 0.00 0 6220 5 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 6343 19 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 7333 13 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 3566 9 0 0
v_cancel_supplier_invoice 770 754 16 2.07 0 3838 16 0 16
v_cancel_supplier_order 830 830 0 0.00 0 1273 8 0 0
v_cancel_write_off 1938 1938 0 0.00 0 7355 20 0 0
v_max_id_clo_ord 5583 5583 0 0.00 0 3043 2 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 2351 14 0 0
v_max_id_ord_sup 5566 5566 0 0.00 0 4620 4 0 0
v_max_non_paid_invoice 1519 1519 0 0.00 0 360 3 0 0
v_max_non_paid_realizn 3736 3736 0 0.00 0 783 3 0 0
v_min_id_clo_ord 5583 5583 0 0.00 0 2931 6 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 2672 14 0 0
v_min_id_ord_sup 5566 5566 0 0.00 0 3467 5 0 0
v_min_non_paid_invoice 5810 5810 0 0.00 0 8757 18 0 0
v_min_non_paid_realizn 7158 7158 0 0.00 0 5944 16 0 0
v_our_firm 10178 10178 0 0.00 0 20 1 0 0
v_reserve_write_off 19946 19946 0 0.00 0 8597 7 0 0
x_lock_depdocs_on_canc_invoice 11412 11412 0 0.00 0 13821 119 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 21633 365 0 0
x_make_qstorno_1000_1200 5584 5584 0 0.00 1 32553 1113 0 0
x_make_qstorno_1200_2000 5571 5571 0 0.00 2 77858 3180 0 0
x_make_qstorno_2100_3300 462912 462912 0 0.00 0 78658 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-02-2023 19:40:35 to 06-02-2023 19:40:39.

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
17:08:45 2 8192000000 8798570496 8809840640 4638240 0.056 2 1 2 0 4737840 56816 42784
17:09:46 29412 8192000000 9907275520 10220720128 988527312 10.767 100 88 176 0 1018204592 6352880 23324400
17:11:31 19613 8192000000 9962150208 10287788032 1036927984 11.235 100 89 178 1 1067796896 8434768 22434144
17:12:57 9818 8192000000 9984591696 10317651968 1070158912 11.554 100 86 170 0 1101150384 10187296 20804176
17:14:11 17782 8192000000 10029491088 10376388608 1117613424 12.004 100 75 150 1 1145696336 10216480 17866432
17:15:33 5467 8192000000 10081809600 10440994816 1168349424 12.481 100 84 168 1 1200352288 12527344 19475520
17:16:43 2969 8192000000 10124041600 10495545344 1219821952 12.960 100 77 154 0 1249104544 12113056 17169536
17:17:48 2219 8192000000 10166310096 10541080576 1247635648 13.216 100 84 167 2 1278507792 13124336 17747808
17:18:51 1741 8192000000 10188110608 10569170944 1269632560 13.418 100 89 177 1 1303367024 13975664 19758800
17:19:55 944 8192000000 10220353312 10607665152 1308177152 13.770 100 94 188 2 1342404368 13100912 21126304
17:20:57 6087 8192000000 10194050000 10609377280 1309423856 13.781 100 25 50 0 1322137840 7372768 5341216
17:22:04 11581 8192000000 10206130976 10626383872 1320057200 13.877 100 11 22 0 1327628032 5257152 2313680
17:23:22 35921 8192000000 10219480864 10641281024 1329995856 13.967 100 37 74 0 1344462768 5635392 8831520
17:25:00 7744 8192000000 10226166688 10651045888 1335085648 14.013 100 22 45 0 1349915856 9963456 4866752
17:26:09 15199 8192000000 10223182704 10651160576 1337404848 14.034 100 18 36 0 1347296352 5834208 4057296
17:27:29 53474 8192000000 10224324272 10646720512 1309470720 13.781 98 27 54 0 1325962480 10582864 5908896
17:29:24 2702 8192000000 10202626240 10617065472 1293306416 13.634 98 38 78 0 1308922480 6487216 9128848
17:30:29 4943 8192000000 10164716176 10569654272 1268419072 13.407 100 33 66 0 1283085056 6699824 7966160
17:31:41 7379 8192000000 10130483328 10532544512 1210926080 12.878 96 23 44 3 1220886848 4710688 5250080
17:32:50 25344 8192000000 10111042160 10493370368 1160766816 12.410 92 42 83 0 1175120672 5707072 8646784
17:34:19 5431 8192000000 10163482112 10571988992 1273590576 13.454 100 24 48 0 1285049024 5900048 5558400
17:35:25 4910 8192000000 10186537664 10594836480 1294391488 13.644 100 30 60 2 1307561072 6357632 6811952
17:36:32 11136 8192000000 10191895664 10609180672 1300769776 13.702 100 19 37 1 1313797872 9596688 3431408
17:37:44 21871 8192000000 10191572256 10607816704 1304865568 13.739 100 28 56 0 1317022512 5494032 6662912
17:39:10 9515 8192000000 10228935168 10638483456 1330491632 13.972 100 55 108 2 1349676864 7387568 11797664
17:40:23 30040 8192000000 10250668272 10672353280 1349662000 14.144 100 38 76 1 1368088848 9397904 9028944
17:42:15 5237 8192000000 10288168992 10704297984 1372505728 14.349 100 75 147 2 1399137968 8627440 18004800
17:43:21 19036 8192000000 10291417136 10712723456 1378787136 14.406 100 49 98 1 1403995968 14312480 10896352
17:45:01 12895 8192000000 10312994240 10736345088 1397885152 14.576 100 48 93 0 1427285072 17562880 11837040
17:46:18 12133 8192000000 10323193648 10741403648 1372550560 14.350 98 58 114 0 1410702720 23758352 14393808
17:47:42 20436 8192000000 10324656176 10760638464 1409422304 14.679 100 46 91 1 1447562896 26223936 11916656
17:49:06 4670 8192000000 10362372144 10787586048 1430213824 14.863 100 75 148 1 1476548944 28495232 17839888
17:50:14 12001 8192000000 10347760816 10780921856 1420710416 14.779 100 43 86 0 1462073280 30431936 10930928
17:51:37 10106 8192000000 10350520224 10775285760 1411898816 14.701 100 57 111 0 1457507360 31925248 13683296
17:53:23 3308 8192000000 10372343872 10795524096 1420273712 14.775 100 60 118 0 1476580368 41432720 14873936
17:54:34 2673 8192000000 10366888976 10789195776 1412735456 14.708 100 70 139 2 1473462000 42965632 17760912
17:55:46 4298 8192000000 10356309168 10769362944 1395031200 14.551 100 73 146 1 1461106928 46891344 19184384
17:56:56 3117 8192000000 10355855712 10774519808 1400427232 14.599 100 71 139 1 1465342928 47094736 17820960
17:58:05 4548 8192000000 10355214496 10766159872 1391388528 14.518 100 67 133 0 1457803504 48845920 17569056
17:59:15 12758 8192000000 10325458960 10751905792 1358725072 14.226 98 34 68 1 1421821184 52763632 10332480
18:00:33 11750 8192000000 10339875600 10770243584 1360306432 14.240 98 42 84 0 1433106928 60492800 12307696
18:01:52 18645 8192000000 10347215648 10785837056 1380255408 14.419 99 30 60 0 1453996944 64860272 8881264
18:03:23 14273 8192000000 10350272320 10775130112 1391090032 14.516 100 48 92 1 1453048480 49689072 12269376
18:04:45 35214 8192000000 10324673504 10761957376 1360933728 14.246 99 26 52 0 1427738400 58498720 8305952
18:06:38 35699 8192000000 10266534896 10718990336 1335774736 14.019 99 10 20 0 1391583744 52646736 3162272
18:08:14 34723 8192000000 10253837584 10671370240 1324680496 13.919 99 42 81 0 1341598096 7114864 9802736
18:09:51 32893 8192000000 10251768096 10690531328 1339041216 14.049 99 19 38 0 1352963104 9688784 4233104
18:11:36 96043 8192000000 10283003824 10726809600 1346361120 14.115 98 7 14 0 1360547056 12763856 1422080
18:15:01 4739 8192000000 10291339264 10733334528 1376899840 14.389 100 39 77 1 1393977424 9273600 7803984
18:16:09 49439 8192000000 10291552896 10744279040 1384001504 14.452 100 40 75 0 1401337936 8319472 9016960
18:19:05 68060 8192000000 10287068752 10736406528 1374678880 14.369 99 13 26 0 1387565952 9916432 2970640
18:21:28 32144 8192000000 10279365456 10740441088 1378686800 14.405 100 21 40 0 1397004784 13101968 5216016
18:23:05 17039 8192000000 10289481584 10744164352 1387653872 14.485 100 20 38 0 1398097920 6279136 4164912
18:24:22 96775 8192000000 10280916128 10741506048 1372999776 14.354 99 16 32 0 1389045536 12019552 4026208
18:27:02 39960 8192000000 10304954064 10759733248 1383827936 14.451 99 23 47 0 1407509904 18253232 5428736
18:28:45 9277 8192000000 10309856192 10765856768 1381380896 14.429 99 14 26 0 1403730944 19102352 3247696
18:29:56 102841 8192000000 10302745104 10781581312 1391266224 14.517 100 9 18 0 1421355296 27400784 2688288
18:32:54 29228 8192000000 10310456864 10761039872 1385211872 14.463 99 25 50 0 1403541744 12578080 5751792
18:34:23 66402 8192000000 10299066768 10766921728 1392764832 14.531 100 25 50 0 1414584368 16075632 5743904
18:36:36 31335 8192000000 10306403904 10758811648 1387637392 14.485 99 27 54 0 1404741488 11038544 6065552
18:38:12 30096 8192000000 10310655184 10751160320 1379138816 14.409 99 46 92 3 1402096816 12918816 10039184
18:39:45 42005 8192000000 10308287392 10752675840 1356713744 14.208 97 27 54 0 1371467296 9220688 5532864
18:41:33 57234 8192000000 10286829424 10731773952 1350349728 14.151 98 19 36 1 1369687888 15160400 4177760
18:43:45 71186 8192000000 10283151136 10739507200 1348639184 14.135 98 16 32 0 1363326688 11362144 3325360
18:46:01 51305 8192000000 10269877696 10704179200 1326097424 13.932 96 37 72 0 1344456384 10052912 8306048
18:47:55 50339 8192000000 10249905488 10692636672 1282486128 13.536 94 19 36 0 1297024384 10390688 4147568
18:50:01 54883 8192000000 10259609968 10717368320 1349885120 14.146 99 34 66 2 1367648496 10045056 7718320
18:51:57 77193 8192000000 10273117248 10722504704 1350628336 14.153 98 13 26 1 1360940800 7450000 2862464
18:54:27 38404 8192000000 10258094736 10713313280 1335685296 14.018 97 21 42 0 1349687040 9258416 4743328
18:56:06 58061 8192000000 10245941808 10699497472 1318913552 13.867 97 11 22 0 1331621728 10128432 2579744
18:58:16 82234 8192000000 10257330032 10711953408 1331784736 13.983 98 7 14 0 1341635232 8441600 1408896
19:00:45 43679 8192000000 10271363232 10722799616 1348125616 14.131 99 19 38 0 1361682464 9393744 4163104
19:02:49 69586 8192000000 10264153952 10724925440 1351900448 14.165 99 17 34 0 1367374240 11585216 3888576
19:05:04 51628 8192000000 10274642400 10723516416 1353730576 14.181 99 25 50 0 1375878656 15867760 6280320
19:07:03 65790 8192000000 10263148528 10703278080 1334611712 14.009 97 15 30 1 1350731952 12627056 3493184
19:09:10 47869 8192000000 10299803664 10761543680 1387790512 14.486 100 15 30 0 1404524592 13646464 3087616
19:11:05 131542 8192000000 10285126912 10749255680 1384115360 14.453 100 30 61 1 1399639312 8843728 6680224
19:14:17 69494 8192000000 10268171264 10730037248 1369754080 14.325 99 7 14 0 1379436960 8494896 1187984
19:17:01 55272 8192000000 10280840336 10739654656 1381132144 14.427 100 12 24 0 1390855360 7269376 2453840
19:18:57 79229 8192000000 10290106784 10760495104 1397625616 14.574 100 4 8 0 1407246704 9003360 617728
19:21:16 23583 8192000000 10298886736 10759970816 1400082992 14.596 100 28 56 1 1415465392 8195632 7186768
19:22:49 41216 8192000000 10319042112 10773614592 1389360656 14.500 98 28 54 1 1413903968 18132528 6410784
19:24:38 120981 8192000000 10283756944 10746064896 1364212304 14.275 99 14 28 0 1382060592 14914480 2933808
19:27:39 51770 8192000000 10273432160 10719133696 1358162432 14.221 98 20 41 0 1374462464 11706608 4593424
19:29:37 65523 8192000000 10272054656 10705821696 1291198928 13.615 94 27 54 0 1306608096 9328016 6081152
19:31:46 85284 8192000000 10241182992 10677268480 1196544224 12.744 88 19 38 0 1210493264 9602448 4346592
19:34:32 66473 8192000000 10263512096 10712477696 1351252624 14.159 100 19 37 0 1364658944 9416768 3989552
19:36:41 44506 8192000000 10232583712 10696081408 1333962048 14.003 99 5 10 0 1341496320 6520704 1013568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-02-2023 19:40:41 to 06-02-2023 19:40:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-02-2023 19:40:57 to 06-02-2023 19:40:59.

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          239012
MON$OLDEST_ACTIVE               239970
MON$OLDEST_SNAPSHOT             239970
MON$NEXT_TRANSACTION            239970
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                       2017664
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.33659 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33659 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33659 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 ---
:103: 
:104: mscs-test-161.budzdorov.ru    Mon Feb  6 19:38:49 2023
:105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:106: 
:107: 
:108: mscs-test-161.budzdorov.ru    Mon Feb  6 19:38:49 2023
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:110: 
:111: 
:112: mscs-test-161.budzdorov.ru    Mon Feb  6 19:38:49 2023
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:114: 
:115: 
:116: mscs-test-161.budzdorov.ru    Mon Feb  6 19:38:49 2023
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:118: 
--- end of diff output ---

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

06.02.23 19:41:00. 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
2023-02-06 19:41:02 No severe PSQL-related problems occured
06.02.23 19:41:04. End of report.