Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.07.2022 13: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 15425208 kB
MemAvailable 31035352 kB
Buffers 0 kB
Cached 16290544 kB
SwapCached 2512 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.2
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-07-29 13:37:19
DTS_END                         2022-07-29 13:37:19.2700
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/45904
TRN_ID                          223213
ATT_ID                          586
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: 8814.43 21513 1057732
customer order: creation 171.48 450 20578
customer order: refuse 14.82 396 1778
order to supplier: creation 46.12 1296 5534
order to supplier: removal 3.25 174292 390
invoice (draft): creation 45.84 3271 5501
invoice (draft): removal 48.23 1069 5787
invoice accept: apply 42.43 25225 5092
invoice accept: cancel 43.90 1325 5268
customer reserve: creation 3877.28 241 465273
customer reserve: removal 4220.32 104 506438
realization accept: apply 84.90 296 10188
realization accept: cancel 85.44 166 10253
payment from customer: creation 58.98 191 7078
payment from customer: removal 5.58 146 670
payment to supplier: creation 49.82 209 5978
payment to supplier: removal 3.38 164 406
service: total inventory turnovers 3.82 4436 458
service: total monetary turnovers 7.78 339 934
service: aggregate perf. data 1.03 13822 123
service: refresh index statistics 0.04 202826 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-07-2022 13:38:40 to 29-07-2022 13:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8607
TEST_TIME 2 6395
TEST_TIME 3 6819
TEST_TIME 4 7162
TEST_TIME 5 6633
TEST_TIME 6 6555
TEST_TIME 7 6547
TEST_TIME 8 7016
TEST_TIME 9 7358
TEST_TIME 10 7383
TEST_TIME 11 7349
TEST_TIME 12 7352
TEST_TIME 13 7630
TEST_TIME 14 7936
TEST_TIME 15 7844
TEST_TIME 16 7852
TEST_TIME 17 8111
TEST_TIME 18 8122
TEST_TIME 19 8339
TEST_TIME 20 8423
TEST_TIME 21 8689
TEST_TIME 22 8710
TEST_TIME 23 8832
TEST_TIME 24 8920
TEST_TIME 25 8789
TEST_TIME 26 8853
TEST_TIME 27 9071
TEST_TIME 28 9050
TEST_TIME 29 9210
TEST_TIME 30 9559
TEST_TIME 31 10186
TEST_TIME 32 10310
TEST_TIME 33 10826
TEST_TIME 34 10868
TEST_TIME 35 10999
TEST_TIME 36 11374
TEST_TIME 37 11245
TEST_TIME 38 11050
TEST_TIME 39 10890
TEST_TIME 40 10930
TEST_TIME 41 10812
TEST_TIME 42 10862
TEST_TIME 43 10833
TEST_TIME 44 10706
TEST_TIME 45 10557
TEST_TIME 46 10429
TEST_TIME 47 10363
TEST_TIME 48 10235
TEST_TIME 49 10156
TEST_TIME 50 10072
TEST_TIME 51 9952
TEST_TIME 52 9955
TEST_TIME 53 9915
TEST_TIME 54 9840
TEST_TIME 55 9719
TEST_TIME 56 9638
TEST_TIME 57 9615
TEST_TIME 58 9576
TEST_TIME 59 9515
TEST_TIME 60 9512
TEST_TIME 61 9433
TEST_TIME 62 9389
TEST_TIME 63 9278
TEST_TIME 64 9298
TEST_TIME 65 9276
TEST_TIME 66 9196
TEST_TIME 67 9227
TEST_TIME 68 9253
TEST_TIME 69 9223
TEST_TIME 70 9241
TEST_TIME 71 9227
TEST_TIME 72 9215
TEST_TIME 73 9280
TEST_TIME 74 9359
TEST_TIME 75 9404
TEST_TIME 76 9345
TEST_TIME 77 9390
TEST_TIME 78 9382
TEST_TIME 79 9391
TEST_TIME 80 9341
TEST_TIME 81 9288
TEST_TIME 82 9262
TEST_TIME 83 9236
TEST_TIME 84 9230
TEST_TIME 85 9191
TEST_TIME 86 9179
TEST_TIME 87 9143
TEST_TIME 88 9098
TEST_TIME 89 9039
TEST_TIME 90 9001
TEST_TIME 91 9056
TEST_TIME 92 9085
TEST_TIME 93 9065
TEST_TIME 94 9072
TEST_TIME 95 9053
TEST_TIME 96 9033
TEST_TIME 97 9123
TEST_TIME 98 9228
TEST_TIME 99 9251
TEST_TIME 100 9293
TEST_TIME 101 9294
TEST_TIME 102 9301
TEST_TIME 103 9293
TEST_TIME 104 9220
TEST_TIME 105 9227
TEST_TIME 106 9220
TEST_TIME 107 9158
TEST_TIME 108 9115
TEST_TIME 109 9075
TEST_TIME 110 9042
TEST_TIME 111 9040
TEST_TIME 112 9033
TEST_TIME 113 8995
TEST_TIME 114 8969
TEST_TIME 115 8907
TEST_TIME 116 8935
TEST_TIME 117 8918
TEST_TIME 118 8897
TEST_TIME 119 8895
TEST_TIME 120 8835

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-07-2022 13:38:44 to 29-07-2022 13:38:47.

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 1485796 1485796 0 0.00 0 20112 14 0 0
doc_list_biud 1485796 1485796 0 0.00 0 29257 42 0 0
sp_add_invoice_to_stock 5246 5092 154 2.93 61 187824 25225 0 154
sp_cancel_adding_invoice 5268 5268 0 0.00 6 71237 1326 0 0
sp_cancel_client_order 1778 1778 0 0.00 6 9599 396 0 0
sp_cancel_customer_reserve 506438 506438 0 0.00 1 21088 105 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 4 3159 147 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 6417 165 0 0
sp_cancel_supplier_invoice 5800 5787 13 0.22 2 21400 1070 0 13
sp_cancel_supplier_order 390 390 0 0.00 13 1563207 174293 0 0
sp_cancel_write_off 10253 10253 0 0.00 2 7047 166 0 0
sp_client_order 20578 20578 0 0.00 5 22087 450 0 0
sp_customer_reserve 465273 465273 0 0.00 1 78043 241 0 0
sp_fill_shopping_cart 31609 31609 0 0.00 1 29068 88 0 0
sp_fill_shopping_cart_clo_res 465255 465255 0 0.00 0 21920 18 0 0
sp_get_clo_for_invoice 5056 5056 0 0.00 0 17826 253 0 0
sp_kill_cost_storno 16597 16597 0 0.00 0 8691 78 0 0
sp_kill_qstorno_ret_qs2qd 1029306 1029306 0 0.00 0 20317 45 0 0
sp_kill_qty_storno 545156 545156 0 0.00 0 29250 104 0 0
sp_lock_selected_doc 21041 21041 0 0.00 0 10851 9 0 0
sp_make_cost_storno 28298 28298 0 0.00 0 13619 87 0 0
sp_multiply_rows_for_pdistr 28298 28298 0 0.00 0 7852 37 0 0
sp_multiply_rows_for_qdistr 20578 20578 0 0.00 0 14285 161 0 0
sp_pay_from_customer 7078 7078 0 0.00 5 12703 191 0 0
sp_pay_to_supplier 5978 5978 0 0.00 6 13940 209 0 0
sp_payment_common 13056 13056 0 0.00 2 8417 52 0 0
sp_qd_handle_on_cancel_clo 1778 1778 0 0.00 0 9591 327 0 0
sp_qd_handle_on_invoice_upd_sts 10322 10322 0 0.00 0 29245 567 0 0
sp_qd_handle_on_reserve_upd_sts 10188 10188 0 0.00 0 13893 70 0 0
sp_reserve_write_off 10188 10188 0 0.00 9 13911 296 0 0
sp_supplier_invoice 5501 5501 0 0.00 11 105252 3271 0 0
sp_supplier_order 5534 5534 0 0.00 7 33759 1296 0 0
srv_aggregate_perf_data 123 123 0 0.00 2671 182803 13823 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 733 144475 34048 0 0
srv_find_qd_qs_mism 1008418 1008418 0 0.00 0 20110 10 0 0
srv_make_invnt_saldo 458 458 0 0.00 69 120777 4436 0 0
srv_make_money_saldo 934 934 0 0.00 1 8498 339 0 0
srv_recalc_idx_stat 5 5 0 0.00 46142 400537 202827 0 0
t$perf-abend:sp_add_invoice_to_stock 154 154 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 5092 5092 0 0.00 0 9717 985 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 2 6110 469 0 0
t$perf-norm:sp_cancel_client_order 1778 1778 0 0.00 0 1774 28 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 569 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 10656 32 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 477 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 1139 47 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 87938 7914 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 2989 23 0 0
t$perf-norm:sp_client_order 20578 20578 0 0.00 0 8405 39 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 3316 23 0 0
t$perf-norm:sp_pay_from_customer 7078 7078 0 0.00 0 28333 25 0 0
t$perf-norm:sp_pay_to_supplier 5978 5978 0 0.00 0 28365 26 0 0
t$perf-norm:sp_reserve_write_off 10188 10188 0 0.00 0 6168 24 0 0
t$perf-norm:sp_supplier_invoice 5501 5501 0 0.00 0 15162 32 0 0
t$perf-norm:sp_supplier_order 5534 5534 0 0.00 0 4710 34 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 489 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 193 8 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 428 12 0 0
t$perf-norm:srv_make_money_saldo 934 934 0 0.00 0 470 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 388 134 0 0
v_add_invoice_to_stock 10416 10108 308 2.95 0 9623 31 0 308
v_all_customers 37844 37844 0 0.00 0 71 4 0 0
v_all_suppliers 30960 30960 0 0.00 0 54 5 0 0
v_all_wares 41156 41156 0 0.00 0 63 4 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 890 15 0 0
v_cancel_client_order 3556 3556 0 0.00 0 1288 9 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 2475 16 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 1 730 12 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1901 15 0 0
v_cancel_supplier_invoice 800 774 26 3.25 0 3022 29 0 26
v_cancel_supplier_order 780 780 0 0.00 0 1450 16 0 0
v_cancel_write_off 2072 2072 0 0.00 0 1384 27 0 0
v_max_id_clo_ord 5534 5534 0 0.00 0 123 5 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 1751 12 0 0
v_max_id_ord_sup 5497 5497 0 0.00 0 4521 8 0 0
v_max_non_paid_invoice 1529 1529 0 0.00 0 195 8 0 0
v_max_non_paid_realizn 3729 3729 0 0.00 0 3752 11 0 0
v_min_id_clo_ord 5534 5534 0 0.00 0 4889 10 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 7380 20 0 0
v_min_id_ord_sup 5497 5497 0 0.00 0 5046 11 0 0
v_min_non_paid_invoice 5978 5978 0 0.00 0 8120 20 0 0
v_min_non_paid_realizn 7078 7078 0 0.00 0 8371 21 0 0
v_our_firm 10010 10010 0 0.00 0 60 5 0 0
v_reserve_write_off 20376 20376 0 0.00 0 9628 12 0 0
x_lock_depdocs_on_canc_invoice 10668 10668 0 0.00 0 21528 138 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 3136 353 0 0
x_make_qstorno_1000_1200 5534 5534 0 0.00 3 33729 1117 0 0
x_make_qstorno_1200_2000 5501 5501 0 0.00 4 102217 3033 0 0
x_make_qstorno_2100_3300 465273 465273 0 0.00 0 78011 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-07-2022 13:38:48 to 29-07-2022 13:38:52.

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
11:07:23 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:24 11698 8192000000 9713694288 10006822912 794552352 8.841 100 90 181 0 824112608 7775664 21784592
11:09:45 18628 8192000000 9752619248 10058551296 824205408 9.141 100 86 172 1 856819680 12043696 20570576
11:11:10 19351 8192000000 9788292336 10102931456 855918272 9.459 100 90 180 1 899100048 22143936 21037840
11:12:37 10445 8192000000 9852151952 10184953856 902856960 9.927 100 78 156 0 965279840 45393536 17029344
11:13:54 5396 8192000000 9906599808 10244874240 948801024 10.379 100 84 161 0 1013450208 48075312 16573872
11:15:01 1535 8192000000 9963941280 10314084352 995275696 10.833 100 94 180 1 1077389456 63078576 19035184
11:16:06 1168 8192000000 9999349168 10359721984 1022230416 11.094 100 95 181 1 1120548192 80520320 17797456
11:17:10 911 8192000000 10020686992 10386624512 1043372144 11.297 100 95 186 3 1142167616 79491552 19303920
11:18:12 962 8192000000 10037334496 10413207552 1057105712 11.429 100 93 177 2 1155723712 80888736 17729264
11:19:14 768 8192000000 10050043552 10431696896 1064955904 11.504 100 92 179 3 1175336480 92686512 17694064
11:20:16 957 8192000000 10042408080 10426417152 1078583760 11.634 100 91 176 0 1168178560 71146368 18448432
11:21:18 1097 8192000000 10062264800 10446245888 1088205168 11.726 100 92 176 1 1183930832 78951520 16774144
11:22:20 961 8192000000 10028401168 10416623616 1091343952 11.755 100 85 162 1 1148486640 41370400 15772288
11:23:22 1102 8192000000 10066208800 10451021824 1094955136 11.790 100 97 192 1 1183889344 68919264 20014944
11:24:24 1203 8192000000 10066799264 10455900160 1098343296 11.822 100 94 180 2 1185593376 69571920 17678160
11:25:26 1056 8192000000 10090926496 10477191168 1098823472 11.826 100 100 192 2 1210340720 92294576 19222672
11:26:28 1029 8192000000 10062711040 10455556096 1099313984 11.831 100 79 149 0 1182393648 67558608 15521056
11:27:30 856 8192000000 10067937792 10456752128 1097049456 11.810 100 93 175 1 1187425200 72969376 17406368
11:28:31 1711 8192000000 10036672816 10432974848 1094947152 11.790 100 71 135 0 1164188928 54079264 15162512
11:29:35 2226 8192000000 10015639616 10403102720 1075473680 11.604 99 72 141 0 1131264272 41024032 14766560
11:30:38 5066 8192000000 10045131344 10440192000 1072894016 11.580 100 72 136 2 1162077776 74961040 14222720
11:31:44 1093 8192000000 10017649072 10393157632 1055380096 11.412 100 98 184 1 1140775024 67023840 18371088
11:32:46 765 8192000000 9991213152 10364989440 1029971664 11.168 100 93 177 1 1111804528 62836224 18996640
11:33:49 984 8192000000 10005061648 10386980864 1050646224 11.367 100 89 172 2 1127887360 59235792 18005344
11:34:51 1318 8192000000 10002289776 10380029952 1041190112 11.276 100 80 152 0 1122739968 65313584 16236272
11:35:54 2055 8192000000 9998781216 10386714624 1045683360 11.319 100 61 119 0 1125514240 67959536 11871344
11:36:56 1055 8192000000 9988464928 10366902272 1042622800 11.290 100 89 171 4 1116728112 55396512 18708800
11:38:04 2036 8192000000 10019175072 10403778560 1069609712 11.548 100 82 144 4 1147069552 61453472 16006368
11:39:12 6001 8192000000 10050648096 10429247488 1089627088 11.739 100 91 170 0 1168177008 61584224 16965696
11:40:23 4573 8192000000 10081985264 10470039552 1089523072 11.738 100 72 141 1 1195452832 91469680 14460080
11:41:30 1809 8192000000 10094350880 10478813184 1097224032 11.811 99 85 156 3 1203328720 89293808 16810880
11:42:34 2354 8192000000 10145799424 10533220352 1118337184 12.011 100 88 165 1 1260728944 125950112 16441648
11:43:42 4411 8192000000 10167822640 10567544832 1130196096 12.123 100 73 142 1 1283339056 137969392 15173568
11:44:52 2523 8192000000 10197020192 10593513472 1136553664 12.183 100 85 159 1 1313261984 160543184 16165136
11:45:57 2109 8192000000 10216333760 10615074816 1141388720 12.229 100 85 165 1 1334339424 174556656 18394048
11:47:02 1990 8192000000 10230388944 10642042880 1155618256 12.362 100 85 163 0 1344970320 172608128 16743936
11:48:08 943 8192000000 10236157056 10641862656 1154654560 12.353 100 85 158 4 1350924128 178895440 17374128
11:49:13 1084 8192000000 10296761760 10699849728 1160560624 12.409 100 88 173 0 1402136480 222174016 19401840
11:50:17 1058 8192000000 10318500080 10728185856 1170981296 12.506 100 98 193 1 1429692304 236842624 21868384
11:51:20 822 8192000000 10320389584 10735550464 1171183488 12.508 100 91 170 1 1432648160 239726288 21738384
11:52:25 1405 8192000000 10349713488 10753622016 1185712208 12.643 100 93 182 0 1460482768 254030448 20740112
11:53:28 1737 8192000000 10373096544 10787241984 1180341520 12.593 100 87 166 1 1478354832 278294144 19719168
11:54:33 1041 8192000000 10387008320 10799489024 1181696688 12.606 100 93 183 0 1493233280 290378752 21157840
11:55:38 877 8192000000 10400191664 10831360000 1172056144 12.516 99 99 192 0 1506587984 311838896 22692944
11:56:41 973 8192000000 10422426976 10842583040 1167654192 12.475 100 99 195 0 1530373616 339205664 23513760
11:57:45 2033 8192000000 10439950528 10864508928 1172830976 12.523 100 82 156 2 1550758544 358415680 19511888
11:58:50 8204 8192000000 10420854560 10884042752 1173840800 12.533 100 58 116 0 1528732912 339959488 14932624
12:00:07 725 8192000000 10450242256 10884292608 1175591056 12.549 100 93 182 0 1553536144 356018272 21926816
12:01:14 880 8192000000 10477675184 10884141056 1188773088 12.672 100 95 182 2 1578844832 367844352 22227392
12:02:16 13173 8192000000 10487793376 10928877568 1164644896 12.447 98 54 104 0 1594802704 416852736 13305072
12:03:34 3251 8192000000 10518917920 10982232064 1174379632 12.538 99 62 120 0 1636283184 446874352 15029200
12:04:39 3720 8192000000 10567607504 10992472064 1182133584 12.610 100 60 120 0 1670475472 473609040 14732848
12:05:55 3275 8192000000 10561549232 11017523200 1172527088 12.520 100 58 115 0 1668368640 481699264 14142288
12:07:07 4150 8192000000 10571032240 11045494784 1175106048 12.545 100 52 103 0 1671166464 482976608 13083808
12:08:15 26057 8192000000 10465566160 10944274432 1154294048 12.350 99 32 64 0 1561046224 398554272 8197904
12:09:47 23166 8192000000 10358426016 10836594688 1118036128 12.008 97 31 61 0 1459386576 333686352 7664096
12:11:13 33140 8192000000 10242580864 10677420032 1130259120 12.124 99 26 50 0 1316728384 180664880 5804384
12:12:48 8159 8192000000 10196850176 10686996480 1121528160 12.041 98 34 68 1 1281126704 151328976 8269568
12:14:03 74538 8192000000 10178546608 10631147520 1139008896 12.206 99 29 58 1 1264386864 118220064 7157904
12:16:35 9980 8192000000 10194773920 10637107200 1138697360 12.203 99 47 94 0 1285028848 136325840 10005648
12:17:51 57215 8192000000 10160562816 10633629696 1150263168 12.312 100 20 39 0 1270175216 115463216 4448832
12:20:21 72441 8192000000 10062047104 10530639872 1119869184 12.026 98 17 34 0 1155646720 32245200 3532336
12:22:41 144475 8192000000 10076195184 10521661440 1102929600 11.865 96 15 29 0 1146939472 41015968 2993904
12:26:25 13564 8192000000 10095590880 10530717696 1125496592 12.079 99 49 93 2 1184667568 49382496 9788480
12:27:40 94039 8192000000 10072201712 10570121216 1116321600 11.992 99 16 30 1 1212196560 91867360 4007600
12:30:15 50060 8192000000 10067877456 10521329664 1119390336 12.021 99 18 36 0 1153299856 30287280 3622240
12:32:10 43664 8192000000 10067819920 10506637312 1125591216 12.080 99 26 50 0 1147213472 16554736 5067520
12:34:02 18021 8192000000 10091162832 10537414656 1110920576 11.941 98 48 89 1 1178859680 59308016 8631088
12:35:27 11177 8192000000 10057737200 10486550528 1120093968 12.028 100 67 129 1 1161222800 26823040 14305792
12:36:39 27040 8192000000 10057608624 10495344640 1069243648 11.545 95 30 60 1 1127399072 51902624 6252800
12:38:20 101445 8192000000 10056231760 10528952320 1121964800 12.046 99 17 32 0 1201840640 76688560 3187280
12:41:01 82027 8192000000 10087322160 10558111744 1117414400 12.003 99 25 50 0 1171812720 49280304 5118016
12:43:26 47296 8192000000 10081696256 10565263360 1126437440 12.088 99 12 20 1 1188168336 60175728 1555168
12:45:17 38038 8192000000 10119766192 10581594112 1123583872 12.061 99 48 94 0 1207029072 74136960 9308240
12:46:57 34338 8192000000 10115271888 10570907648 1113696160 11.967 98 39 78 0 1187611792 65878176 8037456
12:48:32 26518 8192000000 10122321728 10565300224 1147306736 12.284 100 44 87 0 1223863136 67102576 9453824
12:50:02 47718 8192000000 10128879152 10617827328 1144907520 12.262 100 13 26 0 1261075504 113073216 3094768
12:51:50 83564 8192000000 10083059312 10527375360 1111170160 11.943 97 23 46 0 1172659568 57151520 4337888
12:54:20 47225 8192000000 10102037952 10539528192 1095624160 11.796 97 34 66 0 1181467680 79335504 6508016
12:56:16 39920 8192000000 10098813712 10538610688 1118029120 12.008 99 39 78 0 1185359712 59656336 7674256
12:57:56 78710 8192000000 10078496624 10548817920 1100860288 11.846 97 24 44 0 1143779792 38602096 4317408
13:00:20 41606 8192000000 10115933120 10582183936 1129653024 12.118 99 25 50 0 1198616416 63948384 5015008
13:02:02 61744 8192000000 10067272016 10542551040 1122267968 12.048 99 9 19 0 1183275440 59220992 1786480
13:04:15 56016 8192000000 10062802848 10517745664 1127701232 12.100 100 15 30 0 1165641792 34708768 3231792
13:06:13 58886 8192000000 10115904592 10567344128 1118824160 12.016 98 9 18 0 1194644832 73905856 1914816
13:08:14 70682 8192000000 10124099584 10561196032 1075093008 11.601 95 19 38 0 1169869808 90765792 4011008
13:10:26 25121 8192000000 10125735296 10557628416 1132958592 12.149 100 23 41 1 1212950736 74937440 5054704
13:11:58 35093 8192000000 10174867872 10607783936 1141734512 12.232 100 40 81 2 1260162544 109434320 8993712
13:13:36 71563 8192000000 10178803968 10630438912 1113208496 11.963 97 16 32 0 1247589056 130847600 3532960
13:15:59 46153 8192000000 10106109856 10537598976 1135506320 12.173 100 25 50 0 1250958608 109109424 6342864
13:17:45 7303 8192000000 10108869056 10536538112 1135579952 12.174 100 52 102 3 1198890368 52921728 10388688
13:18:55 57710 8192000000 10110782688 10558353408 1119488944 12.022 98 37 72 1 1197597936 71035344 7073648
13:20:56 106247 8192000000 10139386416 10601402368 1140159008 12.217 99 29 59 0 1196421248 50321472 5940768
13:24:02 71830 8192000000 10101922016 10603741184 1132125328 12.141 99 6 12 0 1205049040 71595520 1328192
13:26:14 108834 8192000000 10102203472 10577727488 1120416208 12.031 98 11 22 0 1204436496 81573312 2446976
13:29:15 67877 8192000000 10069683248 10529255424 1130594928 12.127 99 13 26 0 1163103008 29928496 2579584
13:31:23 57192 8192000000 10076480384 10531454976 1132982848 12.149 99 16 32 0 1173149344 36998576 3167920
13:34:12 77862 8192000000 10051990208 10561179648 1126330208 12.087 99 11 20 0 1162183184 34087040 1765936
13:36:31 18670 8192000000 10064791968 10505900032 1100120976 11.839 97 30 60 0 1150051440 44027840 5902624

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-07-2022 13:38:54 to 29-07-2022 13:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 154 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 29-07-2022 13:39:11 to 29-07-2022 13:39:13.

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          219905
MON$OLDEST_ACTIVE               223411
MON$OLDEST_SNAPSHOT             223411
MON$NEXT_TRANSACTION            223411
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                       2105728
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:248: 
:249: mscs-test-161.budzdorov.ru    Fri Jul 29 13:37:24 2022
:250:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:251: 
:252: 
:253: mscs-test-161.budzdorov.ru    Fri Jul 29 13:37:26 2022
:254:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:255: 
:256: 
:257: mscs-test-161.budzdorov.ru    Fri Jul 29 13:37:26 2022
:258:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:259: 
--- end of diff output ---

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

29.07.22 13:39:14. 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-07-29 13:39:17 No severe PSQL-related problems occured
29.07.22 13:39:18. End of report.