Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.06.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 15865388 kB
MemAvailable 31314844 kB
Buffers 20 kB
Cached 15806580 kB
SwapCached 2448 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-06-06 04:37:15
DTS_END                         2022-06-06 04:37:15.8590
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/49240
TRN_ID                          224836
ATT_ID                          643
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: 9238.39 28308 1108607
customer order: creation 169.32 454 20318
customer order: refuse 15.50 433 1860
order to supplier: creation 47.10 1264 5652
order to supplier: removal 3.41 160503 409
invoice (draft): creation 45.97 3158 5516
invoice (draft): removal 51.93 958 6231
invoice accept: apply 42.05 25844 5046
invoice accept: cancel 47.49 1125 5699
customer reserve: creation 3953.62 245 474434
customer reserve: removal 4561.68 93 547401
realization accept: apply 83.98 263 10077
realization accept: cancel 86.53 150 10383
payment from customer: creation 58.71 164 7045
payment from customer: removal 5.93 130 711
payment to supplier: creation 49.38 196 5926
payment to supplier: removal 3.69 165 443
service: total inventory turnovers 4.02 3773 482
service: total monetary turnovers 7.15 394 858
service: aggregate perf. data 0.93 15766 111
service: refresh index statistics 0.04 351080 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-06-2022 04:39:54 to 06-06-2022 04:39:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4825
TEST_TIME 2 5316
TEST_TIME 3 5330
TEST_TIME 4 5893
TEST_TIME 5 5692
TEST_TIME 6 5878
TEST_TIME 7 5840
TEST_TIME 8 6146
TEST_TIME 9 6401
TEST_TIME 10 6767
TEST_TIME 11 7080
TEST_TIME 12 7175
TEST_TIME 13 7182
TEST_TIME 14 7144
TEST_TIME 15 7332
TEST_TIME 16 7429
TEST_TIME 17 7516
TEST_TIME 18 7628
TEST_TIME 19 7662
TEST_TIME 20 7690
TEST_TIME 21 7966
TEST_TIME 22 8235
TEST_TIME 23 8261
TEST_TIME 24 8280
TEST_TIME 25 8171
TEST_TIME 26 8230
TEST_TIME 27 8565
TEST_TIME 28 9418
TEST_TIME 29 9484
TEST_TIME 30 10080
TEST_TIME 31 10646
TEST_TIME 32 10586
TEST_TIME 33 10981
TEST_TIME 34 11150
TEST_TIME 35 11093
TEST_TIME 36 11024
TEST_TIME 37 11038
TEST_TIME 38 11226
TEST_TIME 39 11278
TEST_TIME 40 11283
TEST_TIME 41 11158
TEST_TIME 42 10988
TEST_TIME 43 10894
TEST_TIME 44 10750
TEST_TIME 45 10670
TEST_TIME 46 10738
TEST_TIME 47 10715
TEST_TIME 48 10569
TEST_TIME 49 10495
TEST_TIME 50 10484
TEST_TIME 51 10424
TEST_TIME 52 10384
TEST_TIME 53 10504
TEST_TIME 54 10371
TEST_TIME 55 10283
TEST_TIME 56 10183
TEST_TIME 57 10186
TEST_TIME 58 10179
TEST_TIME 59 10027
TEST_TIME 60 9993
TEST_TIME 61 9930
TEST_TIME 62 9821
TEST_TIME 63 9739
TEST_TIME 64 9768
TEST_TIME 65 9736
TEST_TIME 66 9740
TEST_TIME 67 9664
TEST_TIME 68 9656
TEST_TIME 69 9770
TEST_TIME 70 9742
TEST_TIME 71 9695
TEST_TIME 72 9684
TEST_TIME 73 9765
TEST_TIME 74 9799
TEST_TIME 75 9812
TEST_TIME 76 9786
TEST_TIME 77 9823
TEST_TIME 78 9805
TEST_TIME 79 9731
TEST_TIME 80 9721
TEST_TIME 81 9698
TEST_TIME 82 9626
TEST_TIME 83 9562
TEST_TIME 84 9554
TEST_TIME 85 9534
TEST_TIME 86 9539
TEST_TIME 87 9512
TEST_TIME 88 9483
TEST_TIME 89 9489
TEST_TIME 90 9491
TEST_TIME 91 9564
TEST_TIME 92 9578
TEST_TIME 93 9582
TEST_TIME 94 9652
TEST_TIME 95 9623
TEST_TIME 96 9612
TEST_TIME 97 9530
TEST_TIME 98 9510
TEST_TIME 99 9516
TEST_TIME 100 9479
TEST_TIME 101 9431
TEST_TIME 102 9393
TEST_TIME 103 9394
TEST_TIME 104 9367
TEST_TIME 105 9336
TEST_TIME 106 9315
TEST_TIME 107 9349
TEST_TIME 108 9324
TEST_TIME 109 9361
TEST_TIME 110 9353
TEST_TIME 111 9365
TEST_TIME 112 9367
TEST_TIME 113 9428
TEST_TIME 114 9448
TEST_TIME 115 9455
TEST_TIME 116 9450
TEST_TIME 117 9385
TEST_TIME 118 9369
TEST_TIME 119 9310
TEST_TIME 120 9255

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-06-2022 04:39:58 to 06-06-2022 04:40:01.

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 1545130 1545130 0 0.00 0 17799 13 0 0
doc_list_biud 1545114 1545114 0 0.00 0 44896 39 0 0
sp_add_invoice_to_stock 5205 5046 159 3.05 76 198304 25845 0 159
sp_cancel_adding_invoice 5699 5699 0 0.00 7 106254 1125 0 0
sp_cancel_client_order 1860 1860 0 0.00 5 11923 434 0 0
sp_cancel_customer_reserve 547401 547401 0 0.00 1 17754 94 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 4 4297 131 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 4 8152 166 0 0
sp_cancel_supplier_invoice 6246 6231 15 0.24 3 17000 958 0 15
sp_cancel_supplier_order 409 409 0 0.00 21 1398225 160503 0 0
sp_cancel_write_off 10383 10383 0 0.00 2 11694 151 0 0
sp_client_order 20318 20318 0 0.00 6 18132 454 0 0
sp_customer_reserve 474434 474434 0 0.00 1 35852 245 0 0
sp_fill_shopping_cart 31473 31473 0 0.00 1 22010 77 0 0
sp_fill_shopping_cart_clo_res 474408 474408 0 0.00 0 12749 17 0 0
sp_get_clo_for_invoice 5016 5016 0 0.00 0 19851 260 0 0
sp_kill_cost_storno 17236 17236 0 0.00 0 12424 68 0 0
sp_kill_qstorno_ret_qs2qd 1111825 1111825 0 0.00 0 44894 41 0 0
sp_kill_qty_storno 587070 587070 0 0.00 0 44896 94 0 0
sp_lock_selected_doc 20953 20953 0 0.00 0 7218 7 0 0
sp_make_cost_storno 28060 28060 0 0.00 0 16374 78 0 0
sp_multiply_rows_for_pdistr 28058 28058 0 0.00 0 10252 38 0 0
sp_multiply_rows_for_qdistr 20318 20318 0 0.00 0 14225 175 0 0
sp_pay_from_customer 7045 7045 0 0.00 6 16361 165 0 0
sp_pay_to_supplier 5926 5926 0 0.00 7 17807 196 0 0
sp_payment_common 12968 12968 0 0.00 2 6108 44 0 0
sp_qd_handle_on_cancel_clo 1860 1860 0 0.00 0 11903 362 0 0
sp_qd_handle_on_invoice_upd_sts 10713 10713 0 0.00 0 14999 499 0 0
sp_qd_handle_on_reserve_upd_sts 10072 10072 0 0.00 0 27319 58 0 0
sp_reserve_write_off 10077 10077 0 0.00 8 27390 263 0 0
sp_supplier_invoice 5516 5516 0 0.00 11 100672 3158 0 0
sp_supplier_order 5652 5652 0 0.00 7 118988 1264 0 0
srv_aggregate_perf_data 111 111 0 0.00 2600 163217 15767 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 818 103810 32623 0 0
srv_find_qd_qs_mism 1059048 1059048 0 0.00 0 12603 10 0 0
srv_make_invnt_saldo 482 482 0 0.00 74 87734 3774 0 0
srv_make_money_saldo 858 858 0 0.00 1 12808 395 0 0
srv_recalc_idx_stat 5 5 0 0.00 240867 482632 351080 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5048 5048 0 0.00 1 14741 816 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 2 11047 439 0 0
t$perf-norm:sp_cancel_client_order 1860 1860 0 0.00 0 3004 30 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 626 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 568 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 4177 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 852 33 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 89298 6769 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 1288 17 0 0
t$perf-norm:sp_client_order 20318 20318 0 0.00 0 8862 32 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 4213 18 0 0
t$perf-norm:sp_pay_from_customer 7045 7045 0 0.00 0 3043 15 0 0
t$perf-norm:sp_pay_to_supplier 5926 5926 0 0.00 0 8600 18 0 0
t$perf-norm:sp_reserve_write_off 10077 10077 0 0.00 0 13079 23 0 0
t$perf-norm:sp_supplier_invoice 5517 5517 0 0.00 0 1808 26 0 0
t$perf-norm:sp_supplier_order 5652 5652 0 0.00 0 10250 29 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 205 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 136 6 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 7202 35 0 0
t$perf-norm:srv_make_money_saldo 858 858 0 0.00 0 909 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 221 88 0 0
v_add_invoice_to_stock 10338 10020 318 3.07 0 5287 27 0 318
v_all_customers 37566 37566 0 0.00 0 270 3 0 0
v_all_suppliers 31214 31214 0 0.00 0 75 4 0 0
v_all_wares 40629 40629 0 0.00 0 105 3 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 1239 15 0 0
v_cancel_client_order 3720 3720 0 0.00 0 1679 9 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 4283 19 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 1365 12 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 8071 25 0 0
v_cancel_supplier_invoice 860 830 30 3.48 0 1357 29 0 30
v_cancel_supplier_order 818 818 0 0.00 0 1357 14 0 0
v_cancel_write_off 2002 2002 0 0.00 0 2163 26 0 0
v_max_id_clo_ord 5651 5651 0 0.00 0 352 4 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 513 9 0 0
v_max_id_ord_sup 5507 5507 0 0.00 0 410 4 0 0
v_max_non_paid_invoice 1476 1476 0 0.00 0 937 8 0 0
v_max_non_paid_realizn 3558 3558 0 0.00 0 1900 7 0 0
v_min_id_clo_ord 5650 5650 0 0.00 0 4253 9 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 874 7 0 0
v_min_id_ord_sup 5507 5507 0 0.00 0 4672 10 0 0
v_min_non_paid_invoice 5925 5925 0 0.00 0 5989 15 0 0
v_min_non_paid_realizn 7041 7041 0 0.00 0 6104 18 0 0
v_our_firm 10028 10028 0 0.00 0 88 4 0 0
v_reserve_write_off 20144 20144 0 0.00 0 4018 11 0 0
x_lock_depdocs_on_canc_invoice 11515 11515 0 0.00 0 14676 117 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 18262 412 0 0
x_make_qstorno_1000_1200 5652 5652 0 0.00 2 118944 1095 0 0
x_make_qstorno_1200_2000 5516 5516 0 0.00 4 100108 2960 0 0
x_make_qstorno_2100_3300 474434 474434 0 0.00 0 35827 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-06-2022 04:40:02 to 06-06-2022 04:40:06.

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:19 3 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:20 26395 8192000000 9732543376 10033668096 807754368 8.975 100 88 176 1 836259280 7531264 20973648
02:09:58 24812 8192000000 9774300592 10085302272 846892064 9.369 100 89 176 1 881756608 14129520 20735024
02:11:38 10705 8192000000 9820840400 10142076928 884989040 9.749 100 84 168 0 931455232 27437904 19028288
02:12:54 11582 8192000000 9874629920 10208841728 921461184 10.110 100 85 170 0 989675392 49684256 18529952
02:14:09 4417 8192000000 9917739744 10260041728 964208768 10.530 100 96 189 0 1038804976 54589488 20006720
02:15:15 1995 8192000000 9973573792 10325807104 993692224 10.817 100 93 177 2 1097653760 85528544 18432992
02:16:20 1831 8192000000 9992949680 10357751808 1021035296 11.082 100 87 173 1 1105002768 66552000 17415472
02:17:24 1240 8192000000 10011194352 10379460608 1046717040 11.329 100 93 181 1 1133885552 68744720 18423792
02:18:27 805 8192000000 10037596032 10410811392 1060635728 11.463 100 96 183 0 1158645664 78773696 19236240
02:19:29 1117 8192000000 10027590336 10402234368 1072841360 11.579 100 97 190 1 1148251616 56886208 18524048
02:20:31 1261 8192000000 10040862272 10419843072 1077284160 11.622 100 94 180 1 1152565392 56409952 18871280
02:21:33 1413 8192000000 10041171424 10433839104 1082432112 11.671 100 73 144 1 1165221168 68567552 14221504
02:22:36 1667 8192000000 10041425952 10433445888 1088147600 11.725 100 80 153 0 1161505024 57328976 16028448
02:23:38 1230 8192000000 10048793728 10436292608 1088552416 11.729 100 95 185 1 1171781376 64665072 18563888
02:24:40 1279 8192000000 10065491456 10456424448 1094601680 11.786 100 94 180 0 1187488448 74304848 18581920
02:25:42 1177 8192000000 10076755424 10465325056 1099326320 11.831 100 97 184 0 1197165920 78345712 19493888
02:26:44 1108 8192000000 10029666048 10415898624 1088160496 11.725 99 92 171 2 1152857776 45182016 19515264
02:27:46 1136 8192000000 10078089952 10466279424 1101037888 11.847 100 88 174 1 1198399408 79364384 17997136
02:28:48 4221 8192000000 10040650256 10438139904 1093729056 11.778 99 61 107 7 1166599184 61315696 11554432
02:29:55 794 8192000000 10055354080 10445963264 1099880560 11.837 100 83 160 1 1177554880 61039488 16634832
02:30:56 961 8192000000 10057900976 10440880128 1084061808 11.686 100 90 178 1 1179494128 76579888 18852432
02:31:59 1039 8192000000 10025815088 10401202176 1053208880 11.391 99 89 172 1 1148407392 78497952 16700560
02:33:00 1498 8192000000 10006643744 10377424896 1040507712 11.270 100 93 179 2 1123193376 62852096 19833568
02:34:03 1266 8192000000 10007718832 10386862080 1047636496 11.338 100 93 176 0 1129866944 62677072 19553376
02:35:04 1477 8192000000 9988229888 10368811008 1045261920 11.315 100 71 137 1 1111832032 52073888 14496224
02:36:07 1150 8192000000 10000359136 10382585856 1046281680 11.325 100 71 134 1 1120706640 59519568 14905392
02:37:09 3274 8192000000 9964642896 10342567936 1034872496 11.215 100 60 119 4 1080543632 33732720 11938416
02:38:23 9887 8192000000 9996710048 10386997248 1056645680 11.424 100 62 119 0 1112838656 44193888 11999088
02:39:41 1860 8192000000 10052157600 10435608576 1076834624 11.617 100 77 151 0 1168811648 76196080 15780944
02:40:52 5103 8192000000 10097132464 10485755904 1093165040 11.773 100 77 146 1 1215406240 107133248 15107952
02:42:01 6818 8192000000 10074975456 10470289408 1080084752 11.648 98 58 108 0 1195575888 104231264 11259872
02:43:12 8902 8192000000 10127401680 10530770944 1112542096 11.956 100 89 165 4 1253239072 123664416 17032560
02:44:26 1278 8192000000 10174913984 10564988928 1124161024 12.066 100 88 166 0 1288129072 146929680 17038368
02:45:33 2350 8192000000 10200613712 10605645824 1131297360 12.134 100 78 146 1 1314669696 167047632 16324704
02:46:39 894 8192000000 10217897488 10613932032 1149118640 12.301 100 94 180 2 1333465392 162883360 21463392
02:47:42 1545 8192000000 10258666496 10664325120 1149261728 12.303 100 85 159 1 1369065680 202640368 17163584
02:48:46 899 8192000000 10284119520 10700877824 1168834944 12.486 100 94 184 3 1392082784 203408112 19839728
02:49:50 1695 8192000000 10318900816 10718011392 1176971488 12.562 100 86 163 1 1431492960 236006048 18515424
02:50:55 2928 8192000000 10346932928 10744512512 1174988480 12.543 100 91 176 0 1455060048 260371792 19699776
02:52:01 1046 8192000000 10400492336 10816102400 1192630272 12.708 100 94 184 1 1506727360 292182288 21914800
02:53:07 1110 8192000000 10431968864 10841485312 1200329120 12.779 100 95 184 2 1540088288 317232016 22527152
02:54:11 1040 8192000000 10453813328 10859110400 1190329696 12.686 100 93 179 0 1556540368 346104288 20106384
02:55:16 990 8192000000 10499114224 10917601280 1199592160 12.773 100 95 188 0 1603576416 382587856 21396400
02:56:22 1090 8192000000 10526374224 10934493184 1196889808 12.747 100 97 185 1 1634401328 415368880 22142640
02:57:24 1184 8192000000 10544072064 10975887360 1201941424 12.794 100 95 189 0 1643692464 419167888 22583152
02:58:29 806 8192000000 10559288096 10983157760 1196539584 12.744 100 97 194 0 1662380368 441973024 23867760
02:59:32 6031 8192000000 10541053568 10978086912 1202015024 12.795 100 59 117 1 1643293568 427189680 14088864
03:00:50 10637 8192000000 10571168592 11000119296 1188520480 12.670 100 51 98 0 1667011520 465719808 12771232
03:02:02 11909 8192000000 10611916960 11060854784 1186768384 12.653 100 46 90 0 1710611520 511784480 12058656
03:03:22 6273 8192000000 10615528976 11095117824 1168473440 12.483 99 58 117 1 1745543840 562237536 14832864
03:04:42 18610 8192000000 10454905104 11069038592 1171885552 12.514 100 32 64 1 1669539008 488659296 8994160
03:06:03 6084 8192000000 10406694528 10846601216 1150786768 12.317 99 33 66 1 1526135456 367310064 8038624
03:07:10 4257 8192000000 10338864704 10861547520 1137649712 12.193 100 60 117 1 1435436240 284066656 13719872
03:08:16 20210 8192000000 10230127712 10714025984 1143457792 12.248 99 35 66 0 1334231088 183264672 7508624
03:09:37 30405 8192000000 10223901136 10675814400 1138261632 12.199 100 23 46 0 1335401552 190833312 6306608
03:11:12 17023 8192000000 10120306064 10615635968 1142843616 12.242 100 21 42 0 1236885728 88657104 5385008
03:12:31 20407 8192000000 10113252368 10568994816 1145698512 12.269 100 59 111 5 1229685696 70861488 13125696
03:13:53 71088 8192000000 10143643072 10649968640 1135284528 12.171 98 17 34 0 1254390832 114913808 4192496
03:16:08 100219 8192000000 10048124144 10534473728 1078693104 11.635 95 10 20 0 1149895152 69236064 1965984
03:18:55 66200 8192000000 10078596816 10522591232 1099457296 11.832 97 48 92 1 1148390784 38876352 10057136
03:21:02 78603 8192000000 10097297536 10557562880 1120232960 12.029 100 41 80 0 1187378944 57245472 9900512
03:23:42 42866 8192000000 10045840544 10496630784 1065345184 11.508 95 38 74 3 1116250304 43598880 7306240
03:25:29 20166 8192000000 10052895584 10505420800 1124881088 12.073 100 16 30 2 1154933552 27013120 3039344
03:26:51 8380 8192000000 10105994816 10538340352 1126136896 12.085 99 57 110 1 1202272000 65083984 11051120
03:28:06 33611 8192000000 10157668992 10582953984 1127596560 12.099 99 71 140 2 1228713712 85277888 15839264
03:30:17 35944 8192000000 10066781840 10564947968 1115843888 11.988 98 14 28 0 1175195712 56230976 3120848
03:32:31 57587 8192000000 10104928048 10570461184 1133219632 12.152 100 13 26 1 1190158320 53971472 2967216
03:34:36 35733 8192000000 10099497888 10566623232 1123635696 12.061 99 10 20 1 1208287632 82293744 2358192
03:36:13 44669 8192000000 10036679360 10495225856 1110522672 11.937 98 17 34 2 1127394192 13490256 3381264
03:38:01 41272 8192000000 10045083248 10504654848 1122617392 12.052 99 50 97 2 1152661296 17839904 12204000
03:39:42 71119 8192000000 10100555792 10557145088 1112972496 11.961 98 16 32 0 1175328352 58863184 3492672
03:41:54 49948 8192000000 10084380896 10549174272 1130973840 12.131 100 14 28 0 1200317920 66044208 3299872
03:43:45 42307 8192000000 10044832656 10485215232 1097641264 11.815 97 28 56 2 1132228944 28622688 5964992
03:45:28 60171 8192000000 10079124704 10556071936 1098720496 11.825 98 43 86 2 1177680112 70243536 8716080
03:47:31 30558 8192000000 10077310816 10518212608 1112996032 11.961 99 46 88 2 1156226720 34373824 8856864
03:49:03 20481 8192000000 10152410512 10588192768 1142691344 12.241 100 58 112 2 1236598176 82127776 11779056
03:50:26 43677 8192000000 10087690352 10571534336 1126735920 12.091 100 23 46 1 1206862784 75554336 4572528
03:52:15 67155 8192000000 10081856016 10541764608 1110306560 11.935 98 30 60 0 1166098480 49371152 6420768
03:54:25 65099 8192000000 10077416464 10553798656 1111859760 11.950 98 14 28 0 1194007184 79519312 2628112
03:56:32 68127 8192000000 10124496848 10589515776 1122294384 12.049 99 25 50 1 1172569136 45051728 5223024
03:58:41 65605 8192000000 10075754256 10542702592 1124892640 12.073 99 16 32 1 1179708624 51392416 3423568
04:00:47 57723 8192000000 10048208128 10521010176 1106760400 11.902 98 5 10 0 1127956656 20551824 644432
04:02:45 60823 8192000000 10092962112 10563067904 1124631232 12.071 99 21 42 1 1187419008 58108384 4679392
04:04:46 31933 8192000000 10122582352 10545848320 1120466240 12.031 98 52 104 0 1193333040 61796032 11070768
04:06:19 35967 8192000000 10137358384 10583220224 1111589840 11.947 98 25 48 2 1223432896 106577360 5265696
04:08:02 47050 8192000000 10089652496 10567073792 1079144096 11.639 96 8 14 1 1155511616 74662048 1705472
04:09:52 47455 8192000000 10131029376 10581458944 1121972800 12.046 99 45 90 1 1215272928 84408992 8891136
04:11:41 4101 8192000000 10065533072 10510098432 1129898784 12.120 100 65 116 7 1163342976 20386656 13057536
04:12:47 11091 8192000000 10127614336 10556661760 1139490544 12.211 100 63 126 0 1226654160 74853168 12310448
04:14:00 97737 8192000000 10127065584 10594959360 1142249632 12.237 100 28 56 0 1238634608 90015648 6369328
04:16:39 18403 8192000000 10042821968 10488811520 1104033424 11.876 97 29 52 2 1136000512 25958704 6008384
04:17:58 39374 8192000000 10071390608 10518188032 1114471856 11.975 99 17 35 0 1170125760 51870304 3783600
04:19:39 63758 8192000000 10044576304 10520236032 1105194256 11.887 98 7 15 0 1163989728 57320608 1474864
04:22:04 62989 8192000000 10103861424 10558685184 1112811104 11.959 98 28 56 3 1192068192 73083584 6173504
04:24:13 54033 8192000000 10079295280 10516430848 1061741488 11.473 93 35 69 2 1118105104 49046512 7317104
04:26:11 91662 8192000000 10093866512 10559176704 1104793776 11.883 98 17 34 0 1179330128 71333376 3202976
04:28:43 32440 8192000000 10136939216 10585714688 1132358048 12.144 100 39 78 1 1227403568 86768128 8277392
04:30:27 52273 8192000000 10117888432 10616225792 1111806752 11.950 98 33 66 1 1225763152 107054688 6901712
04:32:20 29584 8192000000 10109322592 10563244032 1111022352 11.942 98 44 88 1 1159554448 39762688 8769408
04:33:51 103808 8192000000 10059352752 10524033024 1091037600 11.753 96 35 69 0 1149517840 51748752 6731488
04:36:36 47817 8192000000 9841774768 10289594368 938926128 10.282 83 21 43 0 979733024 36550448 4256448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-06-2022 04:40:08 to 06-06-2022 04:40:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-06-2022 04:40:25 to 06-06-2022 04:40:27.

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          226485
MON$OLDEST_ACTIVE               226486
MON$OLDEST_SNAPSHOT             226486
MON$NEXT_TRANSACTION            226486
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                       2083040
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
:185: 
:186: mscs-test-161.budzdorov.ru    Mon Jun  6 04:37:44 2022
:187:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:188: 
:189: 
:190: mscs-test-161.budzdorov.ru    Mon Jun  6 04:38:01 2022
:191:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:192: 
--- end of diff output ---

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

06.06.22 04:40:28. 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-06-06 04:40:31 No severe PSQL-related problems occured
06.06.22 04:40:32. End of report.