Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.02.2023 07:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15261436 kB
MemAvailable 30455212 kB
Buffers 0 kB
Cached 16470776 kB
SwapCached 3140 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-20 07:37:26
DTS_END                         2023-02-20 07:37:26.4600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56814
TRN_ID                          214204
ATT_ID                          648
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: 8537.53 20385 1024503
customer order: creation 161.61 480 19393
customer order: refuse 13.57 450 1628
order to supplier: creation 42.55 1357 5106
order to supplier: removal 3.08 158453 370
invoice (draft): creation 44.17 3505 5300
invoice (draft): removal 46.33 925 5560
invoice accept: apply 40.14 27749 4817
invoice accept: cancel 42.03 1234 5043
customer reserve: creation 3731.33 265 447760
customer reserve: removal 4142.49 93 497099
realization accept: apply 78.87 285 9464
realization accept: cancel 68.33 170 8200
payment from customer: creation 56.44 200 6773
payment from customer: removal 5.23 147 627
payment to supplier: creation 46.17 188 5540
payment to supplier: removal 3.15 117 378
service: total inventory turnovers 4.03 3911 483
service: total monetary turnovers 7.10 356 852
service: aggregate perf. data 0.88 14811 105
service: refresh index statistics 0.04 193003 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-02-2023 07:39:51 to 20-02-2023 07:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8675
TEST_TIME 2 6933
TEST_TIME 3 6233
TEST_TIME 4 6259
TEST_TIME 5 5890
TEST_TIME 6 5807
TEST_TIME 7 6402
TEST_TIME 8 6145
TEST_TIME 9 6185
TEST_TIME 10 6348
TEST_TIME 11 6564
TEST_TIME 12 6801
TEST_TIME 13 6828
TEST_TIME 14 6852
TEST_TIME 15 6991
TEST_TIME 16 7200
TEST_TIME 17 7419
TEST_TIME 18 7720
TEST_TIME 19 8131
TEST_TIME 20 8260
TEST_TIME 21 8490
TEST_TIME 22 8577
TEST_TIME 23 8492
TEST_TIME 24 8502
TEST_TIME 25 8821
TEST_TIME 26 9046
TEST_TIME 27 9327
TEST_TIME 28 9393
TEST_TIME 29 9686
TEST_TIME 30 10012
TEST_TIME 31 10270
TEST_TIME 32 10694
TEST_TIME 33 10952
TEST_TIME 34 10799
TEST_TIME 35 10519
TEST_TIME 36 10455
TEST_TIME 37 10279
TEST_TIME 38 10488
TEST_TIME 39 10313
TEST_TIME 40 10233
TEST_TIME 41 10049
TEST_TIME 42 10041
TEST_TIME 43 9922
TEST_TIME 44 9795
TEST_TIME 45 9760
TEST_TIME 46 9708
TEST_TIME 47 9530
TEST_TIME 48 9431
TEST_TIME 49 9476
TEST_TIME 50 9382
TEST_TIME 51 9288
TEST_TIME 52 9272
TEST_TIME 53 9192
TEST_TIME 54 9102
TEST_TIME 55 9031
TEST_TIME 56 9033
TEST_TIME 57 8973
TEST_TIME 58 8922
TEST_TIME 59 8966
TEST_TIME 60 8935
TEST_TIME 61 8890
TEST_TIME 62 8965
TEST_TIME 63 8936
TEST_TIME 64 9078
TEST_TIME 65 9062
TEST_TIME 66 9131
TEST_TIME 67 9105
TEST_TIME 68 9076
TEST_TIME 69 9055
TEST_TIME 70 9129
TEST_TIME 71 9106
TEST_TIME 72 9089
TEST_TIME 73 9027
TEST_TIME 74 8973
TEST_TIME 75 8968
TEST_TIME 76 8961
TEST_TIME 77 8888
TEST_TIME 78 8809
TEST_TIME 79 8738
TEST_TIME 80 8725
TEST_TIME 81 8652
TEST_TIME 82 8742
TEST_TIME 83 8728
TEST_TIME 84 8706
TEST_TIME 85 8727
TEST_TIME 86 8757
TEST_TIME 87 8745
TEST_TIME 88 8779
TEST_TIME 89 8767
TEST_TIME 90 8770
TEST_TIME 91 8739
TEST_TIME 92 8768
TEST_TIME 93 8836
TEST_TIME 94 8812
TEST_TIME 95 8750
TEST_TIME 96 8715
TEST_TIME 97 8685
TEST_TIME 98 8649
TEST_TIME 99 8661
TEST_TIME 100 8616
TEST_TIME 101 8581
TEST_TIME 102 8600
TEST_TIME 103 8562
TEST_TIME 104 8617
TEST_TIME 105 8608
TEST_TIME 106 8575
TEST_TIME 107 8607
TEST_TIME 108 8676
TEST_TIME 109 8666
TEST_TIME 110 8713
TEST_TIME 111 8739
TEST_TIME 112 8823
TEST_TIME 113 8815
TEST_TIME 114 8741
TEST_TIME 115 8714
TEST_TIME 116 8680
TEST_TIME 117 8697
TEST_TIME 118 8655
TEST_TIME 119 8621
TEST_TIME 120 8566

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-02-2023 07:39:55 to 20-02-2023 07:39:58.

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 1436279 1436279 0 0.00 0 17736 13 0 0
doc_list_biud 1436269 1436269 0 0.00 0 51378 38 0 0
sp_add_invoice_to_stock 4983 4817 166 3.33 24 253180 27750 0 166
sp_cancel_adding_invoice 5043 5043 0 0.00 4 123013 1235 0 0
sp_cancel_client_order 1628 1628 0 0.00 6 19680 450 0 0
sp_cancel_customer_reserve 497099 497099 0 0.00 1 51388 94 0 0
sp_cancel_pay_from_customer 627 627 0 0.00 3 6510 148 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 4 1325 118 0 0
sp_cancel_supplier_invoice 5568 5560 8 0.14 3 47565 926 0 8
sp_cancel_supplier_order 370 370 0 0.00 12 1420041 158453 0 0
sp_cancel_write_off 8200 8200 0 0.00 2 8301 171 0 0
sp_client_order 19393 19393 0 0.00 5 29975 481 0 0
sp_customer_reserve 447760 447760 0 0.00 0 68664 265 0 0
sp_fill_shopping_cart 29791 29791 0 0.00 1 26243 96 0 0
sp_fill_shopping_cart_clo_res 447723 447723 0 0.00 0 18209 20 0 0
sp_get_clo_for_invoice 4766 4766 0 0.00 0 36065 348 0 0
sp_kill_cost_storno 14248 14248 0 0.00 0 16658 76 0 0
sp_kill_qstorno_ret_qs2qd 1008328 1008328 0 0.00 0 51346 41 0 0
sp_kill_qty_storno 532129 532129 0 0.00 0 51375 95 0 0
sp_lock_selected_doc 19537 19537 0 0.00 0 11183 8 0 0
sp_make_cost_storno 26543 26543 0 0.00 0 16539 77 0 0
sp_multiply_rows_for_pdistr 26542 26542 0 0.00 0 12740 41 0 0
sp_multiply_rows_for_qdistr 19392 19392 0 0.00 0 14285 178 0 0
sp_pay_from_customer 6773 6773 0 0.00 5 13356 201 0 0
sp_pay_to_supplier 5540 5540 0 0.00 6 14014 189 0 0
sp_payment_common 12313 12313 0 0.00 2 7729 48 0 0
sp_qd_handle_on_cancel_clo 1628 1628 0 0.00 0 19658 361 0 0
sp_qd_handle_on_invoice_upd_sts 9808 9808 0 0.00 0 30219 513 0 0
sp_qd_handle_on_reserve_upd_sts 9464 9464 0 0.00 0 9026 66 0 0
sp_reserve_write_off 9464 9464 0 0.00 8 14240 285 0 0
sp_supplier_invoice 5300 5300 0 0.00 11 160152 3506 0 0
sp_supplier_order 5106 5106 0 0.00 6 68625 1358 0 0
srv_aggregate_perf_data 105 105 0 0.00 2387 175904 14811 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 611 162933 38079 0 0
srv_find_qd_qs_mism 977558 977558 0 0.00 0 14655 11 0 0
srv_make_invnt_saldo 483 483 0 0.00 53 145453 3912 0 0
srv_make_money_saldo 852 852 0 0.00 1 8480 356 0 0
srv_recalc_idx_stat 5 5 0 0.00 31287 320889 193003 0 0
t$perf-abend:sp_add_invoice_to_stock 166 166 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 4822 4822 0 0.00 1 19849 938 0 0
t$perf-norm:sp_cancel_adding_invoice 361 361 0 0.00 1 8720 506 0 0
t$perf-norm:sp_cancel_client_order 1628 1628 0 0.00 0 2453 30 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 3027 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 627 627 0 0.00 0 4875 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 770 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 854 32 0 0
t$perf-norm:sp_cancel_supplier_order 370 370 0 0.00 0 104573 6640 0 0
t$perf-norm:sp_cancel_write_off 918 918 0 0.00 0 4116 32 0 0
t$perf-norm:sp_client_order 19393 19393 0 0.00 0 7607 39 0 0
t$perf-norm:sp_customer_reserve 902 902 0 0.00 0 2726 18 0 0
t$perf-norm:sp_pay_from_customer 6773 6773 0 0.00 0 1872 17 0 0
t$perf-norm:sp_pay_to_supplier 5540 5540 0 0.00 0 8353 24 0 0
t$perf-norm:sp_reserve_write_off 9465 9465 0 0.00 0 8351 25 0 0
t$perf-norm:sp_supplier_invoice 5300 5300 0 0.00 0 3261 27 0 0
t$perf-norm:sp_supplier_order 5106 5106 0 0.00 0 1236 27 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 602 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 103 7 0 0
t$perf-norm:srv_make_invnt_saldo 483 483 0 0.00 0 2198 18 0 0
t$perf-norm:srv_make_money_saldo 852 852 0 0.00 0 6770 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 189 93 0 0
v_add_invoice_to_stock 9854 9522 332 3.36 0 8772 29 0 332
v_all_customers 35568 35568 0 0.00 0 114 4 0 0
v_all_suppliers 28676 28676 0 0.00 0 78 4 0 0
v_all_wares 38782 38782 0 0.00 0 65 4 0 0
v_cancel_adding_invoice 722 722 0 0.00 0 1708 18 0 0
v_cancel_client_order 3256 3256 0 0.00 0 4224 17 0 0
v_cancel_customer_prepayment 1254 1254 0 0.00 0 6155 25 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 5311 19 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 555 10 0 0
v_cancel_supplier_invoice 762 746 16 2.09 0 2052 31 0 16
v_cancel_supplier_order 740 740 0 0.00 0 1381 16 0 0
v_cancel_write_off 1836 1836 0 0.00 0 6638 31 0 0
v_max_id_clo_ord 5104 5104 0 0.00 0 1478 6 0 0
v_max_id_clo_res 902 902 0 0.00 0 3244 17 0 0
v_max_id_ord_sup 5296 5296 0 0.00 0 5047 8 0 0
v_max_non_paid_invoice 1601 1601 0 0.00 0 382 8 0 0
v_max_non_paid_realizn 3595 3595 0 0.00 0 7684 9 0 0
v_min_id_clo_ord 5104 5104 0 0.00 0 3169 10 0 0
v_min_id_clo_res 902 902 0 0.00 0 3717 19 0 0
v_min_id_ord_sup 5296 5296 0 0.00 0 7322 13 0 0
v_min_non_paid_invoice 5540 5540 0 0.00 0 4870 16 0 0
v_min_non_paid_realizn 6773 6773 0 0.00 0 5844 20 0 0
v_our_firm 9570 9570 0 0.00 0 71 5 0 0
v_reserve_write_off 18928 18928 0 0.00 0 6588 13 0 0
x_lock_depdocs_on_canc_invoice 10230 10230 0 0.00 0 27643 133 0 0
x_lock_depdocs_on_canc_sup_ord 370 370 0 0.00 0 7667 409 0 0
x_make_qstorno_1000_1200 5106 5106 0 0.00 2 68144 1183 0 0
x_make_qstorno_1200_2000 5300 5300 0 0.00 6 160126 3221 0 0
x_make_qstorno_2100_3300 447758 447758 0 0.00 0 51493 233 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-02-2023 07:39:59 to 20-02-2023 07:40:03.

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
05:07:30 3 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:32 31386 8192000000 9727693152 10028208128 803754144 8.934 100 91 182 0 834030736 7837024 22439568
05:10:12 26332 8192000000 9777546752 10089218048 845278736 9.353 100 89 178 0 879532640 13601872 20652032
05:11:45 15204 8192000000 9828657728 10152660992 881919104 9.719 100 83 163 0 927152576 26911040 18322432
05:13:02 6913 8192000000 9888752032 10223255552 930401600 10.199 100 92 185 0 1001391376 50745568 20244208
05:14:17 4125 8192000000 9941392272 10282455040 959559872 10.485 100 90 178 0 1052231216 73997680 18673664
05:15:25 2054 8192000000 9973771232 10325151744 994747952 10.828 100 90 177 1 1088803824 75873184 18182688
05:16:29 1323 8192000000 10004055968 10364887040 1024226656 11.113 100 96 176 3 1120552944 77839344 18486944
05:17:32 1285 8192000000 10026872032 10397843456 1045613360 11.319 100 99 189 1 1149065824 84148464 19304000
05:18:35 1050 8192000000 10040232240 10413178880 1060001760 11.456 100 98 189 0 1161917696 82411424 19504512
05:19:37 938 8192000000 10043003296 10421334016 1068264080 11.536 100 98 193 0 1157123808 70080176 18779552
05:20:40 1122 8192000000 10055802608 10442240000 1075057360 11.600 100 88 166 5 1179528160 88728272 15742528
05:21:42 1017 8192000000 10048829264 10429337600 1079332912 11.641 100 90 172 2 1170058288 72348928 18376448
05:22:44 944 8192000000 10050019760 10437644288 1087269680 11.717 100 91 173 0 1173153344 66961280 18922384
05:23:46 1245 8192000000 10061280176 10448355328 1094441968 11.785 100 90 172 1 1183024976 71648352 16934656
05:24:48 1642 8192000000 10051540672 10442207232 1095874912 11.798 100 79 147 5 1171724160 61401072 14448176
05:25:51 1056 8192000000 10067693744 10452086784 1098261600 11.821 100 98 188 3 1188234032 71365792 18606640
05:26:53 3492 8192000000 10055449680 10445942784 1095584640 11.796 100 82 155 1 1181935664 69933600 16417424
05:27:57 1244 8192000000 10062750272 10452516864 1097448656 11.813 100 93 178 2 1186946528 70746288 18751584
05:28:59 1241 8192000000 10069200288 10455531520 1084513456 11.690 99 94 182 2 1194818592 91382976 18922160
05:30:02 1038 8192000000 10045275504 10432040960 1077017296 11.619 99 95 185 0 1173493616 76889312 19587008
05:31:04 2744 8192000000 10028715728 10415759360 1060789536 11.464 100 69 131 2 1145127424 70601024 13736864
05:32:07 1323 8192000000 10001264112 10380193792 1043147968 11.295 100 79 156 0 1127428208 67912752 16367488
05:33:11 1140 8192000000 10003905760 10381701120 1035616608 11.223 100 88 163 3 1129655728 77306880 16732240
05:34:13 1062 8192000000 9974243152 10341507072 1019291296 11.065 99 93 176 0 1093963664 55858624 18813744
05:35:15 1180 8192000000 9983530992 10359435264 1038379792 11.249 100 93 178 3 1104149184 47824752 17944640
05:36:17 1019 8192000000 9985858368 10356838400 1037351344 11.239 100 96 183 1 1107201040 49189184 20660512
05:37:20 1447 8192000000 9999120160 10380386304 1054121456 11.400 100 76 138 1 1121060816 52878112 14061248
05:38:27 21410 8192000000 10020109136 10416566272 1067708208 11.530 100 70 138 1 1144287696 62118528 14460960
05:40:00 3816 8192000000 10037075568 10421575680 1089245744 11.735 100 90 171 0 1155611472 48914224 17451504
05:41:06 11823 8192000000 10086939728 10479890432 1100025024 11.838 100 66 122 0 1208756576 95240256 13491296
05:42:21 5916 8192000000 10114011440 10509217792 1110583456 11.938 100 81 159 1 1235423520 107790336 17049728
05:43:41 4162 8192000000 10151843968 10541744128 1121493584 12.041 100 87 166 3 1267646752 128791520 17361648
05:44:50 7137 8192000000 10152795280 10558533632 1135762544 12.176 100 71 138 0 1269138720 117517696 15858480
05:46:01 3884 8192000000 10185475760 10594332672 1144540016 12.258 100 64 122 0 1302050560 144126624 13383920
05:47:07 984 8192000000 10225952720 10622083072 1153289680 12.340 100 99 190 0 1345479440 170410128 21779632
05:48:13 982 8192000000 10272419168 10670891008 1156493696 12.370 100 93 187 1 1383602464 206060096 21048672
05:49:18 2759 8192000000 10253754736 10670280704 1163752480 12.438 100 70 136 1 1369421760 190632672 15036608
05:50:25 1067 8192000000 10334005184 10738495488 1174872576 12.542 100 94 188 0 1444272048 248398688 21000784
05:51:30 1884 8192000000 10355245216 10765860864 1188519840 12.670 100 89 174 4 1466634832 258260944 19854048
05:52:35 980 8192000000 10372088688 10792861696 1186618480 12.652 100 95 178 1 1480554192 273381056 20554656
05:53:38 1057 8192000000 10399767856 10813927424 1188257840 12.667 100 94 181 5 1509488432 300277568 20953024
05:54:42 938 8192000000 10386855296 10826227712 1178082864 12.572 99 88 173 0 1494561056 297070544 19407648
05:55:45 1348 8192000000 10367570496 10816958464 1173441936 12.529 100 96 190 0 1478294256 281579968 23272352
05:56:50 791 8192000000 10368471552 10809888768 1167074000 12.469 100 92 180 1 1476406304 288096320 21235984
05:57:53 1706 8192000000 10381471072 10815352832 1178021904 12.572 100 90 173 1 1480057200 282948736 19086560
05:58:58 2945 8192000000 10423658816 10841407488 1180620704 12.596 100 79 156 0 1526078624 326890992 18566928
06:00:02 1632 8192000000 10436215760 10848239616 1167706912 12.475 100 68 131 1 1539259856 356145072 15407872
06:01:11 1410 8192000000 10417555376 10865782784 1157551200 12.380 99 75 142 2 1522736512 346805040 18380272
06:02:14 599 8192000000 10481023504 10931073024 1177350080 12.565 100 91 182 0 1586613200 387184064 22079056
06:03:16 11224 8192000000 10452635728 10901147648 1181162320 12.601 100 41 82 0 1568544688 375570192 11812176
06:04:32 19602 8192000000 10407407904 10911760384 1166366912 12.463 100 39 78 0 1553447584 376828528 10252144
06:05:55 15656 8192000000 10394659344 10866851840 1145407264 12.266 100 32 64 0 1495811760 341883328 8521168
06:07:22 43764 8192000000 10309244400 10773315584 1140173776 12.217 99 20 40 0 1420575840 275143088 5258976
06:09:18 44882 8192000000 10079470096 10582405120 1122496256 12.051 98 11 19 0 1297974576 172897104 2581216
06:11:12 114948 8192000000 10170752320 10614906880 1122153440 12.047 97 45 90 0 1209631984 77501104 9977440
06:14:30 58625 8192000000 10126512256 10602307584 1132989280 12.150 99 22 42 1 1267551936 129450144 5112512
06:17:54 70917 8192000000 10133825200 10582519808 1114969088 11.979 97 31 60 0 1177685088 56705360 6010640
06:20:14 65635 8192000000 10099375056 10556760064 1128479424 12.107 99 23 47 0 1202481552 68316976 5685152
06:22:22 61854 8192000000 10067676448 10521886720 1107498768 11.909 98 9 18 0 1165221696 55733200 1989728
06:24:41 30673 8192000000 10073454000 10512809984 1099860272 11.836 98 47 92 2 1162427536 53172016 9395248
06:26:12 8744 8192000000 10091986544 10532241408 1118439584 12.012 100 36 67 0 1194261216 68345824 7475808
06:27:22 11735 8192000000 10031942256 10524327936 1106120048 11.896 99 21 42 0 1142280080 31403424 4756608
06:28:35 44894 8192000000 10074959584 10542772224 1116394416 11.993 99 26 53 0 1188066736 65920752 5751568
06:30:22 83506 8192000000 10100731520 10588991488 1135380640 12.172 100 14 29 0 1211810288 73151488 3278160
06:32:46 75086 8192000000 10090409696 10573053952 1127314368 12.096 99 18 35 0 1156452576 25514912 3623296
06:35:04 78628 8192000000 10069311088 10621431808 1132743456 12.147 100 6 12 0 1198498160 64516976 1237728
06:37:22 32617 8192000000 10101575680 10546614272 1140978736 12.225 99 20 35 1 1181778560 37159712 3640112
06:38:59 49062 8192000000 10125160592 10598137856 1118354496 12.011 97 25 50 0 1250177616 126145152 5677968
06:40:54 42835 8192000000 10085978368 10592047104 1121689248 12.043 99 31 62 0 1213637488 85764272 6183968
06:42:41 25651 8192000000 10123424128 10579804160 1136017376 12.178 100 46 93 0 1204356848 59289920 9049552
06:44:09 59310 8192000000 10086184672 10513301504 1129588944 12.117 99 50 94 2 1179670400 40352640 9728816
06:46:09 8976 8192000000 10124222016 10549665792 1146978848 12.281 100 62 116 1 1230444720 72296800 11169072
06:47:22 32158 8192000000 10107543952 10575478784 1138798912 12.204 99 38 74 1 1216268432 70688272 6781248
06:48:55 29359 8192000000 10154980336 10592821248 1133764224 12.157 99 48 96 0 1225442080 82274448 9403408
06:50:26 102700 8192000000 10113038800 10549272576 1125931088 12.083 99 48 93 0 1205750112 70628928 9190096
06:53:09 51882 8192000000 10052088768 10512392192 1128276944 12.105 99 24 40 0 1170388656 37787712 4324000
06:55:39 77972 8192000000 10098139440 10553847808 1070681088 11.559 94 15 30 0 1148655728 74308672 3665968
06:57:57 61382 8192000000 10074503760 10530619392 1102311184 11.860 99 10 20 0 1194986896 90465392 2210320
07:00:00 55829 8192000000 10068283616 10525884416 1090958352 11.752 97 24 46 0 1141565472 45761056 4846064
07:01:56 21762 8192000000 10097026752 10530680832 1099561328 11.833 98 34 66 0 1170504720 64272624 6670768
07:03:19 38147 8192000000 10066492272 10505801728 1117184480 12.000 100 18 36 0 1167478576 46434816 3859280
07:04:59 95636 8192000000 10085161328 10542088192 1102700576 11.863 98 23 46 0 1147979632 40429168 4849888
07:07:50 63410 8192000000 10110071520 10533576704 1081404672 11.661 95 35 68 1 1157218480 69064208 6749600
07:09:56 44302 8192000000 10073022192 10541944832 1111372240 11.945 99 34 68 0 1215340896 97022128 6946528
07:11:42 75121 8192000000 10084927760 10544091136 1104223504 11.878 98 37 74 0 1174161168 62526736 7410928
07:14:08 74638 8192000000 10074958496 10542268416 1118252672 12.010 100 17 34 0 1185487312 63300848 3933792
07:16:26 61279 8192000000 10059253536 10526203904 1113875008 11.969 99 13 23 0 1152280928 36044112 2361808
07:18:27 37811 8192000000 10099603072 10529865728 1102307760 11.860 97 41 81 1 1159306240 48263440 8735040
07:20:15 124534 8192000000 10081865232 10543484928 1086881568 11.713 96 18 36 0 1153008656 62078784 4048304
07:23:23 34244 8192000000 10107123712 10546053120 1104379696 11.879 99 36 72 0 1190510400 78155424 7975280
07:25:09 58828 8192000000 10106917488 10556444672 1113350768 11.964 99 14 28 0 1171593008 55462720 2779520
07:27:11 64212 8192000000 10094216976 10592210944 1091697808 11.759 97 20 40 0 1162264448 66585264 3981376
07:29:16 162933 8192000000 10042880400 10499002368 1088809232 11.731 97 7 14 0 1132913472 43093952 1010288
07:33:16 48999 8192000000 10072807584 10529099776 1126868576 12.092 100 30 57 1 1192988928 60876688 5243664
07:35:17 62244 8192000000 10060776016 10513981440 1126813488 12.091 100 6 12 0 1166831376 38859488 1158400
07:37:20 9716 8192000000 9275622256 9584459776 416878448 4.842 37 18 34 0 447654480 26254976 4521056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 20-02-2023 07:40:05 to 20-02-2023 07:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 166 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 20-02-2023 07:40:23 to 20-02-2023 07:40:25.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          214668
MON$OLDEST_ACTIVE               215552
MON$OLDEST_SNAPSHOT             215552
MON$NEXT_TRANSACTION            215552
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-30 07:30:08.6030 GMT
MON$PAGES                       2046496
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
--- end of diff output ---

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

20.02.23 07:40:26. There are 3 logs in /var/tmp/logs-oltp40 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-20 07:40:28 No severe PSQL-related problems occured
20.02.23 07:40:30. End of report.