Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.12.2022 04: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 14682580 kB
MemAvailable 30090096 kB
Buffers 20 kB
Cached 16979140 kB
SwapCached 3544 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 2022-12-28 04:37:20
DTS_END                         2022-12-28 04:37:20.3380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44436
TRN_ID                          222768
ATT_ID                          647
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: 8658.36 19504 1039003
customer order: creation 178.08 488 21369
customer order: refuse 15.15 389 1818
order to supplier: creation 46.35 1409 5562
order to supplier: removal 3.38 154092 405
invoice (draft): creation 46.82 3274 5618
invoice (draft): removal 47.47 1027 5696
invoice accept: apply 41.61 24347 4993
invoice accept: cancel 43.20 1280 5184
customer reserve: creation 3698.50 240 443820
customer reserve: removal 4242.43 97 509092
realization accept: apply 84.19 268 10103
realization accept: cancel 78.68 154 9441
payment from customer: creation 60.71 176 7285
payment from customer: removal 5.75 160 690
payment to supplier: creation 49.78 201 5973
payment to supplier: removal 3.47 103 416
service: total inventory turnovers 4.16 3479 499
service: total monetary turnovers 7.68 417 922
service: aggregate perf. data 0.93 13627 112
service: refresh index statistics 0.04 184854 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-12-2022 04:40:13 to 28-12-2022 04:40:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8837
TEST_TIME 2 7674
TEST_TIME 3 7933
TEST_TIME 4 6998
TEST_TIME 5 7271
TEST_TIME 6 6943
TEST_TIME 7 6621
TEST_TIME 8 6564
TEST_TIME 9 6383
TEST_TIME 10 6259
TEST_TIME 11 6377
TEST_TIME 12 6722
TEST_TIME 13 6831
TEST_TIME 14 7372
TEST_TIME 15 7370
TEST_TIME 16 7638
TEST_TIME 17 7958
TEST_TIME 18 8181
TEST_TIME 19 8532
TEST_TIME 20 8729
TEST_TIME 21 8724
TEST_TIME 22 8714
TEST_TIME 23 8673
TEST_TIME 24 8800
TEST_TIME 25 8842
TEST_TIME 26 8765
TEST_TIME 27 9148
TEST_TIME 28 9281
TEST_TIME 29 9789
TEST_TIME 30 9902
TEST_TIME 31 10010
TEST_TIME 32 10294
TEST_TIME 33 10557
TEST_TIME 34 10726
TEST_TIME 35 11030
TEST_TIME 36 10838
TEST_TIME 37 10706
TEST_TIME 38 10537
TEST_TIME 39 10496
TEST_TIME 40 10344
TEST_TIME 41 10176
TEST_TIME 42 10183
TEST_TIME 43 10062
TEST_TIME 44 9928
TEST_TIME 45 9877
TEST_TIME 46 9737
TEST_TIME 47 9614
TEST_TIME 48 9624
TEST_TIME 49 9633
TEST_TIME 50 9622
TEST_TIME 51 9534
TEST_TIME 52 9465
TEST_TIME 53 9380
TEST_TIME 54 9319
TEST_TIME 55 9249
TEST_TIME 56 9230
TEST_TIME 57 9249
TEST_TIME 58 9174
TEST_TIME 59 9066
TEST_TIME 60 9019
TEST_TIME 61 8923
TEST_TIME 62 9051
TEST_TIME 63 9010
TEST_TIME 64 8977
TEST_TIME 65 9005
TEST_TIME 66 9056
TEST_TIME 67 9023
TEST_TIME 68 9012
TEST_TIME 69 9002
TEST_TIME 70 9031
TEST_TIME 71 8984
TEST_TIME 72 9077
TEST_TIME 73 9044
TEST_TIME 74 9056
TEST_TIME 75 9087
TEST_TIME 76 9117
TEST_TIME 77 9091
TEST_TIME 78 9094
TEST_TIME 79 9003
TEST_TIME 80 8940
TEST_TIME 81 8922
TEST_TIME 82 8925
TEST_TIME 83 8872
TEST_TIME 84 8832
TEST_TIME 85 8780
TEST_TIME 86 8765
TEST_TIME 87 8742
TEST_TIME 88 8788
TEST_TIME 89 8765
TEST_TIME 90 8748
TEST_TIME 91 8770
TEST_TIME 92 8791
TEST_TIME 93 8769
TEST_TIME 94 8801
TEST_TIME 95 8825
TEST_TIME 96 8876
TEST_TIME 97 8945
TEST_TIME 98 8895
TEST_TIME 99 8890
TEST_TIME 100 8832
TEST_TIME 101 8795
TEST_TIME 102 8772
TEST_TIME 103 8761
TEST_TIME 104 8701
TEST_TIME 105 8671
TEST_TIME 106 8646
TEST_TIME 107 8622
TEST_TIME 108 8610
TEST_TIME 109 8611
TEST_TIME 110 8635
TEST_TIME 111 8649
TEST_TIME 112 8679
TEST_TIME 113 8665
TEST_TIME 114 8675
TEST_TIME 115 8636
TEST_TIME 116 8692
TEST_TIME 117 8732
TEST_TIME 118 8809
TEST_TIME 119 8759
TEST_TIME 120 8690

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-12-2022 04:40:16 to 28-12-2022 04:40:20.

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 1449233 1449233 0 0.00 0 31712 13 0 0
doc_list_biud 1449230 1449230 0 0.00 0 25106 40 0 0
sp_add_invoice_to_stock 5122 4993 129 2.51 49 236856 24348 0 129
sp_cancel_adding_invoice 5184 5184 0 0.00 7 69973 1281 0 0
sp_cancel_client_order 1818 1818 0 0.00 6 11863 390 0 0
sp_cancel_customer_reserve 509092 509092 0 0.00 1 23930 98 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 3 9284 161 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 4 1133 104 0 0
sp_cancel_supplier_invoice 5709 5696 13 0.22 3 22102 1028 0 13
sp_cancel_supplier_order 405 405 0 0.00 13 1199284 154093 0 0
sp_cancel_write_off 9441 9441 0 0.00 2 7954 154 0 0
sp_client_order 21369 21369 0 0.00 5 47083 489 0 0
sp_customer_reserve 443820 443820 0 0.00 0 47936 240 0 0
sp_fill_shopping_cart 32549 32549 0 0.00 1 14747 81 0 0
sp_fill_shopping_cart_clo_res 443786 443786 0 0.00 0 19412 19 0 0
sp_get_clo_for_invoice 4949 4949 0 0.00 0 27163 310 0 0
sp_kill_cost_storno 15731 15731 0 0.00 0 16526 70 0 0
sp_kill_qstorno_ret_qs2qd 1033726 1033726 0 0.00 0 23898 43 0 0
sp_kill_qty_storno 546688 546688 0 0.00 0 25104 99 0 0
sp_lock_selected_doc 20937 20937 0 0.00 0 8229 7 0 0
sp_make_cost_storno 28310 28310 0 0.00 0 13185 79 0 0
sp_multiply_rows_for_pdistr 28310 28310 0 0.00 0 12784 37 0 0
sp_multiply_rows_for_qdistr 21369 21369 0 0.00 0 18325 199 0 0
sp_pay_from_customer 7285 7285 0 0.00 5 18404 176 0 0
sp_pay_to_supplier 5973 5973 0 0.00 6 18665 201 0 0
sp_payment_common 13257 13257 0 0.00 2 4432 40 0 0
sp_qd_handle_on_cancel_clo 1818 1818 0 0.00 0 11853 327 0 0
sp_qd_handle_on_invoice_upd_sts 10133 10133 0 0.00 0 25103 512 0 0
sp_qd_handle_on_reserve_upd_sts 10103 10103 0 0.00 0 11070 57 0 0
sp_reserve_write_off 10103 10103 0 0.00 8 12814 268 0 0
sp_supplier_invoice 5618 5618 0 0.00 9 119005 3275 0 0
sp_supplier_order 5562 5562 0 0.00 7 42524 1410 0 0
srv_aggregate_perf_data 112 112 0 0.00 2243 151669 13627 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 724 190157 37552 0 0
srv_find_qd_qs_mism 990783 990783 0 0.00 0 31709 9 0 0
srv_make_invnt_saldo 499 499 0 0.00 71 91709 3479 0 0
srv_make_money_saldo 922 922 0 0.00 1 19026 417 0 0
srv_recalc_idx_stat 5 5 0 0.00 68944 357045 184854 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 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 4994 4994 0 0.00 1 10868 872 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 1 10728 541 0 0
t$perf-norm:sp_cancel_client_order 1818 1818 0 0.00 0 3657 28 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 689 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 2645 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 270 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 2204 33 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 91877 6622 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 885 18 0 0
t$perf-norm:sp_client_order 21369 21369 0 0.00 0 10491 35 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 3252 16 0 0
t$perf-norm:sp_pay_from_customer 7285 7285 0 0.00 0 5188 19 0 0
t$perf-norm:sp_pay_to_supplier 5973 5973 0 0.00 0 7018 20 0 0
t$perf-norm:sp_reserve_write_off 10103 10103 0 0.00 0 6211 21 0 0
t$perf-norm:sp_supplier_invoice 5618 5618 0 0.00 0 3696 29 0 0
t$perf-norm:sp_supplier_order 5562 5562 0 0.00 0 4125 31 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 328 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 666 26 0 0
t$perf-norm:srv_make_invnt_saldo 499 499 0 0.00 0 623 10 0 0
t$perf-norm:srv_make_money_saldo 922 922 0 0.00 0 320 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 174 62 0 0
v_add_invoice_to_stock 10156 9898 258 2.54 0 6565 24 0 258
v_all_customers 38968 38968 0 0.00 0 208 4 0 0
v_all_suppliers 31068 31068 0 0.00 0 208 4 0 0
v_all_wares 42738 42738 0 0.00 0 172 4 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 215 13 0 0
v_cancel_client_order 3636 3636 0 0.00 0 2346 9 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 4920 17 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 3902 15 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 465 10 0 0
v_cancel_supplier_invoice 814 788 26 3.19 0 4213 27 0 26
v_cancel_supplier_order 810 810 0 0.00 0 4341 14 0 0
v_cancel_write_off 1978 1978 0 0.00 0 6564 28 0 0
v_max_id_clo_ord 5562 5562 0 0.00 0 500 5 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 5867 24 0 0
v_max_id_ord_sup 5618 5618 0 0.00 0 9141 8 0 0
v_max_non_paid_invoice 1618 1618 0 0.00 0 262 7 0 0
v_max_non_paid_realizn 3801 3801 0 0.00 0 331 7 0 0
v_min_id_clo_ord 5562 5562 0 0.00 0 4243 8 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 6747 20 0 0
v_min_id_ord_sup 5618 5618 0 0.00 0 4004 8 0 0
v_min_non_paid_invoice 5972 5972 0 0.00 0 3658 12 0 0
v_min_non_paid_realizn 7285 7285 0 0.00 0 4428 14 0 0
v_our_firm 10738 10738 0 0.00 0 57 4 0 0
v_reserve_write_off 20206 20206 0 0.00 0 7156 12 0 0
x_lock_depdocs_on_canc_invoice 10486 10486 0 0.00 0 12654 112 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 3265 288 0 0
x_make_qstorno_1000_1200 5562 5562 0 0.00 2 42495 1251 0 0
x_make_qstorno_1200_2000 5618 5618 0 0.00 4 118671 3040 0 0
x_make_qstorno_2100_3300 443819 443819 0 0.00 0 47925 209 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-12-2022 04:40:21 to 28-12-2022 04:40:25.

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
02:07:24 2 8192000000 8796874672 8807370752 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:24 29949 8192000000 9724822976 10025959424 802937264 8.926 100 90 180 0 832460880 7752960 21770656
02:09:58 20718 8192000000 9771754544 10081607680 845113760 9.351 100 89 178 0 879696528 13364544 21218224
02:11:22 18968 8192000000 9815870048 10132934656 870985744 9.610 100 90 180 0 916499888 24397488 21116656
02:13:00 7155 8192000000 9865249024 10201518080 920799792 10.104 100 76 150 0 979234464 41647216 16787456
02:14:11 4839 8192000000 9928150800 10272960512 963403456 10.522 100 82 164 1 1042554544 62166416 16984672
02:15:19 2158 8192000000 9958071312 10314842112 994123152 10.822 100 85 164 1 1073010512 61438176 17449184
02:16:24 1255 8192000000 10008036384 10373644288 1023336096 11.104 100 91 180 1 1130118496 88525792 18256608
02:17:27 1327 8192000000 10028832880 10401193984 1046138656 11.324 100 94 178 1 1151882240 86367712 19375872
02:18:29 711 8192000000 10053633936 10431541248 1062059024 11.476 100 97 194 1 1171079216 89446224 19573968
02:19:31 2007 8192000000 10035301808 10415939584 1067736224 11.530 100 87 164 2 1154096272 70638880 15721168
02:20:34 1333 8192000000 10064850688 10448674816 1074115936 11.591 100 97 183 1 1189211408 95586464 19509008
02:21:36 2598 8192000000 10045302032 10439131136 1078053712 11.629 100 70 133 1 1170509904 78257360 14198832
02:22:40 1937 8192000000 10031502224 10419970048 1083872288 11.684 100 81 159 1 1149592944 49993872 15726784
02:23:42 2746 8192000000 10050105936 10443923456 1082841408 11.675 100 73 140 0 1169528000 73178768 13507824
02:24:46 2200 8192000000 10044573200 10439741440 1092854656 11.770 100 76 144 1 1172425344 65097488 14473200
02:25:50 2203 8192000000 10066802288 10460188672 1094400368 11.784 100 75 143 1 1188773680 79939168 14434144
02:26:53 1187 8192000000 10082079008 10471501824 1098071440 11.819 100 97 184 2 1203563088 85953360 19538288
02:27:56 2262 8192000000 10051575072 10448846848 1097767360 11.816 100 71 140 2 1176011888 63790832 14453696
02:28:59 1131 8192000000 10072115584 10461306880 1095399456 11.794 100 92 182 1 1193128976 78966128 18763392
02:30:02 1112 8192000000 10036723808 10423201792 1087085216 11.715 100 88 164 3 1159199664 54660208 17454240
02:31:05 1265 8192000000 10014820928 10392391680 1058354832 11.441 99 96 182 0 1135294224 57549264 19390128
02:32:07 2026 8192000000 9992829344 10373382144 1048909264 11.350 100 73 135 2 1118836608 55601904 14325440
02:33:10 1509 8192000000 9981885424 10363564032 1042754272 11.291 99 73 135 1 1102045568 46201056 13090240
02:34:12 11755 8192000000 9977860384 10369597440 1014424176 11.018 98 54 106 1 1082936976 58331200 10181600
02:35:24 3080 8192000000 9998251568 10381430784 1044855424 11.311 100 79 134 4 1123149168 63717872 14575872
02:36:28 1167 8192000000 9977775648 10342887424 1023570176 11.106 100 96 184 1 1101757520 59636304 18551040
02:37:30 2697 8192000000 10008544688 10389893120 1035896400 11.225 98 62 113 1 1123741392 76209696 11635296
02:38:41 12001 8192000000 9987744048 10371186688 1062124704 11.477 100 59 116 0 1108702000 34767408 11809888
02:39:59 1953 8192000000 10056290544 10439557120 1084958736 11.695 100 83 160 2 1188887648 87196192 16732720
02:41:04 4168 8192000000 10074971664 10470014976 1104559744 11.881 100 67 127 3 1186276240 68987680 12728816
02:42:13 6967 8192000000 10119062432 10511998976 1118497424 12.013 100 68 133 0 1236433040 103984112 13951504
02:43:22 2354 8192000000 10153631232 10543640576 1123154560 12.057 100 86 167 0 1265738688 125739264 16844864
02:44:42 6388 8192000000 10171239472 10573246464 1133201712 12.152 100 67 131 1 1297225264 149499648 14523904
02:45:58 3500 8192000000 10191245664 10589216768 1142159440 12.236 100 78 149 0 1309215888 150051504 17004944
02:47:04 4101 8192000000 10211378800 10624356352 1148958496 12.300 100 70 133 0 1331527104 167613568 14955040
02:48:14 1791 8192000000 10274238720 10675019776 1161476160 12.417 100 83 157 0 1386231728 207853360 16902208
02:49:18 922 8192000000 10316167776 10720526336 1161728992 12.419 100 97 194 2 1429866368 246043216 22094160
02:50:21 2197 8192000000 10309644688 10724356096 1165585440 12.456 100 84 164 0 1422401760 238561648 18254672
02:51:33 1434 8192000000 10300736736 10731204608 1162684112 12.428 100 97 189 1 1405477200 220979888 21813200
02:52:37 1596 8192000000 10349662704 10750418944 1175114096 12.545 100 97 190 0 1452654000 256320288 21219616
02:53:42 1333 8192000000 10329298608 10770829312 1159207296 12.396 98 88 176 0 1434568336 254841216 20519824
02:54:47 851 8192000000 10333240944 10755489792 1163436000 12.435 100 94 184 1 1439592384 255141184 21015200
02:55:50 915 8192000000 10333648336 10767605760 1163835536 12.439 99 94 182 0 1441839456 257145856 20858064
02:56:53 2249 8192000000 10347440368 10771152896 1172464000 12.520 100 87 168 0 1454139184 262385360 19289824
02:57:58 5667 8192000000 10347783808 10774216704 1151616384 12.325 98 55 107 0 1449705760 285375344 12714032
02:59:17 2412 8192000000 10360228704 10808344576 1180941136 12.599 100 80 150 3 1466148176 265862864 19344176
03:00:23 714 8192000000 10399911744 10811203584 1179395664 12.585 100 89 157 1 1500744784 303306496 18042624
03:01:30 811 8192000000 10398371200 10808696832 1176356928 12.556 99 89 175 4 1507369312 308793392 22218992
03:02:35 1780 8192000000 10436642720 10870763520 1189358928 12.677 100 69 121 2 1537502992 332975008 15169056
03:03:39 5160 8192000000 10487184864 10919530496 1180837824 12.598 100 50 101 1 1594203776 400861552 12504400
03:05:03 9663 8192000000 10460970016 10911559680 1169917520 12.496 99 43 86 0 1549517616 368938560 10661536
03:06:14 9619 8192000000 10394917632 10922536960 1164740832 12.448 100 49 95 0 1499831024 322474464 12615728
03:07:34 21036 8192000000 10319754480 10795692032 1163083872 12.432 100 50 98 0 1440959040 265432208 12442960
03:09:03 13151 8192000000 10374195744 10843992064 1160689872 12.410 100 47 94 1 1478789008 306431984 11667152
03:10:48 20028 8192000000 10252853408 10767069184 1151341216 12.322 100 22 44 0 1395943552 238918048 5684288
03:13:33 56767 8192000000 10113041280 10541748224 1116020144 11.989 98 68 122 1 1199794544 70725216 13049184
03:15:39 52936 8192000000 10095754832 10590867456 1130450576 12.126 100 25 51 0 1230602848 94540896 5611376
03:17:33 103004 8192000000 10078712688 10560454656 1117133312 12.000 98 25 50 0 1178104608 55513072 5458224
03:20:25 53088 8192000000 10089286816 10545381376 1131992720 12.140 99 19 35 0 1167267616 31894480 3380416
03:22:19 68995 8192000000 10091319616 10555535360 1096229952 11.802 96 18 37 0 1185909216 85328576 4350688
03:24:28 47745 8192000000 10107945568 10563145728 1087189712 11.716 96 25 46 1 1179524832 86238672 6096448
03:26:31 190157 8192000000 10061704192 10575687680 1102008448 11.857 98 11 22 0 1155679280 51304144 2366688
03:30:50 14195 8192000000 10073393888 10513846272 1112577488 11.957 99 44 88 1 1166894848 45392784 8924576
03:32:05 54852 8192000000 10124616944 10569916416 1122198944 12.048 99 42 82 0 1192212704 61567520 8446240
03:34:10 74612 8192000000 10067746480 10555600896 1095300096 11.793 97 9 16 1 1150451152 52891328 2259728
03:36:25 37849 8192000000 10101757872 10563334144 1123513504 12.060 100 11 22 0 1195800752 69780992 2506256
03:38:09 88920 8192000000 10108050912 10599264256 1119412496 12.021 99 17 31 0 1236597584 113797792 3387296
03:40:40 131677 8192000000 10065876512 10580807680 1091844272 11.760 96 8 16 0 1149463056 56295488 1323296
03:43:53 61577 8192000000 10078219616 10537263104 1097739104 11.816 97 20 38 0 1137111840 35113744 4258992
03:45:56 56839 8192000000 10093974400 10530934784 1112420688 11.955 98 36 72 0 1175700224 55781328 7498208
03:47:55 58953 8192000000 10139226544 10586271744 1122132928 12.047 98 27 53 0 1198454192 70541952 5779312
03:49:56 30956 8192000000 10093484272 10525388800 1130521536 12.126 99 47 94 0 1181083312 40492448 10069328
03:51:30 51791 8192000000 10110898640 10570506240 1123273376 12.058 98 39 78 0 1194208928 62374880 8560672
03:53:23 77897 8192000000 10113632512 10565193728 1094968688 11.790 96 21 42 0 1156247584 56596592 4682304
03:55:41 80819 8192000000 10086672448 10529554432 1125250304 12.077 99 52 96 1 1173496672 37384944 10861424
03:58:19 28344 8192000000 10132824080 10585882624 1147245104 12.284 100 53 103 0 1226622432 68932736 10444592
03:59:49 42152 8192000000 10050210944 10518315008 1123899392 12.064 98 15 30 0 1154005872 26808240 3298240
04:01:31 58223 8192000000 10051640960 10514833408 1111896208 11.950 98 11 22 0 1139368160 25121824 2350128
04:04:07 45498 8192000000 10072806448 10520006656 1115957072 11.989 98 21 42 0 1180266864 60157792 4152000
04:05:53 38557 8192000000 10093641952 10542882816 1104896016 11.884 97 23 46 0 1162312256 52594032 4822208
04:07:33 49276 8192000000 10094845840 10547929088 1092682544 11.768 96 25 50 0 1173307056 75188960 5435552
04:09:23 43189 8192000000 10130834016 10564706304 1141791296 12.232 100 34 65 1 1226617936 77691216 7135424
04:11:06 47815 8192000000 10138881184 10594619392 1109033232 11.923 97 39 78 0 1232064960 114956256 8075472
04:13:33 53599 8192000000 10079467824 10578100224 1101062496 11.848 97 26 52 1 1215786848 109016368 5707984
04:15:32 15733 8192000000 10081258928 10501554176 1106849600 11.903 98 46 92 0 1148225104 32336368 9039136
04:16:49 2204 8192000000 10107255168 10518568960 1121907504 12.045 100 81 155 1 1200962944 63381184 15674256
04:17:53 82273 8192000000 10074343184 10517569536 1113470400 11.965 99 37 74 1 1192345744 70936576 7938768
04:20:17 56128 8192000000 10062058416 10549833728 1132705600 12.147 100 7 14 0 1178686176 44666560 1314016
04:22:14 55305 8192000000 10074658960 10518204416 1123337024 12.059 99 18 36 1 1160634432 33075536 4221872
04:24:14 67088 8192000000 10118016592 10584686592 1128939360 12.111 99 17 34 1 1207006816 74030880 4036576
04:26:21 32923 8192000000 10131934688 10590871552 1130241360 12.124 99 30 54 0 1232445008 95666752 6536896
04:27:54 55102 8192000000 10107035168 10574483456 1107040208 11.904 97 19 36 0 1187968240 76526032 4402000
04:29:50 52518 8192000000 10092311248 10554023936 1118108160 12.009 99 20 40 0 1167724080 45342384 4273536
04:31:43 75392 8192000000 10141438480 10576855040 1076110656 11.610 95 31 60 1 1145309824 62787264 6411904
04:34:01 82767 8192000000 10097221104 10588790784 1123987008 12.065 99 8 16 0 1237134400 111374464 1772928
04:36:30 103160 8192000000 8985051408 9247596544 143842496 1.725 13 8 16 0 158745056 13069360 1833200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-12-2022 04:40:27 to 28-12-2022 04:40:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 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 28-12-2022 04:40:44 to 28-12-2022 04:40:46.

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          222300
MON$OLDEST_ACTIVE               224025
MON$OLDEST_SNAPSHOT             224025
MON$NEXT_TRANSACTION            224025
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                       2070112
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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'

28.12.22 04:40:47. 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
2022-12-28 04:40:50 No severe PSQL-related problems occured
28.12.22 04:40:51. End of report.