Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.09.2022 07:38
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 15095328 kB
MemAvailable 30609092 kB
Buffers 0 kB
Cached 16631032 kB
SwapCached 2032 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-09-29 07:37:19
DTS_END                         2022-09-29 07:37:19.0610
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44884
TRN_ID                          210895
ATT_ID                          626
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: 8982.73 27357 1077928
customer order: creation 161.19 494 19343
customer order: refuse 13.98 498 1677
order to supplier: creation 41.61 1495 4993
order to supplier: removal 3.34 201953 401
invoice (draft): creation 42.30 3273 5076
invoice (draft): removal 52.21 1125 6265
invoice accept: apply 39.67 28790 4760
invoice accept: cancel 47.48 1351 5697
customer reserve: creation 3725.93 269 447111
customer reserve: removal 4566.48 114 547977
realization accept: apply 76.62 320 9194
realization accept: cancel 89.82 168 10778
payment from customer: creation 54.69 183 6563
payment from customer: removal 5.49 121 659
payment to supplier: creation 46.73 235 5607
payment to supplier: removal 3.11 140 373
service: total inventory turnovers 3.55 4982 426
service: total monetary turnovers 7.67 388 920
service: aggregate perf. data 0.85 21141 102
service: refresh index statistics 0.05 280095 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-09-2022 07:39:04 to 29-09-2022 07:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7596
TEST_TIME 2 5515
TEST_TIME 3 5692
TEST_TIME 4 5536
TEST_TIME 5 5657
TEST_TIME 6 5481
TEST_TIME 7 5320
TEST_TIME 8 5185
TEST_TIME 9 5522
TEST_TIME 10 5645
TEST_TIME 11 6135
TEST_TIME 12 6712
TEST_TIME 13 6923
TEST_TIME 14 7016
TEST_TIME 15 7081
TEST_TIME 16 7169
TEST_TIME 17 7272
TEST_TIME 18 7337
TEST_TIME 19 7455
TEST_TIME 20 7376
TEST_TIME 21 7301
TEST_TIME 22 7233
TEST_TIME 23 7350
TEST_TIME 24 7511
TEST_TIME 25 7444
TEST_TIME 26 7505
TEST_TIME 27 7628
TEST_TIME 28 7693
TEST_TIME 29 7760
TEST_TIME 30 8115
TEST_TIME 31 8126
TEST_TIME 32 8358
TEST_TIME 33 8824
TEST_TIME 34 9230
TEST_TIME 35 9591
TEST_TIME 36 9706
TEST_TIME 37 10115
TEST_TIME 38 10365
TEST_TIME 39 10278
TEST_TIME 40 10081
TEST_TIME 41 10206
TEST_TIME 42 10225
TEST_TIME 43 10258
TEST_TIME 44 10137
TEST_TIME 45 9995
TEST_TIME 46 10032
TEST_TIME 47 9969
TEST_TIME 48 9879
TEST_TIME 49 9859
TEST_TIME 50 9798
TEST_TIME 51 9693
TEST_TIME 52 9799
TEST_TIME 53 9818
TEST_TIME 54 9816
TEST_TIME 55 9873
TEST_TIME 56 9869
TEST_TIME 57 9785
TEST_TIME 58 9659
TEST_TIME 59 9546
TEST_TIME 60 9553
TEST_TIME 61 9426
TEST_TIME 62 9344
TEST_TIME 63 9318
TEST_TIME 64 9241
TEST_TIME 65 9245
TEST_TIME 66 9199
TEST_TIME 67 9174
TEST_TIME 68 9282
TEST_TIME 69 9229
TEST_TIME 70 9220
TEST_TIME 71 9125
TEST_TIME 72 9093
TEST_TIME 73 9162
TEST_TIME 74 9129
TEST_TIME 75 9087
TEST_TIME 76 9071
TEST_TIME 77 9143
TEST_TIME 78 9196
TEST_TIME 79 9161
TEST_TIME 80 9160
TEST_TIME 81 9225
TEST_TIME 82 9191
TEST_TIME 83 9223
TEST_TIME 84 9207
TEST_TIME 85 9250
TEST_TIME 86 9268
TEST_TIME 87 9238
TEST_TIME 88 9158
TEST_TIME 89 9168
TEST_TIME 90 9142
TEST_TIME 91 9125
TEST_TIME 92 9067
TEST_TIME 93 9052
TEST_TIME 94 8981
TEST_TIME 95 8935
TEST_TIME 96 8972
TEST_TIME 97 8985
TEST_TIME 98 9076
TEST_TIME 99 9128
TEST_TIME 100 9090
TEST_TIME 101 9158
TEST_TIME 102 9189
TEST_TIME 103 9150
TEST_TIME 104 9170
TEST_TIME 105 9189
TEST_TIME 106 9192
TEST_TIME 107 9185
TEST_TIME 108 9206
TEST_TIME 109 9228
TEST_TIME 110 9259
TEST_TIME 111 9265
TEST_TIME 112 9226
TEST_TIME 113 9163
TEST_TIME 114 9151
TEST_TIME 115 9145
TEST_TIME 116 9124
TEST_TIME 117 9092
TEST_TIME 118 9069
TEST_TIME 119 9064
TEST_TIME 120 9001

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-09-2022 07:39:07 to 29-09-2022 07:39:11.

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 1489304 1489304 0 0.00 0 28223 15 0 0
doc_list_biud 1489304 1489304 0 0.00 0 22990 48 0 0
sp_add_invoice_to_stock 4875 4760 115 2.35 58 204496 28790 0 115
sp_cancel_adding_invoice 5697 5697 0 0.00 6 97511 1352 0 0
sp_cancel_client_order 1677 1677 0 0.00 5 16762 499 0 0
sp_cancel_customer_reserve 547977 547977 0 0.00 1 22823 115 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 1407 121 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 4 8672 141 0 0
sp_cancel_supplier_invoice 6271 6265 6 0.09 3 23005 1125 0 6
sp_cancel_supplier_order 401 401 0 0.00 21 1610523 201953 0 0
sp_cancel_write_off 10778 10778 0 0.00 1 13048 169 0 0
sp_client_order 19343 19343 0 0.00 6 35801 494 0 0
sp_customer_reserve 447111 447111 0 0.00 0 127488 269 0 0
sp_fill_shopping_cart 29404 29404 0 0.00 1 11164 89 0 0
sp_fill_shopping_cart_clo_res 447096 447096 0 0.00 0 12698 20 0 0
sp_get_clo_for_invoice 4720 4720 0 0.00 0 25215 303 0 0
sp_kill_cost_storno 17507 17507 0 0.00 0 8663 87 0 0
sp_kill_qstorno_ret_qs2qd 1113398 1113398 0 0.00 0 22987 49 0 0
sp_kill_qty_storno 586708 586708 0 0.00 0 22989 113 0 0
sp_lock_selected_doc 19333 19333 0 0.00 0 8970 8 0 0
sp_make_cost_storno 26083 26083 0 0.00 0 28176 101 0 0
sp_multiply_rows_for_pdistr 26083 26083 0 0.00 0 10876 44 0 0
sp_multiply_rows_for_qdistr 19343 19343 0 0.00 0 35704 162 0 0
sp_pay_from_customer 6563 6563 0 0.00 6 26568 184 0 0
sp_pay_to_supplier 5607 5607 0 0.00 7 20225 236 0 0
sp_payment_common 12170 12170 0 0.00 2 12854 52 0 0
sp_qd_handle_on_cancel_clo 1677 1677 0 0.00 0 16746 412 0 0
sp_qd_handle_on_invoice_upd_sts 10416 10416 0 0.00 0 22219 623 0 0
sp_qd_handle_on_reserve_upd_sts 9194 9194 0 0.00 0 19129 74 0 0
sp_reserve_write_off 9194 9194 0 0.00 8 29370 320 0 0
sp_supplier_invoice 5076 5076 0 0.00 12 88752 3274 0 0
sp_supplier_order 4993 4993 0 0.00 8 39756 1496 0 0
srv_aggregate_perf_data 102 102 0 0.00 2390 276815 21142 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 786 120488 31341 0 0
srv_find_qd_qs_mism 1031416 1031416 0 0.00 0 13158 10 0 0
srv_make_invnt_saldo 426 426 0 0.00 81 77998 4983 0 0
srv_make_money_saldo 920 920 0 0.00 1 13234 389 0 0
srv_recalc_idx_stat 6 6 0 0.00 54645 477448 280095 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4762 4762 0 0.00 1 19085 1080 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 1 6280 522 0 0
t$perf-norm:sp_cancel_client_order 1677 1677 0 0.00 0 2550 30 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 2099 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 1513 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 503 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 4838 59 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 95890 8126 0 0
t$perf-norm:sp_cancel_write_off 917 917 0 0.00 0 1115 23 0 0
t$perf-norm:sp_client_order 19343 19343 0 0.00 0 11819 43 0 0
t$perf-norm:sp_customer_reserve 896 896 0 0.00 0 7553 29 0 0
t$perf-norm:sp_pay_from_customer 6563 6563 0 0.00 0 10420 26 0 0
t$perf-norm:sp_pay_to_supplier 5607 5607 0 0.00 0 9613 21 0 0
t$perf-norm:sp_reserve_write_off 9194 9194 0 0.00 0 7696 29 0 0
t$perf-norm:sp_supplier_invoice 5076 5076 0 0.00 0 9086 33 0 0
t$perf-norm:sp_supplier_order 4993 4993 0 0.00 0 8984 39 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 940 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 275 12 0 0
t$perf-norm:srv_make_invnt_saldo 426 426 0 0.00 0 727 22 0 0
t$perf-norm:srv_make_money_saldo 920 920 0 0.00 0 850 7 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 4 180 66 0 0
v_add_invoice_to_stock 9668 9438 230 2.37 0 7424 28 0 230
v_all_customers 35534 35534 0 0.00 0 70 4 0 0
v_all_suppliers 28688 28688 0 0.00 0 125 5 0 0
v_all_wares 38686 38686 0 0.00 0 140 4 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 2206 21 0 0
v_cancel_client_order 3354 3354 0 0.00 0 2533 13 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 699 10 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 502 12 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 973 14 0 0
v_cancel_supplier_invoice 752 740 12 1.59 0 1298 25 0 12
v_cancel_supplier_order 802 802 0 0.00 0 758 14 0 0
v_cancel_write_off 1834 1834 0 0.00 0 763 23 0 0
v_max_id_clo_ord 4993 4993 0 0.00 0 3973 6 0 0
v_max_id_clo_res 896 896 0 0.00 0 2706 14 0 0
v_max_id_ord_sup 5068 5068 0 0.00 0 4093 8 0 0
v_max_non_paid_invoice 1324 1324 0 0.00 0 435 9 0 0
v_max_non_paid_realizn 3265 3265 0 0.00 0 403 8 0 0
v_min_id_clo_ord 4993 4993 0 0.00 0 2025 9 0 0
v_min_id_clo_res 896 896 0 0.00 0 3878 14 0 0
v_min_id_ord_sup 5068 5068 0 0.00 0 7486 11 0 0
v_min_non_paid_invoice 5607 5607 0 0.00 0 9570 25 0 0
v_min_non_paid_realizn 6563 6563 0 0.00 0 12846 19 0 0
v_our_firm 9748 9748 0 0.00 0 58 5 0 0
v_reserve_write_off 18388 18388 0 0.00 0 3510 12 0 0
x_lock_depdocs_on_canc_invoice 11592 11592 0 0.00 0 15147 146 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 28284 469 0 0
x_make_qstorno_1000_1200 4993 4993 0 0.00 3 39674 1308 0 0
x_make_qstorno_1200_2000 5076 5076 0 0.00 6 85982 3041 0 0
x_make_qstorno_2100_3300 447111 447111 0 0.00 0 127477 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-09-2022 07:39:12 to 29-09-2022 07:39:16.

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:22 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:24 20625 8192000000 9717486944 10017038336 792304880 8.818 100 85 170 0 820036608 7391072 20340656
05:09:51 19488 8192000000 9764723984 10073362432 838974464 9.289 100 87 174 0 871403680 11896944 20532272
05:11:17 15429 8192000000 9796711568 10112356352 865931808 9.559 100 81 160 0 901208320 16557920 18718592
05:12:35 9820 8192000000 9839252928 10166743040 901871360 9.917 100 84 165 1 954815728 34604144 18340224
05:13:49 3929 8192000000 9908570384 10252361728 942948304 10.322 100 82 161 0 1020146416 59973120 17224992
05:15:00 1746 8192000000 9962995744 10313179136 988788896 10.770 100 86 163 1 1072726624 67426992 16510736
05:16:05 1978 8192000000 9981737376 10341978112 1010088960 10.976 100 92 176 1 1099930880 71582448 18259472
05:17:10 827 8192000000 10009266080 10377662464 1032644544 11.194 100 96 179 1 1124218752 73684592 17889616
05:18:13 1133 8192000000 10035491312 10405044224 1047435872 11.336 100 96 188 1 1157164736 90893568 18835296
05:19:16 1392 8192000000 10040933776 10416422912 1058829056 11.445 100 94 188 0 1154422592 77106288 18487248
05:20:18 5861 8192000000 10040265136 10412847104 1065523200 11.509 100 90 170 1 1163366800 81514560 16329040
05:21:26 1042 8192000000 10044415552 10422788096 1071432336 11.566 100 96 183 0 1166681488 75662192 19586960
05:22:28 1270 8192000000 10053125936 10435297280 1076499136 11.614 100 96 185 1 1171274800 76627024 18148640
05:23:31 1269 8192000000 10062282400 10447720448 1082955056 11.676 100 93 183 0 1177863760 76514832 18393872
05:24:34 1073 8192000000 10072215760 10457661440 1091320560 11.755 100 88 167 0 1192332688 84829344 16182784
05:25:36 1106 8192000000 10070765136 10456285184 1094079488 11.781 100 98 190 0 1194689936 81177888 19432560
05:26:39 1115 8192000000 10081478096 10468896768 1096897472 11.808 100 93 180 2 1203714256 89182272 17634512
05:27:41 969 8192000000 10049500416 10438602752 1092251584 11.764 100 89 172 1 1169521232 60098576 17171072
05:28:43 1139 8192000000 10037368288 10423398400 1084057696 11.686 100 96 188 1 1157246464 54446016 18742752
05:29:47 1007 8192000000 10026498848 10412425216 1088878576 11.732 100 92 182 1 1151049920 43569200 18602144
05:30:49 894 8192000000 10055186720 10445287424 1084366928 11.689 100 83 156 2 1178672816 77326800 16979088
05:31:51 1166 8192000000 10008512128 10387009536 1063156128 11.487 100 95 179 1 1129613968 47494992 18962848
05:32:54 5999 8192000000 9989767744 10376327168 1052479056 11.384 100 54 102 0 1111880416 49445024 9956336
05:34:02 801 8192000000 9998111888 10368200704 1030758768 11.176 100 94 176 2 1124037984 74200192 19079024
05:35:04 1087 8192000000 9989583952 10367176704 1039149536 11.256 100 80 154 1 1110647808 55674752 15823520
05:36:06 1798 8192000000 10000227168 10373083136 1030105936 11.169 100 91 178 0 1129004304 80830880 18067488
05:37:09 1070 8192000000 9982239728 10355118080 1035614784 11.222 100 82 150 2 1102501872 52482976 14404112
05:38:18 9276 8192000000 10016199856 10400112640 1056644464 11.424 100 68 127 1 1137598928 68126384 12828080
05:39:54 3462 8192000000 10042506032 10430103552 1077694608 11.625 100 74 140 4 1165424048 72858688 14870752
05:41:04 904 8192000000 10084809520 10464874496 1096080640 11.800 100 90 173 0 1199691712 86506096 17104976
05:42:08 10345 8192000000 10123398176 10523176960 1102703120 11.863 100 59 112 0 1239932960 124735888 12493952
05:43:23 9872 8192000000 10108518768 10515193856 1109604208 11.929 100 60 110 0 1232047856 109954368 12489280
05:44:42 5908 8192000000 10141332400 10535653376 1121414128 12.040 100 83 151 2 1257634272 119883424 16336720
05:45:57 10567 8192000000 10176531296 10592792576 1131543600 12.136 100 61 120 0 1285185424 139971328 13670496
05:47:12 972 8192000000 10220557552 10615083008 1147280064 12.284 100 91 168 0 1336299008 171220000 17798944
05:48:17 1371 8192000000 10234620080 10641195008 1147944416 12.290 100 83 164 0 1345588528 179806528 17837584
05:49:23 2310 8192000000 10236495136 10644348928 1161976528 12.422 100 87 164 1 1352839504 173029712 17833264
05:50:28 2375 8192000000 10294597040 10704646144 1176692736 12.559 100 87 174 0 1410721104 214867696 19160672
05:51:34 867 8192000000 10285485616 10691227648 1177232160 12.564 100 94 185 3 1394306096 196675536 20398400
05:52:38 1076 8192000000 10335271136 10742054912 1190094016 12.684 100 96 183 4 1449621264 237754608 21772640
05:53:43 1084 8192000000 10372095456 10799497216 1197940048 12.757 100 87 174 1 1482598288 264581264 20076976
05:54:49 1329 8192000000 10383589712 10808799232 1201927600 12.794 100 91 176 0 1492868736 271183872 19757264
05:55:57 1908 8192000000 10439905504 10866622464 1223132928 12.991 100 92 175 1 1556066768 312479376 20454464
05:57:02 770 8192000000 10437826000 10843226112 1199663712 12.773 99 95 178 1 1541866336 321532608 20670016
05:58:09 1065 8192000000 10518816192 10924929024 1214643504 12.912 100 95 184 2 1623289008 385636256 23009248
05:59:16 965 8192000000 10578343664 10986717184 1215838448 12.923 100 94 186 0 1679184400 440521584 22824368
06:00:20 829 8192000000 10598380096 11038793728 1211405216 12.882 100 95 184 0 1702989568 468680032 22904320
06:01:25 1330 8192000000 10614123840 11034972160 1210376864 12.873 100 91 176 0 1717045024 485325696 21342464
06:02:31 1087 8192000000 10656628288 11072937984 1214117728 12.907 100 96 187 2 1762191696 525747440 22326528
06:03:37 1237 8192000000 10680013664 11113521152 1213957776 12.906 100 95 181 1 1780780608 544653936 22168896
06:04:42 843 8192000000 10695902080 11135938560 1203665344 12.810 100 96 190 0 1797142736 570077904 23399488
06:05:47 1467 8192000000 10695095584 11155324928 1211863440 12.886 100 93 185 0 1796156848 562100048 22193360
06:06:54 2507 8192000000 10715500448 11169411072 1204437680 12.818 100 77 150 0 1815252128 592018640 18795808
06:08:03 5439 8192000000 10679646880 11158102016 1204923152 12.822 100 56 104 2 1794303376 575781776 13598448
06:09:19 10188 8192000000 10646343216 11130458112 1191421648 12.697 100 45 90 0 1747280240 543678416 12180176
06:10:51 11396 8192000000 10483770784 11028488192 1172085936 12.516 100 33 66 0 1620358848 439443936 8828976
06:12:17 21207 8192000000 10318535008 10779508736 1144481744 12.258 98 36 68 1 1432486816 279591328 8413744
06:13:53 31752 8192000000 10221175104 10705113088 1124191744 12.067 97 24 49 0 1346509232 216294688 6022800
06:15:44 15016 8192000000 10170359328 10600751104 1154301424 12.350 100 42 81 1 1269105488 105966304 8837760
06:17:07 39915 8192000000 10214822064 10659344384 1146923136 12.281 100 29 59 0 1323543536 169456928 7163472
06:18:48 76035 8192000000 10124238672 10610585600 1106274096 11.897 97 14 30 0 1209315840 99848912 3192832
06:21:04 9807 8192000000 10077442352 10512064512 1132770032 12.147 100 41 83 0 1172052384 29088192 10194160
06:22:25 27111 8192000000 10123438592 10557825024 1138081904 12.197 99 44 81 0 1214923520 67878720 8962896
06:23:54 83458 8192000000 10117201040 10587648000 1108468240 11.918 97 16 33 0 1197887168 85990896 3428032
06:26:26 99385 8192000000 10110725696 10552090624 1135137232 12.170 99 27 45 0 1193193968 53202496 4854240
06:29:10 96413 8192000000 10091057264 10565648384 1124489696 12.069 98 10 20 0 1194576368 67524544 2562128
06:31:47 73487 8192000000 10100010992 10586476544 1121934416 12.045 98 13 26 0 1186861680 61921744 3005520
06:34:17 29586 8192000000 10062629488 10489069568 1117268656 12.001 98 37 69 1 1146747600 22339728 7139216
06:35:52 107245 8192000000 10102218784 10582982656 1145045488 12.263 100 33 66 1 1190303248 39185616 6072144
06:38:46 62801 8192000000 10116540288 10548170752 1141476016 12.229 98 41 80 1 1195919296 46685376 7757904
06:40:49 22930 8192000000 10155754640 10597916672 1169839408 12.495 100 47 92 0 1260716000 81159040 9717552
06:42:19 73285 8192000000 10163973536 10638340096 1125017536 12.074 97 21 42 0 1237633584 108005232 4610816
06:44:40 78649 8192000000 10075607808 10571743232 1095929264 11.799 96 13 26 0 1183934448 84693808 3311376
06:46:59 71063 8192000000 10118649120 10594926592 1126591728 12.089 98 24 48 0 1186129088 53806752 5730608
06:49:52 59496 8192000000 10070040160 10586427392 1116578752 11.995 98 13 26 0 1187394320 67472864 3342704
06:51:53 14972 8192000000 10064902288 10504241152 1118489472 12.013 98 46 86 2 1146852528 18478576 9884480
06:53:12 55138 8192000000 10133711088 10597732352 1111045504 11.942 97 25 50 0 1193619776 77162048 5412224
06:55:09 50373 8192000000 10063632656 10509385728 1126467280 12.088 99 13 26 0 1162094000 32643936 2982784
06:57:02 10512 8192000000 10126135840 10554773504 1139089984 12.207 100 49 96 2 1215033744 66578896 9364864
06:58:17 28439 8192000000 10092283040 10563121152 1112992704 11.961 98 34 66 0 1182779392 63152528 6634160
06:59:49 33753 8192000000 10070403920 10527485952 1102225920 11.859 98 32 64 0 1163829024 54877024 6726080
07:01:29 24892 8192000000 10099737504 10537398272 1100274848 11.840 97 48 90 0 1167844640 58411072 9158720
07:02:54 46246 8192000000 10112678128 10586689536 1133005408 12.150 100 30 60 0 1201639648 62200384 6433856
07:04:49 120487 8192000000 10144667360 10622017536 1139928656 12.215 100 27 54 0 1223443552 77682432 5832464
07:08:02 42641 8192000000 10065471952 10504560640 1112780016 11.959 97 22 43 0 1162812704 45841168 4191520
07:09:46 75473 8192000000 10204032512 10681458688 1163793568 12.439 100 39 76 1 1317704048 145408128 8502352
07:12:01 115485 8192000000 10236239696 10756927488 1152872784 12.336 100 16 32 0 1384084432 227204544 4007104
07:15:12 19430 8192000000 10137854432 10575228928 1086193600 11.706 96 38 77 0 1208969408 114092608 8683200
07:16:33 24885 8192000000 10067802176 10510147584 1107737392 11.911 98 23 44 0 1149198432 37134624 4326416
07:18:09 53512 8192000000 10106053376 10592067584 1119182224 12.019 99 20 38 0 1211653360 88695808 3775328
07:20:03 40089 8192000000 10111690704 10555744256 1121927360 12.045 99 54 106 2 1164128896 30558912 11642624
07:21:45 18249 8192000000 10119609280 10551361536 1129103296 12.113 99 44 88 0 1211597088 73655824 8837968
07:23:08 18795 8192000000 10129745792 10559242240 1141867600 12.233 100 47 92 1 1206669200 55871136 8930464
07:24:28 6775 8192000000 10123159168 10551111680 1139588368 12.212 100 55 107 0 1205124032 54901424 10634240
07:25:36 63104 8192000000 10095114608 10530299904 1107529648 11.909 97 24 49 0 1159084512 46514000 5040864
07:27:41 95263 8192000000 10114660448 10581790720 1101959904 11.856 96 20 40 1 1171702432 65350144 4392384
07:31:36 16150 8192000000 10077646032 10554372096 1138277104 12.199 100 27 52 0 1184076896 40468496 5331296
07:32:55 53539 8192000000 10064789056 10553638912 1122381264 12.049 100 14 26 1 1172469520 47526272 2561984
07:35:05 44500 8192000000 10053474960 10503811072 1109485712 11.928 98 23 42 0 1136553280 23172464 3895104
07:36:55 33505 8192000000 9361328304 9717190656 491715232 5.662 44 3 6 0 515827216 23608016 503968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-09-2022 07:39:18 to 29-09-2022 07:39:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-09-2022 07:39:35 to 29-09-2022 07:39:37.

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          211284
MON$OLDEST_ACTIVE               211285
MON$OLDEST_SNAPSHOT             211285
MON$NEXT_TRANSACTION            211285
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                       2088800
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             757
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

29.09.22 07:39:38. There are 6 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-09-29 07:39:41 No severe PSQL-related problems occured
29.09.22 07:39:42. End of report.