Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.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 15163460 kB
MemAvailable 30447812 kB
Buffers 0 kB
Cached 16558676 kB
SwapCached 5060 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-09 19:39:33
DTS_END                         2023-02-09 19:38:38.3410
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47744
TRN_ID                          247462
ATT_ID                          274
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: 9513.36 18266 1141603
customer order: creation 184.63 331 22156
customer order: refuse 15.42 356 1850
order to supplier: creation 47.16 1158 5659
order to supplier: removal 3.54 94886 425
invoice (draft): creation 47.90 3241 5748
invoice (draft): removal 51.65 708 6198
invoice accept: apply 44.16 18115 5299
invoice accept: cancel 47.38 814 5686
customer reserve: creation 4095.18 180 491422
customer reserve: removal 4672.15 58 560658
realization accept: apply 88.66 182 10639
realization accept: cancel 79.18 99 9502
payment from customer: creation 61.66 114 7399
payment from customer: removal 6.47 78 776
payment to supplier: creation 51.34 135 6161
payment to supplier: removal 3.61 70 433
service: total inventory turnovers 5.42 926 650
service: total monetary turnovers 6.84 27 821
service: aggregate perf. data 0.98 18466 117
service: refresh index statistics 0.03 225384 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-02-2023 19:41:00 to 09-02-2023 19:41:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5989
TEST_TIME 2 6009
TEST_TIME 3 5314
TEST_TIME 4 5518
TEST_TIME 5 5573
TEST_TIME 6 5812
TEST_TIME 7 6069
TEST_TIME 8 5800
TEST_TIME 9 5795
TEST_TIME 10 5764
TEST_TIME 11 5901
TEST_TIME 12 5969
TEST_TIME 13 6085
TEST_TIME 14 6080
TEST_TIME 15 6349
TEST_TIME 16 6338
TEST_TIME 17 6304
TEST_TIME 18 6836
TEST_TIME 19 6826
TEST_TIME 20 7121
TEST_TIME 21 7250
TEST_TIME 22 7600
TEST_TIME 23 7972
TEST_TIME 24 8315
TEST_TIME 25 8726
TEST_TIME 26 8811
TEST_TIME 27 9404
TEST_TIME 28 9237
TEST_TIME 29 9663
TEST_TIME 30 10288
TEST_TIME 31 10114
TEST_TIME 32 10180
TEST_TIME 33 10264
TEST_TIME 34 10272
TEST_TIME 35 10180
TEST_TIME 36 10058
TEST_TIME 37 10006
TEST_TIME 38 10078
TEST_TIME 39 10027
TEST_TIME 40 10031
TEST_TIME 41 9925
TEST_TIME 42 9727
TEST_TIME 43 9730
TEST_TIME 44 9721
TEST_TIME 45 9663
TEST_TIME 46 9569
TEST_TIME 47 9631
TEST_TIME 48 9592
TEST_TIME 49 9621
TEST_TIME 50 9605
TEST_TIME 51 9481
TEST_TIME 52 9550
TEST_TIME 53 9473
TEST_TIME 54 9434
TEST_TIME 55 9460
TEST_TIME 56 9360
TEST_TIME 57 9310
TEST_TIME 58 9301
TEST_TIME 59 9317
TEST_TIME 60 9293
TEST_TIME 61 9448
TEST_TIME 62 9442
TEST_TIME 63 9519
TEST_TIME 64 9599
TEST_TIME 65 9580
TEST_TIME 66 9581
TEST_TIME 67 9642
TEST_TIME 68 9675
TEST_TIME 69 9615
TEST_TIME 70 9716
TEST_TIME 71 9706
TEST_TIME 72 9749
TEST_TIME 73 9786
TEST_TIME 74 9729
TEST_TIME 75 9651
TEST_TIME 76 9608
TEST_TIME 77 9551
TEST_TIME 78 9526
TEST_TIME 79 9494
TEST_TIME 80 9505
TEST_TIME 81 9479
TEST_TIME 82 9487
TEST_TIME 83 9404
TEST_TIME 84 9439
TEST_TIME 85 9477
TEST_TIME 86 9469
TEST_TIME 87 9453
TEST_TIME 88 9469
TEST_TIME 89 9544
TEST_TIME 90 9580
TEST_TIME 91 9598
TEST_TIME 92 9616
TEST_TIME 93 9621
TEST_TIME 94 9590
TEST_TIME 95 9677
TEST_TIME 96 9644
TEST_TIME 97 9628
TEST_TIME 98 9531
TEST_TIME 99 9574
TEST_TIME 100 9550
TEST_TIME 101 9542
TEST_TIME 102 9497
TEST_TIME 103 9511
TEST_TIME 104 9451
TEST_TIME 105 9435
TEST_TIME 106 9406
TEST_TIME 107 9438
TEST_TIME 108 9413
TEST_TIME 109 9407
TEST_TIME 110 9389
TEST_TIME 111 9441
TEST_TIME 112 9485
TEST_TIME 113 9479
TEST_TIME 114 9507
TEST_TIME 115 9505
TEST_TIME 116 9495
TEST_TIME 117 9526
TEST_TIME 118 9543
TEST_TIME 119 9529
TEST_TIME 120 9516

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-02-2023 19:41:04 to 09-02-2023 19:41:07.

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 1595073 1595073 0 0.00 0 19465 6 0 0
doc_list_biud 1595072 1595072 0 0.00 0 60206 26 0 0
sp_add_invoice_to_stock 5438 5299 139 2.55 27 190830 18116 0 139
sp_cancel_adding_invoice 5686 5686 0 0.00 4 76627 815 0 0
sp_cancel_client_order 1850 1850 0 0.00 4 14944 356 0 0
sp_cancel_customer_reserve 560658 560658 0 0.00 0 60208 58 0 0
sp_cancel_pay_from_customer 776 776 0 0.00 3 7278 78 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 2793 71 0 0
sp_cancel_supplier_invoice 6211 6198 13 0.20 2 24245 708 0 13
sp_cancel_supplier_order 425 425 0 0.00 8 1157291 94887 0 0
sp_cancel_write_off 9502 9502 0 0.00 1 8379 100 0 0
sp_client_order 22156 22156 0 0.00 3 57257 331 0 0
sp_customer_reserve 491422 491422 0 0.00 0 39415 180 0 0
sp_fill_shopping_cart 33561 33561 0 0.00 1 27609 35 0 0
sp_fill_shopping_cart_clo_res 491415 491415 0 0.00 0 15247 13 0 0
sp_get_clo_for_invoice 5291 5291 0 0.00 0 30819 292 0 0
sp_kill_cost_storno 16397 16397 0 0.00 0 16164 53 0 0
sp_kill_qstorno_ret_qs2qd 1137441 1137441 0 0.00 0 60204 31 0 0
sp_kill_qty_storno 600249 600249 0 0.00 0 60206 67 0 0
sp_lock_selected_doc 22070 22070 0 0.00 0 10215 6 0 0
sp_make_cost_storno 29490 29490 0 0.00 0 16200 53 0 0
sp_multiply_rows_for_pdistr 29490 29490 0 0.00 0 8054 29 0 0
sp_multiply_rows_for_qdistr 22156 22156 0 0.00 0 57207 143 0 0
sp_pay_from_customer 7399 7399 0 0.00 4 16347 114 0 0
sp_pay_to_supplier 6161 6161 0 0.00 4 16106 135 0 0
sp_payment_common 13560 13560 0 0.00 1 12382 25 0 0
sp_qd_handle_on_cancel_clo 1850 1850 0 0.00 0 14932 328 0 0
sp_qd_handle_on_invoice_upd_sts 10977 10977 0 0.00 0 26460 304 0 0
sp_qd_handle_on_reserve_upd_sts 10639 10639 0 0.00 0 20125 57 0 0
sp_reserve_write_off 10639 10639 0 0.00 6 20143 182 0 0
sp_supplier_invoice 5748 5748 0 0.00 7 96403 3242 0 0
sp_supplier_order 5659 5659 0 0.00 5 43684 1159 0 0
srv_aggregate_perf_data 117 117 0 0.00 2642 514473 18466 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2991 188660 43596 0 0
srv_find_qd_qs_mism 1090280 1090280 0 0.00 0 19464 6 0 0
srv_make_invnt_saldo 650 650 0 0.00 35 44808 926 0 0
srv_make_money_saldo 821 821 0 0.00 1 2652 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 51290 343328 225384 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5299 5299 0 0.00 0 12002 164 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 3882 89 0 0
t$perf-norm:sp_cancel_client_order 1850 1850 0 0.00 0 1203 9 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 8375 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 776 776 0 0.00 0 6066 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 1446 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1166 16 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 26473 1487 0 0
t$perf-norm:sp_cancel_write_off 1089 1089 0 0.00 0 3877 6 0 0
t$perf-norm:sp_client_order 22156 22156 0 0.00 0 13688 18 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 440 5 0 0
t$perf-norm:sp_pay_from_customer 7399 7399 0 0.00 0 9229 11 0 0
t$perf-norm:sp_pay_to_supplier 6161 6161 0 0.00 0 16180 18 0 0
t$perf-norm:sp_reserve_write_off 10639 10639 0 0.00 0 18188 14 0 0
t$perf-norm:sp_supplier_invoice 5748 5748 0 0.00 0 4919 15 0 0
t$perf-norm:sp_supplier_order 5659 5659 0 0.00 0 8500 17 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 46 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 62 1 0 0
t$perf-norm:srv_make_invnt_saldo 650 650 0 0.00 0 2019 22 0 0
t$perf-norm:srv_make_money_saldo 821 821 0 0.00 0 7630 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10856 10578 278 2.56 0 12167 23 0 278
v_all_customers 39308 39308 0 0.00 0 51 0 0 0
v_all_suppliers 31328 31328 0 0.00 0 18 1 0 0
v_all_wares 44312 44312 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 2794 11 0 0
v_cancel_client_order 3700 3700 0 0.00 0 2798 8 0 0
v_cancel_customer_prepayment 1552 1552 0 0.00 0 3561 12 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 3584 7 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 1712 7 0 0
v_cancel_supplier_invoice 848 822 26 3.06 0 2937 23 0 26
v_cancel_supplier_order 850 850 0 0.00 0 1266 5 0 0
v_cancel_write_off 2178 2178 0 0.00 0 2777 18 0 0
v_max_id_clo_ord 5659 5659 0 0.00 0 581 1 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 1279 8 0 0
v_max_id_ord_sup 5746 5746 0 0.00 0 2326 3 0 0
v_max_non_paid_invoice 1902 1902 0 0.00 0 1610 3 0 0
v_max_non_paid_realizn 4152 4152 0 0.00 0 4748 5 0 0
v_min_id_clo_ord 5659 5659 0 0.00 0 2427 4 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 3622 6 0 0
v_min_id_ord_sup 5746 5746 0 0.00 0 5878 12 0 0
v_min_non_paid_invoice 6161 6161 0 0.00 0 12375 19 0 0
v_min_non_paid_realizn 7399 7399 0 0.00 0 6792 16 0 0
v_our_firm 11498 11498 0 0.00 0 14 1 0 0
v_reserve_write_off 21278 21278 0 0.00 0 7015 8 0 0
x_lock_depdocs_on_canc_invoice 11473 11473 0 0.00 0 15588 105 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 3247 264 0 0
x_make_qstorno_1000_1200 5659 5659 0 0.00 1 43658 1095 0 0
x_make_qstorno_1200_2000 5748 5748 0 0.00 3 96189 3102 0 0
x_make_qstorno_2100_3300 491422 491422 0 0.00 0 39414 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-02-2023 19:41:08 to 09-02-2023 19:41:12.

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:42 2 8192000000 8799977168 8811544576 6373696 0.077 2 1 2 0 6473328 56848 42784
17:09:42 26499 8192000000 9896405376 10207723520 979290496 10.677 100 89 179 0 1008596976 5757632 23548848
17:11:13 17127 8192000000 9946676752 10268446720 1025503376 11.125 100 88 174 1 1054785568 7490672 21791520
17:12:34 14218 8192000000 9978325824 10310045696 1063093088 11.486 100 84 168 0 1092901664 8574848 21233728
17:13:51 10453 8192000000 10016393872 10360414208 1108449216 11.918 100 82 162 0 1138090976 9489040 20152720
17:15:07 7334 8192000000 10068438144 10425106432 1156858880 12.374 100 73 145 0 1184773296 10909616 17004800
17:16:18 3818 8192000000 10118159088 10483859456 1208407312 12.854 100 79 156 1 1238546624 11874080 18265232
17:17:24 3165 8192000000 10147210896 10515873792 1234101872 13.092 100 89 174 0 1267066384 14392352 18572160
17:18:29 1157 8192000000 10165214960 10543276032 1255776320 13.291 100 83 163 1 1286185488 13093584 17315584
17:19:33 1498 8192000000 10190206960 10572050432 1277025680 13.486 100 88 176 1 1309599968 13590496 18983792
17:20:36 6483 8192000000 10209913840 10597183488 1293018784 13.632 100 82 157 0 1323463136 12378208 18066144
17:21:44 5499 8192000000 10196912944 10600443904 1302871840 13.721 100 40 77 1 1319674768 7641344 9161584
17:22:52 11382 8192000000 10198372000 10607726592 1307582432 13.764 100 19 35 1 1317136592 5882064 3672096
17:24:07 67395 8192000000 10203539616 10626777088 1319369488 13.871 100 27 54 0 1331195216 5337904 6487824
17:26:15 4753 8192000000 10216280720 10634158080 1326422576 13.935 100 27 50 1 1337884320 5477440 5984304
17:27:21 4949 8192000000 10220314400 10639523840 1328094288 13.950 100 26 52 0 1339404080 5225488 6084304
17:28:28 6828 8192000000 10203463376 10623590400 1315412096 13.835 100 27 54 1 1327181040 5702928 6066016
17:29:42 11179 8192000000 10210306448 10613153792 1304514944 13.736 99 59 119 2 1324042752 5107264 14420544
17:31:02 5664 8192000000 10151258016 10551160832 1244762800 13.190 99 38 75 0 1260514816 7568000 8184016
17:32:12 6518 8192000000 10127365120 10521591808 1198160336 12.759 96 29 56 0 1210575344 5212880 7202128
17:33:23 9320 8192000000 10150936176 10560253952 1262800192 13.356 100 28 51 2 1275398064 5685808 6912064
17:34:33 38665 8192000000 10142349600 10548088832 1217695744 12.940 96 30 60 0 1229969568 5340480 6933344
17:36:18 6734 8192000000 10184369984 10589364224 1289073184 13.596 100 30 58 0 1301160480 5820736 6266560
17:37:27 10450 8192000000 10193694640 10609692672 1304387392 13.735 100 15 30 0 1313063184 5248064 3427728
17:38:38 7533 8192000000 10233974096 10642870272 1334002768 14.003 100 32 63 0 1346274080 5159712 7111600
17:39:48 61420 8192000000 10260585136 10676137984 1351656368 14.162 100 51 102 0 1371879008 8797440 11425200
17:41:55 9452 8192000000 10279027040 10699350016 1370465056 14.331 100 43 86 0 1390843184 10916912 9461216
17:43:11 16136 8192000000 10274154864 10698145792 1371491824 14.340 100 50 100 1 1394155472 11816192 10847456
17:44:33 14272 8192000000 10297397216 10719887360 1382468640 14.439 100 52 102 2 1408278064 14434512 11374912
17:46:14 25287 8192000000 10321140736 10743996416 1397299008 14.571 100 46 90 1 1425090160 17232912 10558240
17:47:51 7456 8192000000 10319705392 10746318848 1391603216 14.520 99 53 106 4 1424284256 20013728 12667312
17:49:11 10578 8192000000 10349138160 10773524480 1422641776 14.796 100 58 116 0 1461149552 24564016 13943760
17:50:29 3677 8192000000 10357131488 10776317952 1419206384 14.766 100 67 132 1 1468103792 32190976 16706432
17:51:41 3582 8192000000 10383234048 10799636480 1436600592 14.920 100 66 132 0 1488150048 35439984 16109472
17:52:49 8196 8192000000 10382747088 10802184192 1432198528 14.881 100 73 146 1 1491170352 41178304 17793520
17:54:01 8444 8192000000 10363404128 10787057664 1418518640 14.760 99 63 126 0 1473256192 39045248 15692304
17:55:14 2991 8192000000 10367888480 10785107968 1401869872 14.612 99 72 144 0 1464898368 44750752 18277744
17:56:25 5368 8192000000 10366805200 10782384128 1410336880 14.687 100 71 142 0 1471326672 42834816 18154976
17:57:45 7174 8192000000 10339516848 10760876032 1388341568 14.491 100 53 103 0 1446601888 44449968 13810352
17:58:58 23213 8192000000 10329928080 10749992960 1370302688 14.330 99 44 88 1 1430949136 47998896 12647552
18:00:26 10285 8192000000 10343495360 10768633856 1385626480 14.467 100 44 88 0 1449744528 52309072 11808976
18:01:39 32059 8192000000 10327889072 10761199616 1384265456 14.455 100 30 58 1 1437139728 44834112 8040160
18:03:19 21403 8192000000 10334800736 10765008896 1366784304 14.298 99 41 80 1 1429455232 51678272 10992656
18:04:49 34179 8192000000 10314412304 10752917504 1352958032 14.174 98 26 52 0 1409076144 49144464 6973648
18:06:26 38372 8192000000 10302124448 10732974080 1345119120 14.104 99 28 54 1 1394954416 41557792 8277504
18:08:10 20937 8192000000 10277793776 10727489536 1366505360 14.296 100 14 25 0 1391086592 21774592 2806640
18:09:35 29950 8192000000 10299341168 10732392448 1374911632 14.371 99 39 77 1 1393205584 10093840 8200112
18:11:20 129507 8192000000 10297502944 10748375040 1385147872 14.463 100 6 12 0 1401241824 14894624 1199328
18:14:29 24890 8192000000 10314416288 10746073088 1379480832 14.412 99 53 106 0 1400993648 8974400 12538416
18:15:57 83207 8192000000 10297543424 10749448192 1378319552 14.402 99 26 49 0 1397134880 13010688 5804640
18:18:21 67987 8192000000 10296445760 10742145024 1371380160 14.339 99 14 28 1 1385064448 10571488 3112800
18:21:04 43862 8192000000 10311592944 10754023424 1379952352 14.416 99 43 86 0 1398819728 9496928 9370448
18:22:52 38199 8192000000 10295385152 10751053824 1391742576 14.521 100 18 34 0 1406624448 11621072 3260800
18:24:32 20094 8192000000 10318229376 10766503936 1402493840 14.617 100 39 78 1 1422239312 10403904 9341568
18:25:53 38810 8192000000 10307353984 10777899008 1405808384 14.647 100 26 52 0 1426537200 14840448 5888368
18:27:41 37573 8192000000 10309735056 10778320896 1402256432 14.615 100 12 24 0 1418029120 13102032 2670656
18:29:21 46011 8192000000 10302268096 10768519168 1401578352 14.609 100 8 17 0 1413686240 9828096 2279792
18:31:08 59138 8192000000 10316552192 10770186240 1404456352 14.635 100 15 28 1 1417391072 8929664 4005056
18:33:12 32910 8192000000 10293418496 10768883712 1397824656 14.576 100 19 39 0 1411798208 9818512 4155040
18:34:50 77231 8192000000 10290367520 10762395648 1381217056 14.427 99 30 60 2 1403052112 14543952 7291104
18:38:10 33032 8192000000 10277725520 10740047872 1350018320 14.148 97 20 40 0 1363399664 8810944 4570400
18:39:48 35622 8192000000 10265969552 10720276480 1307682000 13.765 95 17 32 1 1327281968 15966320 3633648
18:41:28 66674 8192000000 10282250592 10732122112 1347761152 14.127 98 18 36 0 1362956288 11470640 3724496
18:43:36 21762 8192000000 10278336640 10706628608 1339575328 14.054 99 56 110 1 1361132304 9237776 12319200
18:45:03 18730 8192000000 10269606880 10713239552 1333030432 13.995 98 43 86 0 1355270816 12791248 9449136
18:46:32 56306 8192000000 10266349376 10702004224 1303366688 13.726 97 23 46 0 1317882064 9640096 4875280
18:48:29 58380 8192000000 10260460736 10699059200 1333191616 13.996 99 17 34 0 1345995488 9322096 3481776
18:50:28 35320 8192000000 10277763600 10727841792 1345049360 14.103 99 21 41 1 1370619744 21095696 4474688
18:52:12 37261 8192000000 10273182944 10711429120 1346711440 14.118 99 50 101 2 1367106480 8913632 11481408
18:54:33 65305 8192000000 10259991120 10703712256 1304805744 13.739 96 36 70 0 1328614928 16077328 7731856
18:56:43 53993 8192000000 10256124688 10714222592 1348841296 14.137 99 16 33 1 1363095440 10670496 3583648
18:59:28 50533 8192000000 10258443360 10713755648 1343602688 14.090 99 18 36 0 1360551920 12628720 4320512
19:01:38 53830 8192000000 10266342048 10728677376 1349732560 14.145 99 12 24 1 1367866896 15192304 2942032
19:04:12 37093 8192000000 10277343072 10728271872 1345534288 14.107 98 22 44 0 1359322576 9045408 4742880
19:05:49 68957 8192000000 10304715232 10753171456 1380066496 14.417 100 15 30 0 1396803808 13152848 3584464
19:07:58 124462 8192000000 10281738704 10738540544 1295712048 13.656 93 9 18 0 1315198160 17732464 1753648
19:11:03 103210 8192000000 10304252480 10748424192 1355283920 14.195 97 31 62 0 1369685184 7719408 6681856
19:13:46 188660 8192000000 10301804384 10760294400 1304174272 13.733 93 14 28 1 1327689520 20375888 3139360
19:18:03 22820 8192000000 10300137968 10752892928 1377773776 14.397 99 27 54 0 1394407216 11035824 5597616
19:20:07 74041 8192000000 10284412336 10742562816 1339988496 14.057 96 10 18 0 1350032704 8000576 2043632
19:22:21 28350 8192000000 10289376976 10745233408 1367127872 14.301 98 13 26 0 1377914240 7844128 2942240
19:23:50 72882 8192000000 10288772640 10749341696 1363571216 14.269 98 15 30 0 1377977696 10936512 3469968
19:26:04 64133 8192000000 10283823904 10755960832 1380375680 14.420 100 10 19 1 1394036640 11097360 2563600
19:28:10 52133 8192000000 10286408688 10748747776 1349111344 14.139 97 17 34 0 1371062656 17606720 4344592
19:30:09 73295 8192000000 10262622768 10713022464 1311688928 13.801 96 8 16 0 1327370416 13676336 2005152
19:32:23 48228 8192000000 10260315616 10698006528 1323527856 13.909 97 26 51 0 1340889472 11472880 5888736
19:34:21 62196 8192000000 10255057088 10697879552 1280078432 13.514 95 33 66 0 1301701696 14950176 6673088
19:36:27 81513 8192000000 10278948720 10714714112 1332963632 13.994 99 17 34 0 1349832096 12848480 4019984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-02-2023 19:41:14 to 09-02-2023 19:41:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-02-2023 19:41:30 to 09-02-2023 19:41:32.

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          252165
MON$OLDEST_ACTIVE               252166
MON$OLDEST_SNAPSHOT             252166
MON$NEXT_TRANSACTION            252166
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                       2051488
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.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Thu Feb  9 18:40:51 2023
:13:     INET/inet_error: read errno = 104
:14: 
--- end of diff output ---

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

09.02.23 19:41:34. 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-09 19:41:36 No severe PSQL-related problems occured
09.02.23 19:41:37. End of report.