Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.03.2022 10:37
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 16336320 kB
MemAvailable 31567440 kB
Buffers 0 kB
Cached 15337436 kB
SwapCached 2180 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-03-31 10:37:13
DTS_END                         2022-03-31 10:37:13.8280
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60354
TRN_ID                          222303
ATT_ID                          635
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: 9127.73 24016 1095328
customer order: creation 171.03 512 20523
customer order: refuse 14.66 396 1759
order to supplier: creation 45.37 1420 5444
order to supplier: removal 3.48 159360 418
invoice (draft): creation 45.38 3298 5446
invoice (draft): removal 51.25 1010 6150
invoice accept: apply 40.61 25711 4873
invoice accept: cancel 46.57 1182 5588
customer reserve: creation 3852.81 241 462337
customer reserve: removal 4567.15 96 548058
realization accept: apply 82.83 280 9940
realization accept: cancel 76.59 164 9191
payment from customer: creation 59.95 164 7194
payment from customer: removal 5.35 157 642
payment to supplier: creation 48.56 206 5827
payment to supplier: removal 3.59 113 431
service: total inventory turnovers 4.16 3628 499
service: total monetary turnovers 7.54 309 905
service: aggregate perf. data 0.83 15865 99
service: refresh index statistics 0.03 266211 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-03-2022 10:38:18 to 31-03-2022 10:38:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8847
TEST_TIME 2 6863
TEST_TIME 3 5494
TEST_TIME 4 5474
TEST_TIME 5 5166
TEST_TIME 6 5372
TEST_TIME 7 5596
TEST_TIME 8 5517
TEST_TIME 9 5402
TEST_TIME 10 5447
TEST_TIME 11 5517
TEST_TIME 12 5900
TEST_TIME 13 6139
TEST_TIME 14 6440
TEST_TIME 15 6452
TEST_TIME 16 6533
TEST_TIME 17 6774
TEST_TIME 18 6828
TEST_TIME 19 7024
TEST_TIME 20 7392
TEST_TIME 21 7469
TEST_TIME 22 7781
TEST_TIME 23 8095
TEST_TIME 24 8241
TEST_TIME 25 8356
TEST_TIME 26 8875
TEST_TIME 27 8984
TEST_TIME 28 9441
TEST_TIME 29 9697
TEST_TIME 30 9646
TEST_TIME 31 9968
TEST_TIME 32 10812
TEST_TIME 33 11024
TEST_TIME 34 10923
TEST_TIME 35 10853
TEST_TIME 36 10861
TEST_TIME 37 10773
TEST_TIME 38 10811
TEST_TIME 39 10742
TEST_TIME 40 10763
TEST_TIME 41 10866
TEST_TIME 42 10702
TEST_TIME 43 10510
TEST_TIME 44 10415
TEST_TIME 45 10249
TEST_TIME 46 10159
TEST_TIME 47 10090
TEST_TIME 48 10072
TEST_TIME 49 9993
TEST_TIME 50 9997
TEST_TIME 51 10019
TEST_TIME 52 9935
TEST_TIME 53 10111
TEST_TIME 54 9965
TEST_TIME 55 9942
TEST_TIME 56 10007
TEST_TIME 57 9925
TEST_TIME 58 9907
TEST_TIME 59 9871
TEST_TIME 60 9960
TEST_TIME 61 9941
TEST_TIME 62 9911
TEST_TIME 63 9980
TEST_TIME 64 9985
TEST_TIME 65 10036
TEST_TIME 66 9999
TEST_TIME 67 9919
TEST_TIME 68 10016
TEST_TIME 69 10040
TEST_TIME 70 9948
TEST_TIME 71 9941
TEST_TIME 72 9854
TEST_TIME 73 9750
TEST_TIME 74 9746
TEST_TIME 75 9702
TEST_TIME 76 9681
TEST_TIME 77 9637
TEST_TIME 78 9583
TEST_TIME 79 9520
TEST_TIME 80 9470
TEST_TIME 81 9564
TEST_TIME 82 9568
TEST_TIME 83 9550
TEST_TIME 84 9564
TEST_TIME 85 9530
TEST_TIME 86 9555
TEST_TIME 87 9511
TEST_TIME 88 9590
TEST_TIME 89 9668
TEST_TIME 90 9682
TEST_TIME 91 9654
TEST_TIME 92 9630
TEST_TIME 93 9587
TEST_TIME 94 9556
TEST_TIME 95 9485
TEST_TIME 96 9445
TEST_TIME 97 9420
TEST_TIME 98 9402
TEST_TIME 99 9455
TEST_TIME 100 9444
TEST_TIME 101 9428
TEST_TIME 102 9435
TEST_TIME 103 9425
TEST_TIME 104 9386
TEST_TIME 105 9430
TEST_TIME 106 9400
TEST_TIME 107 9427
TEST_TIME 108 9470
TEST_TIME 109 9481
TEST_TIME 110 9420
TEST_TIME 111 9355
TEST_TIME 112 9360
TEST_TIME 113 9308
TEST_TIME 114 9270
TEST_TIME 115 9284
TEST_TIME 116 9245
TEST_TIME 117 9225
TEST_TIME 118 9213
TEST_TIME 119 9209
TEST_TIME 120 9148

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-03-2022 10:38:22 to 31-03-2022 10:38:25.

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 1521930 1521930 0 0.00 0 14779 13 0 0
doc_list_biud 1521925 1521925 0 0.00 0 32935 40 0 0
sp_add_invoice_to_stock 4993 4873 120 2.40 52 192596 25712 0 120
sp_cancel_adding_invoice 5588 5588 0 0.00 5 78942 1182 0 0
sp_cancel_client_order 1759 1759 0 0.00 6 10318 396 0 0
sp_cancel_customer_reserve 548058 548058 0 0.00 1 25504 97 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 3 11208 157 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 4 3838 113 0 0
sp_cancel_supplier_invoice 6158 6150 8 0.12 2 32945 1010 0 8
sp_cancel_supplier_order 418 418 0 0.00 14 1576244 159361 0 0
sp_cancel_write_off 9191 9191 0 0.00 2 14331 164 0 0
sp_client_order 20523 20523 0 0.00 6 19825 512 0 0
sp_customer_reserve 462337 462337 0 0.00 1 97545 241 0 0
sp_fill_shopping_cart 31400 31400 0 0.00 2 12229 78 0 0
sp_fill_shopping_cart_clo_res 462311 462311 0 0.00 0 16259 18 0 0
sp_get_clo_for_invoice 4829 4829 0 0.00 0 19635 272 0 0
sp_kill_cost_storno 15852 15852 0 0.00 0 12479 68 0 0
sp_kill_qstorno_ret_qs2qd 1111875 1111875 0 0.00 0 32932 43 0 0
sp_kill_qty_storno 585933 585933 0 0.00 0 32934 98 0 0
sp_lock_selected_doc 20538 20538 0 0.00 0 9935 8 0 0
sp_make_cost_storno 27790 27790 0 0.00 0 14770 77 0 0
sp_multiply_rows_for_pdistr 27790 27790 0 0.00 0 10593 36 0 0
sp_multiply_rows_for_qdistr 20522 20522 0 0.00 0 14102 203 0 0
sp_pay_from_customer 7194 7194 0 0.00 5 14764 164 0 0
sp_pay_to_supplier 5827 5827 0 0.00 7 18343 207 0 0
sp_payment_common 13021 13021 0 0.00 2 9051 46 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 7903 308 0 0
sp_qd_handle_on_invoice_upd_sts 10417 10417 0 0.00 0 18881 500 0 0
sp_qd_handle_on_reserve_upd_sts 9940 9940 0 0.00 0 19897 71 0 0
sp_reserve_write_off 9940 9940 0 0.00 8 20078 280 0 0
sp_supplier_invoice 5446 5446 0 0.00 7 93214 3299 0 0
sp_supplier_order 5444 5444 0 0.00 8 56074 1420 0 0
srv_aggregate_perf_data 99 99 0 0.00 2838 154610 15865 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 872 98670 36148 0 0
srv_find_qd_qs_mism 1046897 1046897 0 0.00 0 9830 10 0 0
srv_make_invnt_saldo 499 499 0 0.00 78 100783 3629 0 0
srv_make_money_saldo 905 905 0 0.00 1 10870 309 0 0
srv_recalc_idx_stat 4 4 0 0.00 62571 463608 266212 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4874 4874 0 0.00 0 15467 875 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 1 6006 415 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 8219 29 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 1080 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 209 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 11106 49 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 689 31 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 77848 6009 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 2749 22 0 0
t$perf-norm:sp_client_order 20524 20524 0 0.00 0 8573 33 0 0
t$perf-norm:sp_customer_reserve 1021 1021 0 0.00 0 3508 15 0 0
t$perf-norm:sp_pay_from_customer 7194 7194 0 0.00 0 5141 18 0 0
t$perf-norm:sp_pay_to_supplier 5827 5827 0 0.00 0 8297 22 0 0
t$perf-norm:sp_reserve_write_off 9940 9940 0 0.00 0 6950 23 0 0
t$perf-norm:sp_supplier_invoice 5446 5446 0 0.00 0 4521 29 0 0
t$perf-norm:sp_supplier_order 5446 5446 0 0.00 0 5524 29 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 402 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 184 6 0 0
t$perf-norm:srv_make_invnt_saldo 499 499 0 0.00 0 2106 15 0 0
t$perf-norm:srv_make_money_saldo 905 905 0 0.00 0 368 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 12 183 76 0 0
v_add_invoice_to_stock 9894 9654 240 2.42 0 9725 29 0 240
v_all_customers 38064 38064 0 0.00 0 76 3 0 0
v_all_suppliers 30706 30706 0 0.00 0 77 4 0 0
v_all_wares 41040 41040 0 0.00 0 65 4 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 1992 17 0 0
v_cancel_client_order 3518 3518 0 0.00 0 8362 14 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 9742 24 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 2201 15 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 289 8 0 0
v_cancel_supplier_invoice 818 802 16 1.95 0 5754 30 0 16
v_cancel_supplier_order 836 836 0 0.00 0 16898 33 0 0
v_cancel_write_off 1986 1986 0 0.00 0 9089 29 0 0
v_max_id_clo_ord 5439 5439 0 0.00 0 639 4 0 0
v_max_id_clo_res 1021 1021 0 0.00 0 5594 32 0 0
v_max_id_ord_sup 5440 5440 0 0.00 0 8273 11 0 0
v_max_non_paid_invoice 1353 1353 0 0.00 0 100 7 0 0
v_max_non_paid_realizn 3567 3567 0 0.00 0 1113 7 0 0
v_min_id_clo_ord 5439 5439 0 0.00 0 3181 7 0 0
v_min_id_clo_res 1021 1021 0 0.00 0 4171 12 0 0
v_min_id_ord_sup 5440 5440 0 0.00 0 5629 11 0 0
v_min_non_paid_invoice 5827 5827 0 0.00 0 9004 22 0 0
v_min_non_paid_realizn 7194 7194 0 0.00 0 7442 19 0 0
v_our_firm 10230 10230 0 0.00 0 69 4 0 0
v_reserve_write_off 19880 19880 0 0.00 0 16908 13 0 0
x_lock_depdocs_on_canc_invoice 11337 11337 0 0.00 0 20598 120 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 5546 331 0 0
x_make_qstorno_1000_1200 5444 5444 0 0.00 3 56050 1269 0 0
x_make_qstorno_1200_2000 5446 5446 0 0.00 3 93193 3079 0 0
x_make_qstorno_2100_3300 462336 462336 0 0.00 0 97538 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-03-2022 10:38:26 to 31-03-2022 10:38:30.

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
08:07:17 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:18 49936 8192000000 9728703312 10030120960 807809232 8.975 100 95 189 3 839352224 8389808 23153184
08:10:17 21177 8192000000 9764769008 10073088000 832180032 9.221 100 89 178 1 869383664 16057792 21145840
08:11:42 12692 8192000000 9811985984 10134065152 866468688 9.565 100 79 158 0 921240208 36833808 17937712
08:13:01 9612 8192000000 9892926912 10226749440 923451664 10.130 100 89 176 1 998869072 55953968 19463440
08:14:15 3227 8192000000 9936338976 10283143168 968007280 10.567 100 92 181 1 1053948416 67182400 18758736
08:15:21 1211 8192000000 9987221264 10346340352 1005680160 10.934 100 93 182 0 1102778208 78497296 18600752
08:16:24 1173 8192000000 10022993888 10391822336 1037816512 11.244 100 92 180 0 1139813712 84095312 17901888
08:17:27 997 8192000000 10029398880 10404843520 1060387728 11.460 100 97 190 0 1153706368 73894240 19424400
08:18:29 1143 8192000000 10032722256 10406936576 1066302768 11.517 100 91 178 1 1152923280 69013376 17607136
08:19:31 1069 8192000000 10040234928 10424922112 1076370464 11.613 100 96 188 3 1158936400 63945072 18620864
08:20:33 9899 8192000000 10041011216 10418962432 1076409008 11.613 100 86 162 0 1153433776 62769296 14255472
08:21:43 11326 8192000000 10007045552 10402877440 1077177568 11.621 100 62 114 0 1129174304 40959984 11036752
08:22:55 4275 8192000000 10048646096 10440384512 1086220464 11.707 100 70 133 2 1172636304 73070944 13344896
08:24:01 1360 8192000000 10064742240 10453635072 1093853504 11.779 100 94 188 1 1181115776 68199664 19062608
08:25:03 1020 8192000000 10070650400 10460045312 1096241392 11.802 100 95 188 0 1191929888 76687600 19000896
08:26:05 1250 8192000000 10066940288 10456895488 1099470016 11.833 100 96 177 1 1185886544 68893888 17522640
08:27:07 9191 8192000000 10057884016 10453901312 1101622288 11.853 100 73 131 0 1170680720 56389472 12668960
08:28:17 3926 8192000000 10038972128 10433564672 1091436224 11.756 100 73 140 0 1164793776 59718976 13638576
08:29:22 6107 8192000000 10053310464 10443431936 1085347600 11.698 100 73 136 1 1174741296 75668976 13724720
08:30:29 5901 8192000000 9989647552 10374746112 1045582368 11.318 98 68 117 3 1118441168 60605984 12252816
08:31:36 2189 8192000000 10003705472 10384650240 1061438448 11.470 100 90 169 1 1125489872 47789456 16261968
08:32:39 1409 8192000000 10017772896 10411757568 1061012960 11.466 100 67 129 0 1141297984 67546528 12738496
08:33:41 1211 8192000000 10016476976 10389581824 1045839568 11.321 100 96 186 1 1133794368 70023072 17931728
08:34:44 1390 8192000000 9990939408 10368266240 1043613728 11.299 99 77 143 3 1109206144 49433376 16159040
08:35:46 818 8192000000 10001350528 10385326080 1051670496 11.377 100 77 145 0 1122171504 54790080 15710928
08:36:48 1016 8192000000 9999016496 10377658368 1039230656 11.257 99 86 166 0 1118715296 61344128 18140512
08:37:53 2408 8192000000 10018001744 10394460160 1063890896 11.494 100 84 147 3 1136685456 58653696 14140864
08:39:03 1343 8192000000 10043950960 10424696832 1084474720 11.690 100 92 171 0 1157215808 55816816 16924272
08:40:10 24209 8192000000 10054218544 10453778432 1094185136 11.782 100 53 104 1 1171451120 66212448 11053536
08:41:40 18491 8192000000 10079542720 10488233984 1112819536 11.959 100 48 91 0 1210031120 87320880 9890704
08:43:02 9727 8192000000 10145453664 10559410176 1124148912 12.066 100 51 101 0 1254346832 119137312 11060608
08:44:16 8318 8192000000 10166424400 10578403328 1134518160 12.164 100 57 112 0 1283788368 136632768 12637440
08:45:31 4929 8192000000 10211478912 10607550464 1149680896 12.307 100 79 151 1 1327286928 160660752 16945280
08:46:41 5619 8192000000 10259502496 10661851136 1158010928 12.385 100 75 150 0 1369918752 195027632 16880192
08:47:51 1098 8192000000 10300098560 10695966720 1170488176 12.501 100 89 168 4 1401948624 212842560 18617888
08:48:56 1161 8192000000 10329488112 10738130944 1180336336 12.593 100 92 182 1 1438589328 237498912 20754080
08:50:01 2399 8192000000 10326439440 10738233344 1182572512 12.614 100 95 185 1 1445745360 242070864 21101984
08:51:07 991 8192000000 10388725008 10799431680 1183991536 12.627 100 97 186 1 1495643632 290193728 21458368
08:52:11 5381 8192000000 10411160000 10822000640 1188634688 12.671 100 87 168 0 1522976096 314959920 19381488
08:53:19 1300 8192000000 10464307600 10876870656 1195476320 12.734 100 89 175 1 1563745040 347669184 20599536
08:54:25 1933 8192000000 10482116736 10899087360 1186053168 12.647 100 93 186 3 1591182080 383685456 21443456
08:55:30 1089 8192000000 10521126896 10936786944 1200606208 12.782 100 94 186 0 1628961216 407209696 21145312
08:56:33 866 8192000000 10527436656 10973409280 1190485152 12.688 100 98 196 0 1632441248 418940880 23015216
08:57:37 1487 8192000000 10532745936 10979348480 1186435824 12.650 100 83 158 1 1639663840 434054208 19173808
08:58:42 1322 8192000000 10520457392 10958495744 1177563536 12.567 100 89 176 0 1628388528 428627104 22197888
08:59:47 6002 8192000000 10523183504 10975551488 1188477712 12.669 100 66 132 0 1623920656 419189056 16253888
09:00:56 5122 8192000000 10509679536 10950180864 1177649504 12.568 100 60 120 1 1612265216 419033040 15582672
09:02:04 8822 8192000000 10526615632 10987380736 1174197744 12.536 100 49 95 0 1640930064 454252992 12479328
09:03:16 14212 8192000000 10470010880 10983030784 1165850048 12.458 100 35 70 0 1589737616 414100720 9786848
09:04:40 21025 8192000000 10400906464 10919137280 1164039536 12.441 100 36 72 1 1545227360 371527408 9660416
09:06:17 23066 8192000000 10365568688 10830311424 1140092480 12.216 97 40 78 2 1442857792 293297904 9467408
09:07:44 41747 8192000000 10367841696 10905505792 1143792560 12.251 100 24 48 1 1495130384 345033120 6304704
09:09:30 42809 8192000000 10083017536 10588823552 1118462672 12.012 99 14 26 1 1322605840 200328208 3814960
09:11:14 87503 8192000000 10087950496 10534563840 1049297904 11.354 92 16 30 3 1110332800 57813856 3221040
09:13:58 37794 8192000000 10114265792 10547654656 1128545984 12.108 99 40 76 2 1221171552 82654640 9970928
09:15:37 27020 8192000000 10079171904 10532196352 1104133632 11.877 97 22 40 2 1160757696 52667024 3957040
09:17:13 55245 8192000000 10094018992 10600013824 1119382064 12.021 99 28 53 0 1223030656 98113344 5535248
09:19:13 98669 8192000000 10051998608 10487304192 1113143824 11.962 99 37 70 3 1145747552 24565120 8038608
09:21:59 80225 8192000000 10062746016 10490236928 1107844576 11.912 98 48 94 2 1147273984 29272592 10156816
09:24:22 32042 8192000000 10122851760 10556637184 1135981232 12.178 99 47 88 0 1200728736 54543328 10204176
09:26:01 33919 8192000000 10184445760 10607071232 1149050480 12.301 100 54 102 1 1270476720 109258400 12167840
09:27:37 41396 8192000000 10150936224 10628759552 1137441952 12.191 99 21 42 1 1259812544 117446640 4923952
09:29:29 30983 8192000000 10145526784 10609328128 1143358128 12.247 100 18 38 1 1242704800 94963488 4383184
09:31:01 26269 8192000000 10053740672 10510188544 1138233296 12.199 100 8 16 1 1158890048 19068624 1588128
09:32:41 46532 8192000000 10082459600 10578055168 1116900848 11.998 98 9 18 0 1191319936 72367232 2051856
09:34:28 19117 8192000000 10105160480 10538086400 1145473312 12.267 100 57 111 1 1197831136 41073296 11284528
09:35:50 23071 8192000000 10106739744 10552770560 1130219040 12.123 99 37 74 0 1202916128 64986928 7710160
09:37:14 21663 8192000000 10136064656 10580287488 1141958080 12.234 100 25 50 0 1212168688 65209120 5001488
09:38:37 30608 8192000000 10119861456 10579738624 1126468768 12.088 98 23 46 1 1210405376 78837904 5098704
09:40:51 63719 8192000000 10125060240 10600816640 1141926624 12.234 99 21 42 1 1220494544 73822848 4745072
09:42:55 30590 8192000000 10115943968 10579505152 1143767872 12.251 99 37 74 0 1193541888 41952256 7821760
09:44:30 24929 8192000000 10174294512 10612338688 1152793856 12.336 100 49 98 1 1270451680 107651136 10006688
09:45:57 73990 8192000000 10097218080 10537877504 1072646224 11.577 94 20 40 0 1118227936 41374064 4207648
09:48:24 71284 8192000000 10049174336 10492166144 1096769200 11.807 97 45 83 1 1131140512 25412208 8959104
09:50:36 3456 8192000000 10082594848 10509815808 1126689408 12.090 100 64 129 0 1181617728 41817264 13111056
09:51:40 40229 8192000000 10065427088 10530455552 1114760848 11.977 99 28 56 0 1196612832 76286960 5565024
09:53:28 22663 8192000000 10072477856 10506051584 1110568432 11.938 98 34 67 1 1163457264 46083776 6805056
09:54:53 91899 8192000000 10123414896 10593439744 1110539248 11.938 98 16 32 0 1220664128 106593056 3531824
09:57:32 45693 8192000000 10120041792 10589892608 1090185904 11.744 97 31 63 0 1217437152 120078976 7172272
09:59:19 50717 8192000000 10106569952 10571046912 1111820064 11.950 99 33 64 2 1212793888 93639408 7334416
10:01:23 55507 8192000000 10145872528 10586841088 1137395168 12.191 100 24 45 2 1238109600 95401264 5313168
10:03:24 51224 8192000000 10158818752 10590580736 1097304816 11.812 97 42 84 1 1222700592 116458816 8936960
10:05:18 92998 8192000000 10069783056 10556354560 1060002368 11.457 95 16 32 0 1145094160 81424032 3667760
10:07:54 23985 8192000000 10085362976 10532376576 1097298496 11.812 98 36 71 1 1185005008 80866096 6840416
10:09:21 97038 8192000000 10065522048 10509815808 1104862944 11.884 98 45 88 1 1159895200 45897616 9134640
10:12:06 62893 8192000000 10054196384 10530385920 1128331600 12.106 100 9 18 0 1178382288 48354064 1696624
10:14:12 78961 8192000000 10093548112 10551939072 1111976704 11.951 98 14 28 2 1171142944 55854752 3311488
10:16:35 75394 8192000000 10066074432 10532237312 1116022944 11.989 98 15 30 0 1178858096 59273328 3561824
10:19:14 55887 8192000000 10084852432 10514427904 1100141632 11.839 97 36 73 2 1154399376 46611296 7646448
10:21:14 22085 8192000000 10110146432 10549522432 1132957888 12.149 100 34 67 0 1202705264 62662816 7084560
10:22:40 49368 8192000000 10102810720 10548477952 1112738704 11.958 98 34 67 1 1171193296 51669680 6784912
10:24:43 86772 8192000000 10094643520 10572693504 1106173520 11.896 98 22 44 1 1185422896 74855632 4393744
10:27:13 95580 8192000000 10076871824 10523181056 1119830448 12.025 99 28 55 0 1174365456 48502128 6032880
10:29:51 65909 8192000000 10085541552 10545262592 1134186400 12.161 100 9 18 0 1189956704 53613312 2156992
10:32:04 97703 8192000000 10054571376 10570125312 1137668112 12.194 100 8 14 1 1181325136 42650416 1006608
10:34:42 44944 8192000000 10083815296 10552270848 1121047040 12.037 99 30 60 1 1186049984 58984592 6018352
10:36:28 42008 8192000000 10078427408 10525122560 1106139792 11.896 98 26 52 1 1157366640 46233024 4993824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-03-2022 10:38:32 to 31-03-2022 10:38:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-03-2022 10:38:49 to 31-03-2022 10:38:51.

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          218010
MON$OLDEST_ACTIVE               222491
MON$OLDEST_SNAPSHOT             222491
MON$NEXT_TRANSACTION            222491
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                       2073504
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.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
:283: 
:284: mscs-test-161.budzdorov.ru    Thu Mar 31 10:37:17 2022
:285:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:286: 
:287: 
:288: mscs-test-161.budzdorov.ru    Thu Mar 31 10:37:18 2022
:289:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:290: 
--- end of diff output ---

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

31.03.22 10:38:52. 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-03-31 10:38:55 No severe PSQL-related problems occured
31.03.22 10:38:56. End of report.