Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.05.2022 07:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15862120 kB
MemAvailable 31487072 kB
Buffers 0 kB
Cached 15796700 kB
SwapCached 3256 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-05-03 07:37:14
DTS_END                         2022-05-03 07:37:14.8560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(113:22) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41474
TRN_ID                          226398
ATT_ID                          676
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: 9093.85 26391 1091262
customer order: creation 179.72 478 21566
customer order: refuse 15.31 464 1837
order to supplier: creation 47.28 1437 5673
order to supplier: removal 3.30 192123 396
invoice (draft): creation 48.27 3384 5792
invoice (draft): removal 48.73 1230 5848
invoice accept: apply 44.09 27924 5291
invoice accept: cancel 43.25 1559 5190
customer reserve: creation 4077.41 264 489289
customer reserve: removal 4286.20 115 514344
realization accept: apply 86.81 300 10417
realization accept: cancel 76.45 196 9174
payment from customer: creation 62.48 191 7498
payment from customer: removal 5.80 133 696
payment to supplier: creation 51.56 197 6187
payment to supplier: removal 3.73 155 448
service: total inventory turnovers 4.02 4955 482
service: total monetary turnovers 8.51 399 1021
service: aggregate perf. data 0.91 18882 109
service: refresh index statistics 0.03 273432 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 03-05-2022 07:38:21 to 03-05-2022 07:38:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9657
TEST_TIME 2 7277
TEST_TIME 3 5746
TEST_TIME 4 5916
TEST_TIME 5 6658
TEST_TIME 6 6465
TEST_TIME 7 6426
TEST_TIME 8 6335
TEST_TIME 9 5985
TEST_TIME 10 6232
TEST_TIME 11 6540
TEST_TIME 12 6678
TEST_TIME 13 6803
TEST_TIME 14 6829
TEST_TIME 15 7031
TEST_TIME 16 7143
TEST_TIME 17 7223
TEST_TIME 18 7499
TEST_TIME 19 7626
TEST_TIME 20 7899
TEST_TIME 21 7874
TEST_TIME 22 7980
TEST_TIME 23 8149
TEST_TIME 24 8380
TEST_TIME 25 8574
TEST_TIME 26 8562
TEST_TIME 27 8735
TEST_TIME 28 8817
TEST_TIME 29 8876
TEST_TIME 30 8845
TEST_TIME 31 8896
TEST_TIME 32 8872
TEST_TIME 33 8829
TEST_TIME 34 8872
TEST_TIME 35 8878
TEST_TIME 36 9443
TEST_TIME 37 10331
TEST_TIME 38 10189
TEST_TIME 39 10304
TEST_TIME 40 10437
TEST_TIME 41 10786
TEST_TIME 42 11037
TEST_TIME 43 10863
TEST_TIME 44 10763
TEST_TIME 45 10642
TEST_TIME 46 10484
TEST_TIME 47 10536
TEST_TIME 48 10614
TEST_TIME 49 10525
TEST_TIME 50 10429
TEST_TIME 51 10327
TEST_TIME 52 10173
TEST_TIME 53 10051
TEST_TIME 54 10048
TEST_TIME 55 10049
TEST_TIME 56 10044
TEST_TIME 57 10068
TEST_TIME 58 10055
TEST_TIME 59 10057
TEST_TIME 60 10104
TEST_TIME 61 10037
TEST_TIME 62 9996
TEST_TIME 63 9912
TEST_TIME 64 9863
TEST_TIME 65 9806
TEST_TIME 66 9833
TEST_TIME 67 9859
TEST_TIME 68 9803
TEST_TIME 69 9746
TEST_TIME 70 9747
TEST_TIME 71 9722
TEST_TIME 72 9714
TEST_TIME 73 9682
TEST_TIME 74 9756
TEST_TIME 75 9751
TEST_TIME 76 9713
TEST_TIME 77 9710
TEST_TIME 78 9774
TEST_TIME 79 9661
TEST_TIME 80 9588
TEST_TIME 81 9519
TEST_TIME 82 9504
TEST_TIME 83 9464
TEST_TIME 84 9428
TEST_TIME 85 9376
TEST_TIME 86 9340
TEST_TIME 87 9297
TEST_TIME 88 9303
TEST_TIME 89 9327
TEST_TIME 90 9315
TEST_TIME 91 9279
TEST_TIME 92 9291
TEST_TIME 93 9264
TEST_TIME 94 9373
TEST_TIME 95 9427
TEST_TIME 96 9406
TEST_TIME 97 9448
TEST_TIME 98 9406
TEST_TIME 99 9480
TEST_TIME 100 9469
TEST_TIME 101 9519
TEST_TIME 102 9468
TEST_TIME 103 9401
TEST_TIME 104 9397
TEST_TIME 105 9355
TEST_TIME 106 9342
TEST_TIME 107 9290
TEST_TIME 108 9295
TEST_TIME 109 9250
TEST_TIME 110 9190
TEST_TIME 111 9205
TEST_TIME 112 9194
TEST_TIME 113 9197
TEST_TIME 114 9161
TEST_TIME 115 9122
TEST_TIME 116 9107
TEST_TIME 117 9177
TEST_TIME 118 9175
TEST_TIME 119 9183
TEST_TIME 120 9123

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-05-2022 07:38:25 to 03-05-2022 07: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 1537745 1537745 0 0.00 0 23240 15 0 0
doc_list_biud 1537732 1537732 0 0.00 0 47356 45 0 0
sp_add_invoice_to_stock 5377 5291 86 1.59 61 187906 27925 0 86
sp_cancel_adding_invoice 5190 5190 0 0.00 7 96255 1560 0 0
sp_cancel_client_order 1837 1837 0 0.00 6 11016 465 0 0
sp_cancel_customer_reserve 514344 514344 0 0.00 1 47358 115 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 4 5086 133 0 0
sp_cancel_pay_to_supplier 448 448 0 0.00 4 5161 155 0 0
sp_cancel_supplier_invoice 5854 5848 6 0.10 2 38583 1231 0 6
sp_cancel_supplier_order 396 396 0 0.00 20 1815371 192123 0 0
sp_cancel_write_off 9174 9174 0 0.00 2 19167 196 0 0
sp_client_order 21566 21566 0 0.00 6 49103 479 0 0
sp_customer_reserve 489289 489289 0 0.00 0 114427 265 0 0
sp_fill_shopping_cart 33020 33020 0 0.00 1 22402 95 0 0
sp_fill_shopping_cart_clo_res 489250 489250 0 0.00 0 16610 20 0 0
sp_get_clo_for_invoice 5235 5235 0 0.00 0 35993 308 0 0
sp_kill_cost_storno 15508 15508 0 0.00 0 7109 86 0 0
sp_kill_qstorno_ret_qs2qd 1044106 1044106 0 0.00 0 47353 50 0 0
sp_kill_qty_storno 552440 552440 0 0.00 0 47356 116 0 0
sp_lock_selected_doc 21611 21611 0 0.00 0 6938 7 0 0
sp_make_cost_storno 29337 29337 0 0.00 0 23179 87 0 0
sp_multiply_rows_for_pdistr 29336 29336 0 0.00 0 8150 40 0 0
sp_multiply_rows_for_qdistr 21565 21565 0 0.00 0 14948 177 0 0
sp_pay_from_customer 7498 7498 0 0.00 5 10674 191 0 0
sp_pay_to_supplier 6187 6187 0 0.00 7 7551 197 0 0
sp_payment_common 13685 13685 0 0.00 2 7534 51 0 0
sp_qd_handle_on_cancel_clo 1837 1837 0 0.00 0 11008 383 0 0
sp_qd_handle_on_invoice_upd_sts 10424 10424 0 0.00 0 13362 645 0 0
sp_qd_handle_on_reserve_upd_sts 10417 10417 0 0.00 0 10377 64 0 0
sp_reserve_write_off 10417 10417 0 0.00 8 12730 301 0 0
sp_supplier_invoice 5792 5792 0 0.00 11 120600 3384 0 0
sp_supplier_order 5673 5673 0 0.00 7 19633 1438 0 0
srv_aggregate_perf_data 109 109 0 0.00 2562 165660 18883 0 0
srv_fill_mon_cache_memory 76 76 0 0.00 869 142785 29507 0 0
srv_find_qd_qs_mism 1037691 1037691 0 0.00 0 16421 12 0 0
srv_make_invnt_saldo 482 482 0 0.00 101 168354 4956 0 0
srv_make_money_saldo 1021 1021 0 0.00 1 21420 400 0 0
srv_recalc_idx_stat 4 4 0 0.00 66457 519356 273432 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 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 5297 5297 0 0.00 1 16028 1143 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 1 9810 607 0 0
t$perf-norm:sp_cancel_client_order 1837 1837 0 0.00 0 1938 28 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 4088 42 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 2610 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 448 448 0 0.00 0 1085 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 1047 45 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 114066 8902 0 0
t$perf-norm:sp_cancel_write_off 1037 1037 0 0.00 0 7022 31 0 0
t$perf-norm:sp_client_order 21566 21566 0 0.00 0 7661 44 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 2606 20 0 0
t$perf-norm:sp_pay_from_customer 7498 7498 0 0.00 0 2742 21 0 0
t$perf-norm:sp_pay_to_supplier 6187 6187 0 0.00 0 8365 21 0 0
t$perf-norm:sp_reserve_write_off 10417 10417 0 0.00 0 5362 26 0 0
t$perf-norm:sp_supplier_invoice 5792 5792 0 0.00 0 8815 38 0 0
t$perf-norm:sp_supplier_order 5673 5673 0 0.00 0 5842 40 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 356 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 76 76 0 0.00 0 389 19 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 765 19 0 0
t$perf-norm:srv_make_money_saldo 1021 1021 0 0.00 0 917 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 204 92 0 0
v_add_invoice_to_stock 10632 10460 172 1.61 0 14495 27 0 172
v_all_customers 39742 39742 0 0.00 0 96 4 0 0
v_all_suppliers 32114 32114 0 0.00 0 95 5 0 0
v_all_wares 43128 43128 0 0.00 0 95 5 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 958 15 0 0
v_cancel_client_order 3674 3674 0 0.00 0 6054 12 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 1886 15 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 1 1003 11 0 0
v_cancel_payment_to_supplier 896 896 0 0.00 0 3915 21 0 0
v_cancel_supplier_invoice 854 842 12 1.40 0 1437 25 0 12
v_cancel_supplier_order 792 792 0 0.00 0 1295 12 0 0
v_cancel_write_off 2074 2074 0 0.00 0 4438 29 0 0
v_max_id_clo_ord 5670 5670 0 0.00 0 1667 6 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 2383 18 0 0
v_max_id_ord_sup 5785 5785 0 0.00 0 2923 8 0 0
v_max_non_paid_invoice 1584 1584 0 0.00 0 430 9 0 0
v_max_non_paid_realizn 3822 3822 0 0.00 0 884 8 0 0
v_min_id_clo_ord 5670 5670 0 0.00 0 3614 11 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 5215 19 0 0
v_min_id_ord_sup 5785 5785 0 0.00 0 11185 12 0 0
v_min_non_paid_invoice 6187 6187 0 0.00 0 7526 15 0 0
v_min_non_paid_realizn 7497 7497 0 0.00 0 7524 19 0 0
v_our_firm 10738 10738 0 0.00 0 55 5 0 0
v_reserve_write_off 20833 20833 0 0.00 0 4372 12 0 0
x_lock_depdocs_on_canc_invoice 10617 10617 0 0.00 0 9846 136 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 3551 378 0 0
x_make_qstorno_1000_1200 5673 5673 0 0.00 3 18756 1226 0 0
x_make_qstorno_1200_2000 5792 5792 0 0.00 4 111798 3144 0 0
x_make_qstorno_2100_3300 489285 489285 0 0.00 0 114417 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-05-2022 07:38:30 to 03-05-2022 07:38:34.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:20 28922 8192000000 9737903792 10040541184 812610640 9.024 100 86 172 0 841214496 7531184 21072672
05:09:51 31667 8192000000 9780809200 10093150208 851003200 9.410 100 87 174 0 884681792 12946272 20732320
05:11:31 12014 8192000000 9825629856 10147667968 889130608 9.790 100 87 174 0 932130768 23429136 19571024
05:12:44 13216 8192000000 9870960736 10207649792 923971104 10.135 100 86 172 0 986361392 43446704 18943584
05:14:04 3801 8192000000 9936611408 10280550400 955606320 10.446 100 84 161 2 1039537712 66901344 17030048
05:15:10 2738 8192000000 9957670928 10310320128 989407248 10.776 100 83 163 0 1074854432 68467744 16979440
05:16:16 1575 8192000000 10000715232 10358890496 1020314352 11.075 100 94 184 1 1113126128 73859584 18952192
05:17:19 894 8192000000 10010029664 10382913536 1043996592 11.303 100 94 185 0 1134636672 71266304 19373776
05:18:22 1029 8192000000 10048316512 10426695680 1057764368 11.435 100 97 187 0 1165421120 89362864 18293888
05:19:25 880 8192000000 10052448032 10430619648 1069649312 11.549 100 90 171 0 1172762304 85059200 18053792
05:20:28 1743 8192000000 10048316208 10427883520 1070742720 11.559 100 90 172 0 1167228576 79106960 17378896
05:21:33 1262 8192000000 10045685184 10430377984 1080342832 11.651 100 86 163 2 1169387584 72709984 16334768
05:22:38 1199 8192000000 10055668080 10440744960 1088056736 11.724 100 95 185 2 1176212768 68937840 19218192
05:23:40 1023 8192000000 10071553376 10462003200 1093431904 11.775 100 91 178 2 1195387824 82958656 18997264
05:24:45 3077 8192000000 10058146512 10448060416 1096387216 11.803 100 65 123 0 1178227760 70173984 11666560
05:25:49 866 8192000000 10071005008 10461405184 1100663104 11.844 100 96 180 1 1196158560 76971280 18524176
05:26:52 1223 8192000000 10092300096 10483290112 1105407472 11.889 100 96 185 1 1212099840 87258272 19434096
05:27:55 1228 8192000000 10089709952 10480099328 1108914416 11.922 100 95 183 1 1210049072 81703344 19431312
05:28:59 1150 8192000000 10055007280 10442129408 1103047136 11.867 100 90 167 2 1175363584 56041904 16274544
05:30:02 1218 8192000000 10060725216 10454274048 1097337856 11.812 100 94 182 0 1182329952 67600112 17391984
05:31:04 1097 8192000000 9985566144 10360872960 1045638896 11.319 98 93 179 0 1110404432 46671936 18093600
05:32:07 1288 8192000000 10017722288 10398396416 1062282864 11.478 100 96 183 1 1136780672 56843008 17654800
05:33:09 1031 8192000000 10001961568 10383704064 1066925040 11.523 100 91 175 0 1126812864 41790592 18097232
05:34:11 1147 8192000000 10031337760 10406039552 1056357616 11.422 100 94 175 0 1152154928 77340464 18456848
05:35:15 10275 8192000000 9986274112 10376732672 1049173152 11.353 100 58 114 2 1122027312 61643216 11210944
05:36:28 1052 8192000000 9982834352 10358349824 1046224656 11.324 100 88 172 0 1108942576 44569008 18148912
05:37:35 7746 8192000000 10038237584 10414088192 1064779136 11.502 100 83 146 3 1155459104 76612032 14067936
05:38:58 1710 8192000000 10046879872 10428706816 1078510960 11.633 100 84 154 0 1161268112 67897696 14859456
05:40:07 17833 8192000000 10053506304 10444107776 1095507216 11.795 100 71 137 1 1185016160 76373232 13135712
05:41:29 1009 8192000000 10091406576 10472796160 1098627216 11.825 100 95 181 1 1205148752 86501264 20020272
05:42:36 1155 8192000000 10127764880 10525712384 1119614400 12.023 100 89 174 0 1244571248 106678864 18277984
05:43:40 3406 8192000000 10119937136 10525626368 1125644352 12.080 100 71 136 0 1238569824 98217360 14708112
05:44:47 867 8192000000 10159793712 10561216512 1137930336 12.196 100 90 164 1 1275452832 118454016 19068480
05:45:59 2154 8192000000 10186509760 10583830528 1144793584 12.261 100 82 154 2 1299712320 138707888 16210848
05:47:07 11341 8192000000 10215066832 10625744896 1153181712 12.339 100 58 109 2 1339762896 175137296 11443888
05:48:24 1203 8192000000 10238784352 10651910144 1168818080 12.486 100 92 180 1 1356735248 167412032 20505136
05:49:28 4213 8192000000 10258663920 10676154368 1175661600 12.550 100 74 148 1 1375354368 183746560 15946208
05:50:37 1193 8192000000 10285319840 10687021056 1182091920 12.610 100 93 185 0 1393354416 192945968 18316528
05:51:42 1510 8192000000 10318288720 10721673216 1185014944 12.637 100 92 176 0 1430320864 225172208 20133712
05:52:47 1866 8192000000 10340621712 10751336448 1172034304 12.516 100 92 175 1 1455811456 263681968 20095184
05:53:52 2082 8192000000 10362640720 10770268160 1185911840 12.645 100 91 177 1 1474654992 268143776 20599376
05:54:57 1303 8192000000 10383491808 10818199552 1197697328 12.755 100 94 178 1 1491403488 273520112 20186048
05:56:02 1770 8192000000 10380542976 10817060864 1193951728 12.720 100 91 181 0 1488023408 272479456 21592224
05:57:07 1747 8192000000 10414038320 10850598912 1190557760 12.689 100 93 182 0 1521700672 310219296 20923616
05:58:13 1353 8192000000 10434715840 10843287552 1176698016 12.559 99 93 184 1 1544197712 345477120 22022576
05:59:19 1524 8192000000 10487906576 10910121984 1191622608 12.698 100 92 178 0 1595272176 381517776 22131792
06:00:23 1077 8192000000 10450113072 10892980224 1178404608 12.575 100 97 195 0 1555009888 353470160 23135120
06:01:27 892 8192000000 10473194704 10887286784 1166293424 12.462 100 98 188 0 1576008128 387646816 22067888
06:02:33 1213 8192000000 10484583520 10918383616 1170850848 12.505 100 95 190 1 1588835216 394338416 23645952
06:03:37 1151 8192000000 10507985504 10934124544 1166215184 12.461 100 91 176 0 1608481824 421554848 20711792
06:04:43 7313 8192000000 10494499952 10931232768 1181115568 12.601 100 57 112 1 1608708000 413995008 13597424
06:05:59 2604 8192000000 10503239088 10938839040 1173261200 12.527 100 65 126 2 1604602864 415438736 15902928
06:07:05 3007 8192000000 10499359616 10934751232 1166232480 12.462 100 64 126 0 1604344448 422102800 16009168
06:08:11 872 8192000000 10567690288 10992480256 1164686352 12.447 99 75 147 1 1672100304 489902480 17511472
06:09:17 6126 8192000000 10577099904 11005894656 1186213664 12.648 100 60 115 1 1677874960 477650752 14010544
06:10:31 8717 8192000000 10589612352 11060367360 1180382496 12.594 100 56 110 1 1691386144 496470000 14533648
06:11:46 5567 8192000000 10629004944 11062484992 1145550368 12.268 99 64 128 1 1726778336 563708608 17519360
06:12:57 6152 8192000000 10559418336 11080376320 1157373024 12.379 100 54 106 0 1677583072 506054912 14155136
06:14:06 12456 8192000000 10380840896 10974867456 1164835312 12.449 100 40 78 0 1499138192 323243600 11059280
06:15:55 34711 8192000000 10350719904 10852429824 1157283168 12.378 99 29 58 0 1457217584 292843296 7091120
06:17:46 83988 8192000000 10110100576 10638225408 1091598608 11.758 96 11 22 0 1264123712 169817152 2707952
06:20:18 4641 8192000000 10070595872 10488066048 1122976624 12.055 100 71 133 2 1164349360 25852016 15520720
06:21:27 101065 8192000000 10087845248 10520752128 1100159104 11.839 98 33 62 0 1202040096 95126016 6754976
06:24:09 142783 8192000000 10084714928 10525536256 1127888880 12.101 99 23 46 1 1179688816 47346384 4453552
06:27:37 43767 8192000000 10126944656 10556170240 1135924928 12.177 99 35 68 0 1215202448 71521424 7756096
06:29:22 69864 8192000000 10157724496 10604707840 1142700624 12.241 99 29 57 1 1252651600 103316944 6634032
06:31:33 18610 8192000000 10174050768 10608517120 1113948448 11.970 97 48 92 0 1252116896 127688032 10480416
06:32:56 19418 8192000000 10177743168 10625458176 1146399792 12.276 100 70 132 2 1276397584 116270928 13726864
06:34:21 80480 8192000000 10165493856 10679975936 1139499776 12.211 99 16 30 2 1248518032 105230064 3788192
06:36:52 23224 8192000000 10107386816 10607906816 1145344640 12.266 100 15 30 0 1245361504 96194272 3822592
06:38:18 58651 8192000000 10065133968 10546438144 1080657504 11.654 95 21 42 0 1144270928 59215424 4398000
06:40:20 56575 8192000000 10079381392 10529898496 1130928704 12.130 100 34 65 1 1180392880 43315360 6148816
06:42:22 112739 8192000000 10103012864 10598240256 1116036192 11.990 98 17 33 0 1179154368 59448864 3669312
06:45:16 23218 8192000000 10062830736 10503974912 1116048160 11.990 98 22 42 0 1137278240 17733264 3496816
06:46:43 8521 8192000000 10108699232 10540515328 1136828784 12.186 100 57 114 1 1216564976 67874288 11861904
06:47:56 39540 8192000000 10078866528 10526519296 1109216976 11.925 98 28 56 1 1156490304 41640192 5633136
06:49:36 10945 8192000000 10101099824 10539970560 1121973408 12.046 99 63 124 0 1181415536 46805968 12636160
06:50:50 40837 8192000000 10088905504 10558726144 1122078240 12.047 99 32 62 0 1196894592 68516672 6299680
06:52:31 33766 8192000000 10077774400 10512683008 1125291472 12.077 99 28 56 1 1162839328 30859296 6688560
06:54:25 99019 8192000000 10052320048 10547314688 1102255200 11.859 98 12 23 0 1152954368 47636736 3062432
06:58:01 69221 8192000000 10090577632 10542333952 1122370080 12.049 100 31 62 0 1185442960 56948624 6124256
07:00:11 22910 8192000000 10080396640 10509602816 1134008272 12.159 100 56 99 1 1181917152 37666464 10242416
07:01:42 79314 8192000000 10080247904 10531725312 1090175840 11.744 96 17 34 0 1147146464 53013984 3956640
07:04:01 79142 8192000000 10059331744 10540957696 1096520096 11.805 98 9 18 1 1150440528 52109168 1811264
07:06:21 48996 8192000000 10058344176 10517708800 1110916208 11.941 100 39 77 1 1182048928 62030016 9102704
07:08:11 31096 8192000000 10086568576 10523860992 1114778176 11.978 99 19 38 1 1185936480 66926752 4231552
07:09:42 49374 8192000000 10162800768 10615435264 1136388160 12.182 100 32 64 0 1241287952 97855184 7044608
07:11:49 58307 8192000000 10034644816 10506784768 1111994800 11.951 98 11 22 0 1230894864 116122048 2778016
07:13:48 14387 8192000000 10113952928 10538491904 1136077504 12.179 100 50 99 0 1210603520 64728064 9797952
07:15:05 2471 8192000000 10054955104 10468048896 1111591104 11.947 98 74 136 5 1147871056 22924576 13355376
07:16:10 17372 8192000000 10152629952 10591899648 1116613312 11.995 99 46 92 1 1237769888 111568528 9588048
07:17:33 27868 8192000000 10089280256 10533748736 1119207776 12.020 100 38 76 1 1174202896 47515520 7479600
07:19:01 82684 8192000000 10081858016 10525478912 1097306256 11.812 98 38 75 1 1150518752 44778112 8434384
07:21:30 62642 8192000000 10068239888 10522419200 1085083232 11.696 97 38 72 0 1145421840 52236640 8101968
07:23:34 76614 8192000000 10079394160 10528505856 1113990448 11.970 99 17 35 0 1176615936 58837328 3788160
07:25:58 52693 8192000000 10084374848 10537246720 1119826240 12.025 100 19 38 0 1182428448 57918016 4684192
07:27:51 77171 8192000000 10067051888 10530717696 1114670432 11.977 99 18 36 0 1200631264 81735584 4225248
07:30:10 34280 8192000000 10089066864 10544287744 1130375248 12.125 100 20 40 0 1188795408 53563744 4856416
07:31:46 5570 8192000000 10114257392 10546171904 1134097200 12.160 100 64 130 1 1212132992 64053408 13982384
07:32:58 17911 8192000000 10183448832 10618142720 1126915888 12.092 100 45 86 1 1281831616 145778256 9137472
07:34:19 84561 8192000000 10138058080 10600427520 1120098304 12.028 99 23 46 1 1233177008 108037216 5041488
07:37:13 17108 8192000000 9112681232 9412022272 252936176 2.995 23 1 2 0 272958080 19986496 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-05-2022 07:38:36 to 03-05-2022 07:38:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 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 03-05-2022 07:38:53 to 03-05-2022 07: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          226730
MON$OLDEST_ACTIVE               226731
MON$OLDEST_SNAPSHOT             226731
MON$NEXT_TRANSACTION            226731
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                       2116992
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

03.05.22 07: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
2022-05-03 07:38:58 No severe PSQL-related problems occured
03.05.22 07:39:00. End of report.