Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.02.2023 04: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 14822632 kB
MemAvailable 30375800 kB
Buffers 20 kB
Cached 16788072 kB
SwapCached 3604 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-09 04:37:26
DTS_END                         2023-02-09 04:37:26.3060
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/54618
TRN_ID                          213298
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: 9202.83 23917 1104339
customer order: creation 169.70 481 20364
customer order: refuse 14.10 480 1692
order to supplier: creation 45.42 1436 5450
order to supplier: removal 3.57 194674 428
invoice (draft): creation 44.28 3233 5314
invoice (draft): removal 51.79 1096 6215
invoice accept: apply 41.48 27627 4977
invoice accept: cancel 46.98 1255 5637
customer reserve: creation 3870.16 262 464419
customer reserve: removal 4621.41 117 554569
realization accept: apply 82.52 295 9902
realization accept: cancel 84.64 189 10157
payment from customer: creation 58.28 178 6994
payment from customer: removal 5.57 142 668
payment to supplier: creation 46.98 211 5638
payment to supplier: removal 3.42 133 410
service: total inventory turnovers 3.93 4758 471
service: total monetary turnovers 7.67 440 920
service: aggregate perf. data 0.91 17812 109
service: refresh index statistics 0.04 223519 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-02-2023 04:38:21 to 09-02-2023 04:38:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6459
TEST_TIME 2 4989
TEST_TIME 3 4551
TEST_TIME 4 5016
TEST_TIME 5 5323
TEST_TIME 6 5579
TEST_TIME 7 6085
TEST_TIME 8 6271
TEST_TIME 9 6050
TEST_TIME 10 6056
TEST_TIME 11 6234
TEST_TIME 12 6295
TEST_TIME 13 6212
TEST_TIME 14 6108
TEST_TIME 15 6078
TEST_TIME 16 6219
TEST_TIME 17 6386
TEST_TIME 18 6612
TEST_TIME 19 6747
TEST_TIME 20 6823
TEST_TIME 21 7088
TEST_TIME 22 7245
TEST_TIME 23 7325
TEST_TIME 24 7412
TEST_TIME 25 7431
TEST_TIME 26 7494
TEST_TIME 27 7527
TEST_TIME 28 7571
TEST_TIME 29 8020
TEST_TIME 30 8491
TEST_TIME 31 8483
TEST_TIME 32 8688
TEST_TIME 33 8833
TEST_TIME 34 9394
TEST_TIME 35 9416
TEST_TIME 36 9756
TEST_TIME 37 10140
TEST_TIME 38 10446
TEST_TIME 39 10600
TEST_TIME 40 10756
TEST_TIME 41 10565
TEST_TIME 42 10553
TEST_TIME 43 10524
TEST_TIME 44 10539
TEST_TIME 45 10450
TEST_TIME 46 10452
TEST_TIME 47 10434
TEST_TIME 48 10314
TEST_TIME 49 10110
TEST_TIME 50 9956
TEST_TIME 51 9854
TEST_TIME 52 9874
TEST_TIME 53 9756
TEST_TIME 54 9666
TEST_TIME 55 9643
TEST_TIME 56 9585
TEST_TIME 57 9492
TEST_TIME 58 9563
TEST_TIME 59 9596
TEST_TIME 60 9581
TEST_TIME 61 9588
TEST_TIME 62 9669
TEST_TIME 63 9587
TEST_TIME 64 9468
TEST_TIME 65 9634
TEST_TIME 66 9683
TEST_TIME 67 9640
TEST_TIME 68 9662
TEST_TIME 69 9717
TEST_TIME 70 9651
TEST_TIME 71 9671
TEST_TIME 72 9705
TEST_TIME 73 9656
TEST_TIME 74 9695
TEST_TIME 75 9710
TEST_TIME 76 9718
TEST_TIME 77 9778
TEST_TIME 78 9734
TEST_TIME 79 9617
TEST_TIME 80 9611
TEST_TIME 81 9567
TEST_TIME 82 9497
TEST_TIME 83 9460
TEST_TIME 84 9451
TEST_TIME 85 9474
TEST_TIME 86 9432
TEST_TIME 87 9411
TEST_TIME 88 9346
TEST_TIME 89 9314
TEST_TIME 90 9343
TEST_TIME 91 9310
TEST_TIME 92 9319
TEST_TIME 93 9357
TEST_TIME 94 9324
TEST_TIME 95 9359
TEST_TIME 96 9403
TEST_TIME 97 9404
TEST_TIME 98 9460
TEST_TIME 99 9452
TEST_TIME 100 9479
TEST_TIME 101 9477
TEST_TIME 102 9443
TEST_TIME 103 9513
TEST_TIME 104 9446
TEST_TIME 105 9396
TEST_TIME 106 9360
TEST_TIME 107 9344
TEST_TIME 108 9346
TEST_TIME 109 9291
TEST_TIME 110 9254
TEST_TIME 111 9202
TEST_TIME 112 9264
TEST_TIME 113 9257
TEST_TIME 114 9243
TEST_TIME 115 9264
TEST_TIME 116 9232
TEST_TIME 117 9230
TEST_TIME 118 9245
TEST_TIME 119 9291
TEST_TIME 120 9225

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-02-2023 04:38:25 to 09-02-2023 04:38:28.

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 1532499 1532499 0 0.00 0 17861 15 0 0
doc_list_biud 1532491 1532491 0 0.00 0 22600 48 0 0
sp_add_invoice_to_stock 5096 4977 119 2.33 35 236497 27628 0 119
sp_cancel_adding_invoice 5637 5637 0 0.00 7 77558 1256 0 0
sp_cancel_client_order 1692 1692 0 0.00 6 11055 480 0 0
sp_cancel_customer_reserve 554569 554569 0 0.00 1 22614 117 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 4 3309 143 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 4 2798 134 0 0
sp_cancel_supplier_invoice 6221 6215 6 0.09 3 13330 1097 0 6
sp_cancel_supplier_order 428 428 0 0.00 12 1624776 194675 0 0
sp_cancel_write_off 10157 10157 0 0.00 2 17279 190 0 0
sp_client_order 20364 20364 0 0.00 6 26188 481 0 0
sp_customer_reserve 464419 464419 0 0.00 0 104560 263 0 0
sp_fill_shopping_cart 31116 31116 0 0.00 1 22167 86 0 0
sp_fill_shopping_cart_clo_res 464400 464400 0 0.00 0 21640 20 0 0
sp_get_clo_for_invoice 4943 4943 0 0.00 0 30565 259 0 0
sp_kill_cost_storno 16872 16872 0 0.00 0 15224 86 0 0
sp_kill_qstorno_ret_qs2qd 1125938 1125938 0 0.00 0 22414 50 0 0
sp_kill_qty_storno 593538 593538 0 0.00 0 22599 115 0 0
sp_lock_selected_doc 20367 20367 0 0.00 0 10796 8 0 0
sp_make_cost_storno 27476 27476 0 0.00 0 13868 86 0 0
sp_multiply_rows_for_pdistr 27474 27474 0 0.00 0 15102 42 0 0
sp_multiply_rows_for_qdistr 20363 20363 0 0.00 0 11372 171 0 0
sp_pay_from_customer 6994 6994 0 0.00 5 15757 179 0 0
sp_pay_to_supplier 5638 5638 0 0.00 6 15518 211 0 0
sp_payment_common 12631 12631 0 0.00 2 14890 46 0 0
sp_qd_handle_on_cancel_clo 1692 1692 0 0.00 1 11043 387 0 0
sp_qd_handle_on_invoice_upd_sts 10578 10578 0 0.00 0 12574 571 0 0
sp_qd_handle_on_reserve_upd_sts 9899 9899 0 0.00 0 16823 69 0 0
sp_reserve_write_off 9902 9902 0 0.00 9 20736 296 0 0
sp_supplier_invoice 5314 5314 0 0.00 10 93219 3233 0 0
sp_supplier_order 5450 5450 0 0.00 8 30778 1436 0 0
srv_aggregate_perf_data 109 109 0 0.00 2309 160976 17813 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 790 117978 32488 0 0
srv_find_qd_qs_mism 1056172 1056172 0 0.00 0 11941 11 0 0
srv_make_invnt_saldo 471 471 0 0.00 62 72981 4758 0 0
srv_make_money_saldo 920 920 0 0.00 1 18556 440 0 0
srv_recalc_idx_stat 5 5 0 0.00 80760 413586 223519 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 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 4982 4982 0 0.00 0 10203 971 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 9097 391 0 0
t$perf-norm:sp_cancel_client_order 1692 1692 0 0.00 0 3180 33 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 1101 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 256 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 579 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 2331 55 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 121830 8187 0 0
t$perf-norm:sp_cancel_write_off 905 905 0 0.00 0 2600 26 0 0
t$perf-norm:sp_client_order 20364 20364 0 0.00 0 6457 39 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 2278 19 0 0
t$perf-norm:sp_pay_from_customer 6994 6994 0 0.00 0 4684 20 0 0
t$perf-norm:sp_pay_to_supplier 5638 5638 0 0.00 0 5452 23 0 0
t$perf-norm:sp_reserve_write_off 9903 9903 0 0.00 0 5153 25 0 0
t$perf-norm:sp_supplier_invoice 5314 5314 0 0.00 0 3159 29 0 0
t$perf-norm:sp_supplier_order 5450 5450 0 0.00 0 3729 34 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 191 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 6966 108 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 1185 24 0 0
t$perf-norm:srv_make_money_saldo 920 920 0 0.00 0 356 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 179 49 0 0
v_add_invoice_to_stock 10118 9880 238 2.35 0 4548 29 0 238
v_all_customers 37442 37442 0 0.00 0 96 4 0 0
v_all_suppliers 30218 30218 0 0.00 0 90 4 0 0
v_all_wares 40722 40722 0 0.00 0 107 4 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 2282 24 0 0
v_cancel_client_order 3384 3384 0 0.00 0 4059 15 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 3235 19 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 4258 18 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 2752 18 0 0
v_cancel_supplier_invoice 764 752 12 1.57 0 3572 35 0 12
v_cancel_supplier_order 856 856 0 0.00 0 1724 14 0 0
v_cancel_write_off 1810 1810 0 0.00 0 5442 33 0 0
v_max_id_clo_ord 5448 5448 0 0.00 0 528 5 0 0
v_max_id_clo_res 974 974 0 0.00 0 1759 12 0 0
v_max_id_ord_sup 5306 5306 0 0.00 0 6937 8 0 0
v_max_non_paid_invoice 1282 1282 0 0.00 0 2408 11 0 0
v_max_non_paid_realizn 3526 3526 0 0.00 0 3051 8 0 0
v_min_id_clo_ord 5448 5448 0 0.00 0 2161 9 0 0
v_min_id_clo_res 974 974 0 0.00 0 6245 15 0 0
v_min_id_ord_sup 5306 5306 0 0.00 0 5920 15 0 0
v_min_non_paid_invoice 5637 5637 0 0.00 0 11697 18 0 0
v_min_non_paid_realizn 6994 6994 0 0.00 0 14887 18 0 0
v_our_firm 10216 10216 0 0.00 0 103 5 0 0
v_reserve_write_off 19798 19798 0 0.00 0 5001 14 0 0
x_lock_depdocs_on_canc_invoice 11476 11476 0 0.00 0 24225 148 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 5183 387 0 0
x_make_qstorno_1000_1200 5450 5450 0 0.00 3 30765 1268 0 0
x_make_qstorno_1200_2000 5314 5314 0 0.00 4 93147 2990 0 0
x_make_qstorno_2100_3300 464416 464416 0 0.00 0 104542 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-02-2023 04:38:29 to 09-02-2023 04:38:33.

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:30 25 8192000000 8804210512 8817659904 5757808 0.070 3 1 2 0 5858976 65392 35776
02:08:31 27096 8192000000 9729574400 10030899200 812453440 9.022 100 86 173 0 840399360 7062800 20883120
02:10:03 23458 8192000000 9777483712 10086166528 847048000 9.370 100 89 178 0 879925888 11419008 21458880
02:11:34 16925 8192000000 9797034256 10119720960 869615296 9.596 100 82 164 0 911188720 22432000 19141424
02:12:55 13137 8192000000 9877574912 10208133120 928997232 10.185 100 82 162 0 986491264 40051888 17442144
02:14:16 6061 8192000000 9920262608 10263236608 959772736 10.487 100 82 159 0 1025330064 48826928 16730400
02:15:24 2878 8192000000 9962472160 10312495104 992067168 10.802 100 89 169 1 1078644208 69498368 17078672
02:16:30 1779 8192000000 9979314144 10341486592 1013123104 11.006 100 84 166 0 1093943584 63699584 17120896
02:17:35 758 8192000000 10024311504 10391126016 1026922752 11.139 100 96 182 2 1141897696 96453968 18520976
02:18:39 1258 8192000000 10007137984 10382483456 1048435584 11.346 100 92 174 0 1131278224 64218800 18623840
02:19:41 1049 8192000000 10038963872 10414252032 1059696128 11.454 100 94 186 2 1159502480 81149568 18656784
02:20:43 887 8192000000 10064773920 10446499840 1069222864 11.545 100 99 192 0 1182388576 95042672 18123040
02:21:45 1127 8192000000 10025762336 10404216832 1077706672 11.626 100 98 191 3 1147865280 50550080 19608528
02:22:48 996 8192000000 10043871008 10428174336 1083696144 11.683 100 91 181 2 1166205056 63769776 18739136
02:23:50 1910 8192000000 10056003504 10444926976 1087355184 11.718 100 78 139 7 1173433008 71413584 14664240
02:24:54 1498 8192000000 10066321952 10452328448 1095091584 11.791 100 90 176 1 1184086272 70842112 18152576
02:25:57 1080 8192000000 10058424336 10443390976 1100150080 11.839 100 93 177 0 1179543792 61557920 17835792
02:27:00 1309 8192000000 10064206080 10459324416 1102911584 11.865 100 77 146 1 1187602720 69247888 15443248
02:28:03 1035 8192000000 10067890192 10460659712 1105647584 11.891 100 85 164 0 1188150400 65534592 16968224
02:29:06 2126 8192000000 10058673200 10450911232 1098562880 11.824 100 76 136 2 1181061936 69097088 13401968
02:30:09 2646 8192000000 10052684016 10455515136 1100655424 11.844 100 73 141 1 1177836976 63255744 13925808
02:31:13 880 8192000000 9985639040 10364563456 1054179984 11.401 98 88 170 0 1108031824 35867088 17984752
02:32:25 1008 8192000000 10033736912 10419994624 1061565792 11.471 99 89 172 1 1153428992 74166304 17696896
02:33:27 1151 8192000000 10011397184 10394533888 1065942464 11.513 100 92 174 0 1140326640 54393360 19990816
02:34:29 1338 8192000000 10023680000 10400833536 1046992240 11.332 100 94 180 2 1147479840 80954240 19533360
02:35:31 1119 8192000000 10013656448 10392485888 1053703248 11.396 99 93 174 2 1139550304 67377024 18470032
02:36:33 1148 8192000000 9979956016 10355757056 1048311600 11.344 98 91 179 0 1104620896 36517792 19791504
02:37:35 24766 8192000000 9966243280 10356998144 1029407792 11.163 97 47 86 0 1071504144 33824032 8272320
02:39:09 1350 8192000000 10005689408 10378211328 1052211968 11.382 98 92 162 3 1121985376 51644576 18128832
02:40:23 11262 8192000000 10056044736 10440069120 1079433088 11.642 100 74 135 2 1175209008 81674688 14101232
02:41:37 3132 8192000000 10065693808 10453417984 1077672576 11.625 99 71 128 1 1184211088 93117440 13421072
02:42:44 6049 8192000000 10115294720 10504445952 1097281856 11.812 100 65 128 0 1235338736 124462288 13594592
02:43:55 2118 8192000000 10160011424 10560987136 1110582784 11.938 100 74 140 1 1274021824 147859616 15579424
02:45:01 964 8192000000 10182610272 10584006656 1137982336 12.197 100 96 190 2 1302756960 143984288 20790336
02:46:05 10621 8192000000 10198373248 10604265472 1145283408 12.265 100 69 135 1 1309473472 149551104 14638960
02:47:29 2174 8192000000 10232960432 10628902912 1156528176 12.371 100 76 147 1 1346026288 173363936 16134176
02:48:36 1144 8192000000 10267976720 10673664000 1166799216 12.467 100 95 179 1 1379957008 192976384 20181408
02:49:39 1188 8192000000 10282679472 10684252160 1155796144 12.364 100 92 171 0 1394339008 219874848 18668016
02:50:45 1435 8192000000 10327388336 10728878080 1176886352 12.561 100 86 166 0 1441691392 245988592 18816448
02:51:57 984 8192000000 10385249248 10786611200 1182387888 12.612 100 97 191 2 1495545888 290737728 22420272
02:53:04 1203 8192000000 10417101824 10837209088 1186905136 12.655 100 94 183 0 1530112176 321547312 21659728
02:54:09 1850 8192000000 10441780320 10872197120 1193699040 12.718 100 93 176 2 1551001056 336511168 20790848
02:55:16 2239 8192000000 10438683840 10868293632 1195604768 12.735 100 90 179 0 1546965392 329159712 22200912
02:56:23 1695 8192000000 10472355216 10913861632 1201909264 12.794 100 89 176 1 1576922176 352835840 22177072
02:57:30 783 8192000000 10471380688 10908864512 1208131440 12.852 100 94 183 0 1575006000 345167952 21706608
02:58:37 1202 8192000000 10473678112 10926727168 1214528896 12.911 100 91 175 0 1581111232 345619328 20963008
02:59:41 787 8192000000 10500820560 10911723520 1213308528 12.900 100 92 176 2 1603207776 369041888 20857360
03:00:45 1121 8192000000 10533312464 10961580032 1210684016 12.875 100 95 185 1 1641409136 409976016 20749104
03:01:51 1336 8192000000 10562817376 10983936000 1210644176 12.875 100 96 188 1 1667494160 434554592 22295392
03:02:58 1548 8192000000 10602956320 11025371136 1208555728 12.856 100 97 194 0 1707190528 474925648 23709152
03:04:02 1249 8192000000 10623247040 11055996928 1205580720 12.828 100 97 192 0 1725474112 495811888 24081504
03:05:10 805 8192000000 10629389856 11105595392 1208155648 12.852 100 92 182 1 1731895472 500895504 22844320
03:06:16 802 8192000000 10634438000 11109507072 1207830096 12.849 100 83 161 0 1738927392 510404000 20693296
03:07:20 7896 8192000000 10591412448 11113734144 1212593104 12.893 100 62 121 0 1696508016 468146832 15768080
03:08:30 18949 8192000000 10601390208 11051945984 1181916240 12.608 99 50 100 0 1696859920 501737024 13206656
03:10:21 16613 8192000000 10523140080 11002998784 1170664432 12.503 99 46 90 0 1643344992 460753024 11927536
03:11:46 20572 8192000000 10409392768 10878324736 1174520592 12.539 100 27 53 0 1529193856 348106896 6566368
03:13:08 24564 8192000000 10343293632 10810535936 1159154016 12.395 100 23 46 1 1473090160 307558192 6377952
03:14:54 51059 8192000000 10177464848 10671661056 1147386512 12.285 99 19 38 0 1306357584 154988944 3982128
03:16:52 66820 8192000000 10063578720 10543321088 1110621968 11.938 97 12 24 0 1180709792 66810208 3277616
03:19:08 49044 8192000000 10089946560 10548051968 1125893408 12.083 98 15 30 0 1177476864 48889056 2694400
03:21:04 89117 8192000000 10073114624 10531688448 1090041808 11.743 96 20 39 0 1142558768 48873136 3643824
03:23:34 117977 8192000000 10061686752 10529345536 1116997056 11.999 99 16 32 0 1173634976 53444752 3193168
03:26:33 71899 8192000000 10053556768 10485809152 1096614288 11.806 97 42 76 2 1122351280 18038304 7698688
03:28:48 10991 8192000000 10111117584 10527670272 1124325264 12.068 100 79 140 2 1191421728 52175824 14920640
03:30:01 89133 8192000000 10063455872 10495680512 1083980512 11.685 96 24 46 1 1130409088 42180976 4247600
03:32:31 19750 8192000000 10119730752 10553786368 1113972464 11.970 100 35 70 0 1198906000 76873376 8060160
03:33:56 8968 8192000000 10152083200 10582028288 1130896016 12.130 100 50 93 0 1251050032 109728384 10425632
03:35:12 22818 8192000000 10206133856 10636304384 1128889776 12.111 100 68 136 0 1300856416 156900016 15066624
03:36:39 33707 8192000000 10139312752 10630672384 1121464992 12.041 99 38 69 0 1253158096 124406176 7286928
03:39:03 51221 8192000000 10139328528 10641457152 1137692784 12.194 100 17 34 0 1225872912 84582272 3597856
03:40:55 33116 8192000000 10185875088 10644992000 1148568336 12.296 100 12 24 0 1277590432 126199616 2822480
03:42:28 76935 8192000000 10090295792 10553090048 1124939024 12.074 98 15 29 0 1207286064 79558800 2788240
03:44:59 18446 8192000000 10117203536 10572247040 1140813152 12.223 100 51 99 0 1206620144 55331360 10475632
03:46:18 55417 8192000000 10103248000 10593599488 1143085728 12.245 100 26 52 0 1213611840 64694816 5831296
03:48:15 21490 8192000000 10129276512 10557960192 1135420224 12.172 99 57 106 1 1228985696 81784592 11780880
03:49:38 98746 8192000000 10127808272 10564116480 1100368336 11.841 95 28 56 0 1158425936 52716448 5341152
03:52:19 88881 8192000000 10144371072 10609242112 1093238096 11.773 96 11 22 0 1172234144 76646592 2349456
03:54:49 64879 8192000000 10102368272 10546651136 1100568160 11.843 97 41 80 1 1160440768 51959472 7913136
03:56:54 50647 8192000000 10109964944 10575454208 1117775168 12.006 99 50 98 0 1186904432 59020176 10109088
03:59:14 24787 8192000000 10108196560 10545242112 1143480288 12.248 100 39 77 1 1210197792 58606848 8110656
04:00:40 31603 8192000000 10121620544 10603180032 1118178848 12.010 98 27 54 0 1211972000 88464544 5328608
04:02:19 35937 8192000000 10063178576 10503016448 1115154016 11.981 99 30 60 0 1162599536 40990608 6454912
04:04:00 66563 8192000000 10088454032 10560843776 1101904288 11.856 97 13 26 0 1169304880 64705072 2695520
04:06:06 55380 8192000000 10098310368 10565296128 1124173328 12.066 99 10 20 0 1189601888 62440832 2987728
04:08:03 33816 8192000000 10059821056 10502795264 1109009344 11.923 98 37 66 0 1149106752 33827568 6269840
04:09:46 39105 8192000000 10081275264 10573799424 1111424704 11.946 98 29 58 0 1184898160 67250000 6223456
04:11:26 50752 8192000000 10118452736 10574442496 1112583280 11.957 98 25 50 0 1199860704 81735824 5541600
04:13:17 95899 8192000000 10096379232 10526601216 1070030496 11.552 95 18 35 1 1123753760 49990640 3732624
04:15:58 49339 8192000000 10079373744 10500636672 1109878832 11.931 98 49 89 2 1145280976 26204256 9197888
04:17:49 54607 8192000000 10070125328 10524418048 1096855584 11.808 97 19 38 0 1138146240 37592160 3698496
04:19:47 9915 8192000000 10096479536 10522247168 1112454992 11.956 100 63 120 2 1183891696 57844016 13592688
04:20:58 105812 8192000000 10081306960 10520821760 1100081056 11.838 98 25 50 0 1162981840 58337856 4562928
04:23:44 39217 8192000000 10099024320 10532782080 1122421136 12.050 100 32 64 0 1190611408 61616160 6574112
04:25:25 90498 8192000000 10110364144 10587492352 1111431984 11.946 98 14 27 0 1179819568 65281184 3106400
04:28:11 75455 8192000000 10142466096 10597330944 1113123392 11.962 99 24 46 1 1225959008 106667600 6168016
04:30:28 36477 8192000000 10061276320 10520072192 1112849072 11.959 99 24 48 0 1146289696 28481744 4958880
04:32:10 37623 8192000000 10092272000 10543915008 1131457392 12.135 100 20 40 0 1190255296 53968352 4829552
04:33:48 43271 8192000000 10125060336 10574471168 1109396800 11.927 98 27 54 0 1198943344 84037456 5509088
04:35:33 39649 8192000000 10145192800 10599362560 1125315680 12.077 99 30 60 0 1229410304 97194832 6899792
04:37:14 24676 8192000000 9113151504 9381314560 249851168 2.959 22 6 12 1 267263920 16246304 1166448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 09-02-2023 04:38:35 to 09-02-2023 04:38:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 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 09-02-2023 04:38:53 to 09-02-2023 04:38:55.

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          214038
MON$OLDEST_ACTIVE               214039
MON$OLDEST_SNAPSHOT             214039
MON$NEXT_TRANSACTION            214039
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                       2081696
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             761
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

09.02.23 04:38:56. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-09 04:38:59 No severe PSQL-related problems occured
09.02.23 04:39:00. End of report.