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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-20 07:46:36
DTS_END                         2022-10-20 07:46:36.6180
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/57130
TRN_ID                          222686
ATT_ID                          572
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: 9008.73 26323 1081047
customer order: creation 176.55 476 21186
customer order: refuse 15.32 426 1838
order to supplier: creation 46.00 1418 5520
order to supplier: removal 3.49 158389 419
invoice (draft): creation 46.73 3306 5608
invoice (draft): removal 47.98 1021 5757
invoice accept: apply 41.98 25349 5038
invoice accept: cancel 43.74 1275 5249
customer reserve: creation 3952.15 236 474258
customer reserve: removal 4345.98 99 521517
realization accept: apply 85.50 293 10260
realization accept: cancel 72.65 174 8718
payment from customer: creation 60.78 182 7293
payment from customer: removal 5.71 141 685
payment to supplier: creation 48.82 190 5858
payment to supplier: removal 3.32 150 398
service: total inventory turnovers 3.68 4607 442
service: total monetary turnovers 7.35 394 882
service: aggregate perf. data 0.96 17897 115
service: refresh index statistics 0.05 310432 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2022 07:48:04 to 20-10-2022 07:48:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5520
TEST_TIME 2 6258
TEST_TIME 3 5738
TEST_TIME 4 5539
TEST_TIME 5 5318
TEST_TIME 6 5191
TEST_TIME 7 6009
TEST_TIME 8 6062
TEST_TIME 9 6668
TEST_TIME 10 7007
TEST_TIME 11 7197
TEST_TIME 12 7339
TEST_TIME 13 7249
TEST_TIME 14 7307
TEST_TIME 15 7395
TEST_TIME 16 7289
TEST_TIME 17 7294
TEST_TIME 18 7439
TEST_TIME 19 7776
TEST_TIME 20 7603
TEST_TIME 21 7814
TEST_TIME 22 7711
TEST_TIME 23 7697
TEST_TIME 24 8079
TEST_TIME 25 8389
TEST_TIME 26 8530
TEST_TIME 27 8587
TEST_TIME 28 8635
TEST_TIME 29 8769
TEST_TIME 30 9028
TEST_TIME 31 9079
TEST_TIME 32 9167
TEST_TIME 33 9481
TEST_TIME 34 9906
TEST_TIME 35 10177
TEST_TIME 36 10649
TEST_TIME 37 10448
TEST_TIME 38 10634
TEST_TIME 39 10668
TEST_TIME 40 10691
TEST_TIME 41 10543
TEST_TIME 42 10455
TEST_TIME 43 10453
TEST_TIME 44 10546
TEST_TIME 45 10434
TEST_TIME 46 10591
TEST_TIME 47 10496
TEST_TIME 48 10401
TEST_TIME 49 10280
TEST_TIME 50 10239
TEST_TIME 51 10205
TEST_TIME 52 10069
TEST_TIME 53 9973
TEST_TIME 54 9938
TEST_TIME 55 9924
TEST_TIME 56 9860
TEST_TIME 57 9774
TEST_TIME 58 9679
TEST_TIME 59 9722
TEST_TIME 60 9640
TEST_TIME 61 9705
TEST_TIME 62 9703
TEST_TIME 63 9634
TEST_TIME 64 9555
TEST_TIME 65 9550
TEST_TIME 66 9505
TEST_TIME 67 9473
TEST_TIME 68 9371
TEST_TIME 69 9377
TEST_TIME 70 9459
TEST_TIME 71 9454
TEST_TIME 72 9472
TEST_TIME 73 9440
TEST_TIME 74 9436
TEST_TIME 75 9542
TEST_TIME 76 9540
TEST_TIME 77 9525
TEST_TIME 78 9524
TEST_TIME 79 9584
TEST_TIME 80 9611
TEST_TIME 81 9615
TEST_TIME 82 9629
TEST_TIME 83 9620
TEST_TIME 84 9528
TEST_TIME 85 9510
TEST_TIME 86 9441
TEST_TIME 87 9374
TEST_TIME 88 9398
TEST_TIME 89 9347
TEST_TIME 90 9373
TEST_TIME 91 9337
TEST_TIME 92 9317
TEST_TIME 93 9306
TEST_TIME 94 9292
TEST_TIME 95 9261
TEST_TIME 96 9244
TEST_TIME 97 9262
TEST_TIME 98 9305
TEST_TIME 99 9321
TEST_TIME 100 9372
TEST_TIME 101 9364
TEST_TIME 102 9399
TEST_TIME 103 9369
TEST_TIME 104 9373
TEST_TIME 105 9435
TEST_TIME 106 9394
TEST_TIME 107 9329
TEST_TIME 108 9302
TEST_TIME 109 9259
TEST_TIME 110 9279
TEST_TIME 111 9245
TEST_TIME 112 9205
TEST_TIME 113 9150
TEST_TIME 114 9113
TEST_TIME 115 9122
TEST_TIME 116 9080
TEST_TIME 117 9067
TEST_TIME 118 9083
TEST_TIME 119 9074
TEST_TIME 120 9033

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-10-2022 07:48:08 to 20-10-2022 07:48:11.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1517853 1517853 0 0.00 0 15434 13 0 0
doc_list_biud 1517834 1517834 0 0.00 0 22166 40 0 0
sp_add_invoice_to_stock 5179 5038 141 2.72 19 185208 25350 0 141
sp_cancel_adding_invoice 5249 5249 0 0.00 7 116348 1276 0 0
sp_cancel_client_order 1838 1838 0 0.00 6 8610 427 0 0
sp_cancel_customer_reserve 521517 521517 0 0.00 1 22170 99 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 4 4713 141 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 3455 150 0 0
sp_cancel_supplier_invoice 5773 5757 16 0.27 4 14386 1021 0 16
sp_cancel_supplier_order 419 419 0 0.00 8 1509068 158389 0 0
sp_cancel_write_off 8718 8718 0 0.00 2 10455 174 0 0
sp_client_order 21186 21186 0 0.00 6 29257 477 0 0
sp_customer_reserve 474258 474258 0 0.00 0 113211 236 0 0
sp_fill_shopping_cart 32307 32307 0 0.00 1 16143 83 0 0
sp_fill_shopping_cart_clo_res 474228 474228 0 0.00 0 15298 18 0 0
sp_get_clo_for_invoice 4992 4992 0 0.00 0 35267 246 0 0
sp_kill_cost_storno 15050 15050 0 0.00 0 5929 79 0 0
sp_kill_qstorno_ret_qs2qd 1057928 1057928 0 0.00 0 21772 43 0 0
sp_kill_qty_storno 558750 558750 0 0.00 0 22166 100 0 0
sp_lock_selected_doc 21034 21034 0 0.00 0 9502 8 0 0
sp_make_cost_storno 28403 28403 0 0.00 0 12085 79 0 0
sp_multiply_rows_for_pdistr 28403 28403 0 0.00 0 7626 36 0 0
sp_multiply_rows_for_qdistr 21186 21186 0 0.00 0 11413 163 0 0
sp_pay_from_customer 7293 7293 0 0.00 5 17217 182 0 0
sp_pay_to_supplier 5858 5858 0 0.00 7 17031 190 0 0
sp_payment_common 13151 13151 0 0.00 2 17197 50 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 1 8472 355 0 0
sp_qd_handle_on_invoice_upd_sts 10241 10241 0 0.00 0 17138 560 0 0
sp_qd_handle_on_reserve_upd_sts 10260 10260 0 0.00 0 13329 69 0 0
sp_reserve_write_off 10260 10260 0 0.00 8 13444 293 0 0
sp_supplier_invoice 5608 5608 0 0.00 9 98111 3307 0 0
sp_supplier_order 5520 5520 0 0.00 8 31889 1418 0 0
srv_aggregate_perf_data 115 115 0 0.00 2182 166685 17898 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 853 144504 31534 0 0
srv_find_qd_qs_mism 1031193 1031193 0 0.00 0 15432 9 0 0
srv_make_invnt_saldo 442 442 0 0.00 35 139919 4608 0 0
srv_make_money_saldo 882 882 0 0.00 1 13736 395 0 0
srv_recalc_idx_stat 6 6 0 0.00 41755 650724 310432 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5038 5038 0 0.00 1 9961 945 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 2 8023 542 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 4615 28 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 1196 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 1446 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 753 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 540 26 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 96648 7125 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 1870 20 0 0
t$perf-norm:sp_client_order 21186 21186 0 0.00 0 10210 37 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 10258 34 0 0
t$perf-norm:sp_pay_from_customer 7293 7293 0 0.00 0 3249 17 0 0
t$perf-norm:sp_pay_to_supplier 5858 5858 0 0.00 0 9129 22 0 0
t$perf-norm:sp_reserve_write_off 10260 10260 0 0.00 0 13868 24 0 0
t$perf-norm:sp_supplier_invoice 5608 5608 0 0.00 0 2420 28 0 0
t$perf-norm:sp_supplier_order 5520 5520 0 0.00 0 10734 33 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 410 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 1237 24 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 490 11 0 0
t$perf-norm:srv_make_money_saldo 882 882 0 0.00 0 3614 11 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 15 237 89 0 0
v_add_invoice_to_stock 10266 9984 282 2.74 0 5626 28 0 282
v_all_customers 39004 39004 0 0.00 0 75 4 0 0
v_all_suppliers 31086 31086 0 0.00 0 83 4 0 0
v_all_wares 42372 42372 0 0.00 0 107 4 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 217 15 0 0
v_cancel_client_order 3676 3676 0 0.00 0 2504 11 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 2224 15 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 5731 20 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 1224 12 0 0
v_cancel_supplier_invoice 772 740 32 4.14 0 8319 47 0 32
v_cancel_supplier_order 838 838 0 0.00 0 2844 21 0 0
v_cancel_write_off 1940 1940 0 0.00 0 10377 30 0 0
v_max_id_clo_ord 5520 5520 0 0.00 0 114 5 0 0
v_max_id_clo_res 974 974 0 0.00 0 2074 17 0 0
v_max_id_ord_sup 5601 5601 0 0.00 0 11826 9 0 0
v_max_non_paid_invoice 1436 1436 0 0.00 0 999 10 0 0
v_max_non_paid_realizn 3669 3669 0 0.00 0 2323 8 0 0
v_min_id_clo_ord 5520 5520 0 0.00 0 7599 12 0 0
v_min_id_clo_res 974 974 0 0.00 0 1963 14 0 0
v_min_id_ord_sup 5601 5601 0 0.00 0 7205 10 0 0
v_min_non_paid_invoice 5858 5858 0 0.00 0 16985 21 0 0
v_min_non_paid_realizn 7293 7293 0 0.00 0 17168 21 0 0
v_our_firm 10616 10616 0 0.00 0 68 5 0 0
v_reserve_write_off 20520 20520 0 0.00 0 9586 15 0 0
x_lock_depdocs_on_canc_invoice 10636 10636 0 0.00 0 11571 124 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 3839 342 0 0
x_make_qstorno_1000_1200 5520 5520 0 0.00 3 31866 1246 0 0
x_make_qstorno_1200_2000 5608 5608 0 0.00 4 98051 3086 0 0
x_make_qstorno_2100_3300 474258 474258 0 0.00 0 113208 205 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 20-10-2022 07:48:12 to 20-10-2022 07:48:17.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:16:40 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:17:42 21825 8192000000 9712315536 10012758016 789581024 8.791 100 87 174 0 818287424 7437648 21268752
05:19:11 19078 8192000000 9759830464 10067267584 835003664 9.250 100 84 168 0 867231488 12128384 20099440
05:20:33 10907 8192000000 9801385984 10117169152 864965616 9.550 100 83 166 0 906155888 22271888 18918384
05:21:55 9314 8192000000 9862558144 10194051072 917216976 10.069 100 80 158 0 976558880 41493840 17848064
05:23:09 3067 8192000000 9925612480 10268295168 967941904 10.567 100 86 170 0 1036371840 50885168 17544768
05:24:17 1578 8192000000 9987373296 10341859328 996776656 10.847 100 94 186 3 1105838720 89878736 19183328
05:25:22 1124 8192000000 9999865808 10358824960 1017274352 11.046 100 93 183 0 1115398656 79705280 18419024
05:26:25 2325 8192000000 10003874592 10368802816 1032897504 11.196 100 91 163 0 1123064160 74449920 15716736
05:27:29 884 8192000000 10025881184 10398355456 1052707600 11.387 100 94 184 1 1145740928 74386096 18647232
05:28:31 971 8192000000 10045448576 10423001088 1068830512 11.541 100 92 177 1 1166034096 80031008 17172576
05:29:34 975 8192000000 10034083248 10420043776 1069285824 11.545 100 74 142 2 1157179440 73608080 14285536
05:30:38 5195 8192000000 10025539632 10409541632 1075238912 11.602 100 76 142 2 1142832800 53451264 14142624
05:31:45 978 8192000000 10020257872 10406264832 1083621008 11.682 100 93 176 1 1144549200 43178672 17749520
05:32:46 990 8192000000 10044816448 10430517248 1087354992 11.718 100 94 180 2 1165050016 59846912 17848112
05:33:48 6881 8192000000 10038027536 10426834944 1088747216 11.731 100 70 139 2 1166358560 63349040 14262304
05:34:56 1011 8192000000 10062532416 10453647360 1092926608 11.770 100 93 176 0 1178874064 68262112 17685344
05:35:57 1699 8192000000 10082611824 10473693184 1099619024 11.834 100 81 148 0 1205977760 91814016 14544720
05:37:00 1041 8192000000 10054856944 10444312576 1100555136 11.843 100 94 176 0 1176032656 57127504 18350016
05:38:02 4308 8192000000 10041027712 10431549440 1091574704 11.758 100 78 155 0 1174495584 67115152 15805728
05:39:07 2293 8192000000 10043248880 10431139840 1100650000 11.844 100 79 142 4 1165567664 50088752 14828912
05:40:11 1175 8192000000 10050613024 10427408384 1075854688 11.608 100 94 178 0 1171754128 77154720 18744720
05:41:12 1375 8192000000 10005025056 10390499328 1067414928 11.527 100 71 127 1 1127440528 47313744 12711856
05:42:14 2347 8192000000 9972954496 10343092224 1038366080 11.249 100 77 141 0 1098262736 44497392 15399264
05:43:18 1081 8192000000 9959788512 10333347840 1020892528 11.081 100 91 169 2 1089163696 50468992 17802176
05:44:20 930 8192000000 9975283536 10352046080 1038551808 11.251 100 83 163 2 1095001552 40194880 16254864
05:45:22 1039 8192000000 9980289408 10359005184 1052691296 11.386 100 87 160 3 1105879648 35866432 17321920
05:46:24 2979 8192000000 9996068176 10378039296 1060615904 11.462 100 69 125 2 1117537008 44435936 12485168
05:47:33 5298 8192000000 10020419328 10396131328 1070161968 11.554 100 81 152 4 1136281120 50756752 15362400
05:48:42 3488 8192000000 10038676064 10422177792 1081020144 11.657 100 71 134 1 1162772336 68656192 13096000
05:49:49 1784 8192000000 10083834864 10463772672 1090035920 11.743 100 93 174 0 1201422640 94780704 16606016
05:50:55 7375 8192000000 10091221312 10485952512 1093282816 11.774 100 63 122 1 1210438304 103906240 13249248
05:52:10 5322 8192000000 10141498112 10539786240 1114685248 11.977 100 70 136 0 1254265744 124900176 14680320
05:53:18 17313 8192000000 10151565536 10586005504 1122790432 12.053 100 62 120 0 1276994832 140641840 13562560
05:54:38 4239 8192000000 10156402576 10560770048 1131331792 12.134 100 88 161 2 1267083952 117789024 17963136
05:55:46 1311 8192000000 10200792768 10607124480 1148844544 12.299 100 91 177 1 1310762160 142300112 19617504
05:56:51 955 8192000000 10210850528 10605846528 1159327488 12.397 100 96 188 4 1322177472 142240112 20609872
05:57:57 988 8192000000 10228773616 10631135232 1158099296 12.385 100 96 184 0 1342324400 162742592 21482512
05:59:02 3622 8192000000 10256151040 10661191680 1173126464 12.526 100 81 160 0 1363029728 171542352 18360912
06:00:09 1760 8192000000 10325804080 10733572096 1186205600 12.648 100 92 177 0 1435123328 228698112 20219616
06:01:20 1528 8192000000 10322442752 10724937728 1184747856 12.634 100 86 165 1 1428586320 224890720 18947744
06:02:26 2480 8192000000 10353654000 10763964416 1185673968 12.643 100 79 156 0 1461102304 257258000 18170336
06:03:32 1745 8192000000 10383106416 10824617984 1183381424 12.622 100 85 167 1 1490611376 287948944 19281008
06:04:37 958 8192000000 10426644928 10862657536 1190781824 12.691 100 95 185 1 1532407344 319470336 22155184
06:05:43 2282 8192000000 10416163952 10829844480 1198173264 12.759 100 86 168 0 1522320032 303914576 20232192
06:06:51 922 8192000000 10423062112 10854735872 1185712000 12.643 99 95 183 1 1531518816 323562720 22244096
06:07:56 947 8192000000 10480940352 10887688192 1195058128 12.730 100 96 190 0 1583770048 366760976 21950944
06:08:59 843 8192000000 10503779840 10930798592 1187257216 12.658 100 97 192 0 1608881888 399753520 21871152
06:10:03 1963 8192000000 10535394240 10998804480 1194005360 12.721 100 84 168 1 1641448768 426458592 20984816
06:11:07 4035 8192000000 10504264144 10950733824 1177816464 12.570 99 75 149 0 1599454736 402833984 18804288
06:12:15 8020 8192000000 10497667632 10928758784 1171899984 12.515 99 53 106 0 1593773632 408093072 13780576
06:13:31 10529 8192000000 10466741136 10915983360 1165052224 12.451 99 45 90 0 1565968400 389480480 11435696
06:14:45 5247 8192000000 10489132720 10967113728 1164843456 12.449 99 77 153 0 1581945792 398472160 18630176
06:15:52 8737 8192000000 10499346464 10957303808 1180525968 12.595 100 64 127 0 1600192256 404114720 15551568
06:17:07 19249 8192000000 10506074576 10959290368 1181160720 12.601 100 36 72 0 1625238512 435097936 8979856
06:18:28 17696 8192000000 10513882128 10952822784 1181425856 12.603 100 42 82 0 1610998352 419179280 10393216
06:19:56 28721 8192000000 10361157456 10890575872 1109832544 11.931 95 27 50 0 1457201200 339978448 7390208
06:21:31 22370 8192000000 10204220880 10711097344 1117201120 12.001 98 33 64 1 1355133728 230612144 7320464
06:22:56 57059 8192000000 10171042176 10616307712 1122011072 12.046 99 21 39 0 1248431616 121674544 4746000
06:25:07 49934 8192000000 10100551456 10584162304 1130668672 12.128 100 10 19 0 1178722832 46552256 1501904
06:26:58 19246 8192000000 10066788992 10482020352 1131485136 12.135 100 67 128 1 1156841440 11101984 14254320
06:28:23 78293 8192000000 10125470624 10602659840 1111280032 11.945 97 15 26 0 1191017936 76964336 2773568
06:30:54 64341 8192000000 10130236080 10579587072 1143033424 12.244 100 12 24 0 1220354752 75041744 2279584
06:33:06 66160 8192000000 10068551024 10500890624 1132228640 12.142 99 23 45 0 1167586880 31410320 3947920
06:35:12 34080 8192000000 10106090176 10543161344 1122620016 12.052 98 37 70 1 1177522464 48144272 6758176
06:36:51 80793 8192000000 10081220560 10514759680 1120067584 12.028 98 19 35 0 1182670672 58724688 3878400
06:39:20 32331 8192000000 10075361552 10508722176 1125896304 12.083 99 67 126 2 1159332240 19531536 13904400
06:40:58 51464 8192000000 10111730176 10562699264 1129369648 12.115 99 36 68 0 1200051120 63465728 7215744
06:42:56 26483 8192000000 10088133232 10550685696 1138208096 12.199 100 17 34 0 1183751584 42216880 3326608
06:44:24 10812 8192000000 10106369936 10545750016 1136583872 12.183 100 28 53 0 1204145072 61879616 5681584
06:45:35 70713 8192000000 10125488704 10551865344 1117009680 11.999 98 36 67 0 1188178832 64098672 7070480
06:47:49 29393 8192000000 10056769632 10576744448 1120599664 12.033 100 13 26 0 1190100048 66550176 2950208
06:49:19 144503 8192000000 10105104592 10582622208 1130796464 12.129 99 15 30 0 1188360784 54630864 2933456
06:52:46 53536 8192000000 10112458320 10581389312 1147132352 12.283 100 18 36 0 1221513248 70628432 3752464
06:55:12 68726 8192000000 10091126016 10597449728 1149214304 12.302 100 13 26 0 1216297456 64570704 2512448
06:57:21 47136 8192000000 10091422896 10552406016 1112420464 11.955 97 25 50 0 1182421184 64759328 5241392
06:59:09 8012 8192000000 10090628368 10517450752 1138305024 12.200 100 54 103 0 1187609680 37769328 11535328
07:00:19 26906 8192000000 10163444000 10602745856 1100495264 11.842 97 34 68 0 1228822000 121242016 7084720
07:01:58 34704 8192000000 10056428224 10481704960 1074983808 11.600 95 31 59 1 1113758928 32982736 5792384
07:03:33 9950 8192000000 10150371120 10578173952 1133671984 12.156 100 54 107 2 1237236160 92859216 10704960
07:05:21 20823 8192000000 10074629184 10499215360 1108904400 11.922 98 34 64 0 1155868688 40647904 6316384
07:06:44 59623 8192000000 10102099600 10561048576 1114900992 11.979 98 23 46 0 1199184272 79612448 4670832
07:08:44 67096 8192000000 10051806592 10478141440 1053805440 11.397 93 27 52 0 1103177024 43886320 5485264
07:10:51 62769 8192000000 10070539760 10522857472 1101644656 11.853 99 33 65 0 1151552320 42101536 7806128
07:12:54 16873 8192000000 10087332720 10532724736 1127451744 12.097 100 25 48 0 1192427584 60392464 4583376
07:14:13 68324 8192000000 10098802160 10567405568 1119101296 12.019 99 15 30 0 1239769296 117174464 3493536
07:16:29 75958 8192000000 10072245312 10574561280 1118281648 12.011 99 11 22 0 1173763232 53359440 2122144
07:18:46 51371 8192000000 10024295536 10468040704 1085204192 11.697 97 13 26 0 1129945824 41643184 3098448
07:20:38 52067 8192000000 10082989824 10514386944 1107193616 11.906 98 37 74 1 1148778832 34160448 7424768
07:22:32 56683 8192000000 10117940000 10586431488 1101338352 11.850 98 40 78 1 1205944672 96218048 8388272
07:24:58 48156 8192000000 10088606816 10560094208 1110237568 11.935 99 17 34 0 1183168224 69275056 3655600
07:27:03 40746 8192000000 10095640240 10547531776 1126084624 12.084 99 40 81 1 1169844416 35436560 8323232
07:28:45 41220 8192000000 10102929696 10547687424 1130736400 12.128 99 36 67 1 1187686208 50027728 6922080
07:30:28 33160 8192000000 10159855296 10617434112 1125659008 12.080 98 39 78 1 1220689232 86802320 8227904
07:32:15 17877 8192000000 10101301312 10524983296 1144262512 12.256 100 49 94 0 1190434224 36994912 9176800
07:33:33 73267 8192000000 10111316320 10558959616 1129547728 12.117 98 47 94 0 1215255392 75895072 9812592
07:35:48 36919 8192000000 10059643152 10545721344 1118573168 12.014 98 11 20 0 1157156512 36315904 2267440
07:37:25 50791 8192000000 10056567856 10526126080 1120905984 12.036 98 21 41 0 1147151200 21798496 4446720
07:39:23 93132 8192000000 10123057408 10589011968 1148773776 12.298 100 25 50 0 1199561152 45113920 5673456
07:42:13 31197 8192000000 10095445968 10540589056 1129528640 12.117 98 8 16 0 1173355520 42172528 1654352
07:44:02 47512 8192000000 10103674560 10558279680 1128962864 12.112 98 24 48 0 1193204096 58561872 5679360
07:45:55 31413 8192000000 10160611632 10611081216 1149964288 12.309 99 35 70 0 1256635200 99441024 7229888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 20-10-2022 07:48:18 to 20-10-2022 07:48:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-10-2022 07:48:36 to 20-10-2022 07:48:38.

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          217031
MON$OLDEST_ACTIVE               223528
MON$OLDEST_SNAPSHOT             223528
MON$NEXT_TRANSACTION            223528
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                       2109696
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2861 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2861 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2861 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Oct 20 07:46:45 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

20.10.22 07:48:39. 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-10-20 07:48:42 No severe PSQL-related problems occured
20.10.22 07:48:43. End of report.