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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16138324 kB
MemAvailable 31511020 kB
Buffers 20 kB
Cached 15534968 kB
SwapCached 2488 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-05 04:37:46
DTS_END                         2022-05-05 04:37:14.0970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39548
TRN_ID                          223480
ATT_ID                          259
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: 9463.17 23122 1135581
customer order: creation 171.04 452 20525
customer order: refuse 14.78 372 1774
order to supplier: creation 45.76 1330 5491
order to supplier: removal 3.52 167075 422
invoice (draft): creation 44.99 2869 5399
invoice (draft): removal 53.73 1089 6448
invoice accept: apply 42.28 24282 5074
invoice accept: cancel 48.74 1222 5849
customer reserve: creation 3965.42 228 475850
customer reserve: removal 4766.74 95 572009
realization accept: apply 83.11 273 9973
realization accept: cancel 92.33 142 11080
payment from customer: creation 60.31 179 7237
payment from customer: removal 5.44 136 653
payment to supplier: creation 49.57 210 5948
payment to supplier: removal 3.04 155 365
service: total inventory turnovers 4.06 3955 487
service: total monetary turnovers 7.28 331 873
service: aggregate perf. data 1.00 14752 120
service: refresh index statistics 0.03 243293 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-05-2022 04:39:17 to 05-05-2022 04:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8310
TEST_TIME 2 6851
TEST_TIME 3 6119
TEST_TIME 4 6383
TEST_TIME 5 5821
TEST_TIME 6 6003
TEST_TIME 7 6455
TEST_TIME 8 6596
TEST_TIME 9 6601
TEST_TIME 10 6971
TEST_TIME 11 6818
TEST_TIME 12 6890
TEST_TIME 13 6840
TEST_TIME 14 6812
TEST_TIME 15 6919
TEST_TIME 16 6975
TEST_TIME 17 6973
TEST_TIME 18 7272
TEST_TIME 19 7469
TEST_TIME 20 7468
TEST_TIME 21 7771
TEST_TIME 22 8019
TEST_TIME 23 8217
TEST_TIME 24 8281
TEST_TIME 25 8559
TEST_TIME 26 9200
TEST_TIME 27 9767
TEST_TIME 28 10250
TEST_TIME 29 10567
TEST_TIME 30 10740
TEST_TIME 31 11106
TEST_TIME 32 11163
TEST_TIME 33 11227
TEST_TIME 34 11177
TEST_TIME 35 10992
TEST_TIME 36 11325
TEST_TIME 37 11113
TEST_TIME 38 10985
TEST_TIME 39 10775
TEST_TIME 40 10862
TEST_TIME 41 10960
TEST_TIME 42 10943
TEST_TIME 43 10939
TEST_TIME 44 10778
TEST_TIME 45 10634
TEST_TIME 46 10455
TEST_TIME 47 10450
TEST_TIME 48 10384
TEST_TIME 49 10256
TEST_TIME 50 10216
TEST_TIME 51 10210
TEST_TIME 52 10226
TEST_TIME 53 10215
TEST_TIME 54 10136
TEST_TIME 55 10004
TEST_TIME 56 9914
TEST_TIME 57 9836
TEST_TIME 58 9833
TEST_TIME 59 9786
TEST_TIME 60 9676
TEST_TIME 61 9633
TEST_TIME 62 9575
TEST_TIME 63 9584
TEST_TIME 64 9591
TEST_TIME 65 9570
TEST_TIME 66 9579
TEST_TIME 67 9608
TEST_TIME 68 9579
TEST_TIME 69 9520
TEST_TIME 70 9600
TEST_TIME 71 9582
TEST_TIME 72 9553
TEST_TIME 73 9523
TEST_TIME 74 9537
TEST_TIME 75 9640
TEST_TIME 76 9719
TEST_TIME 77 9731
TEST_TIME 78 9758
TEST_TIME 79 9705
TEST_TIME 80 9810
TEST_TIME 81 9843
TEST_TIME 82 9839
TEST_TIME 83 9784
TEST_TIME 84 9767
TEST_TIME 85 9742
TEST_TIME 86 9674
TEST_TIME 87 9692
TEST_TIME 88 9690
TEST_TIME 89 9737
TEST_TIME 90 9814
TEST_TIME 91 9797
TEST_TIME 92 9854
TEST_TIME 93 9834
TEST_TIME 94 9784
TEST_TIME 95 9766
TEST_TIME 96 9746
TEST_TIME 97 9701
TEST_TIME 98 9647
TEST_TIME 99 9589
TEST_TIME 100 9567
TEST_TIME 101 9547
TEST_TIME 102 9545
TEST_TIME 103 9587
TEST_TIME 104 9528
TEST_TIME 105 9492
TEST_TIME 106 9525
TEST_TIME 107 9512
TEST_TIME 108 9490
TEST_TIME 109 9487
TEST_TIME 110 9466
TEST_TIME 111 9480
TEST_TIME 112 9508
TEST_TIME 113 9508
TEST_TIME 114 9529
TEST_TIME 115 9493
TEST_TIME 116 9482
TEST_TIME 117 9464
TEST_TIME 118 9509
TEST_TIME 119 9544
TEST_TIME 120 9488

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-05-2022 04:39:21 to 05-05-2022 04:39:24.

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 1573162 1573162 0 0.00 0 22690 13 0 0
doc_list_biud 1573162 1573162 0 0.00 0 65996 41 0 0
sp_add_invoice_to_stock 5163 5074 89 1.72 49 239280 24282 0 89
sp_cancel_adding_invoice 5849 5849 0 0.00 7 82729 1222 0 0
sp_cancel_client_order 1774 1774 0 0.00 6 10260 372 0 0
sp_cancel_customer_reserve 572009 572009 0 0.00 1 24530 96 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 4 6449 137 0 0
sp_cancel_pay_to_supplier 365 365 0 0.00 4 15992 156 0 0
sp_cancel_supplier_invoice 6461 6448 13 0.20 3 66000 1090 0 13
sp_cancel_supplier_order 422 422 0 0.00 8 1567433 167075 0 0
sp_cancel_write_off 11080 11080 0 0.00 2 12363 143 0 0
sp_client_order 20525 20525 0 0.00 6 26313 453 0 0
sp_customer_reserve 475850 475850 0 0.00 0 135646 228 0 0
sp_fill_shopping_cart 31411 31411 0 0.00 1 17958 77 0 0
sp_fill_shopping_cart_clo_res 475819 475819 0 0.00 0 27946 18 0 0
sp_get_clo_for_invoice 5023 5023 0 0.00 0 24997 283 0 0
sp_kill_cost_storno 17947 17947 0 0.00 0 22673 75 0 0
sp_kill_qstorno_ret_qs2qd 1161968 1161968 0 0.00 0 65995 42 0 0
sp_kill_qty_storno 612573 612573 0 0.00 0 65995 97 0 0
sp_lock_selected_doc 20723 20723 0 0.00 0 10511 8 0 0
sp_make_cost_storno 28176 28176 0 0.00 0 20952 83 0 0
sp_multiply_rows_for_pdistr 28176 28176 0 0.00 0 10421 38 0 0
sp_multiply_rows_for_qdistr 20525 20525 0 0.00 0 19576 179 0 0
sp_pay_from_customer 7237 7237 0 0.00 5 19873 179 0 0
sp_pay_to_supplier 5948 5948 0 0.00 7 24627 210 0 0
sp_payment_common 13185 13185 0 0.00 2 9419 45 0 0
sp_qd_handle_on_cancel_clo 1774 1774 0 0.00 0 10253 304 0 0
sp_qd_handle_on_invoice_upd_sts 10867 10867 0 0.00 0 20740 510 0 0
sp_qd_handle_on_reserve_upd_sts 9973 9973 0 0.00 0 20445 60 0 0
sp_reserve_write_off 9973 9973 0 0.00 9 20536 273 0 0
sp_supplier_invoice 5399 5399 0 0.00 10 85510 2870 0 0
sp_supplier_order 5491 5491 0 0.00 8 26937 1330 0 0
srv_aggregate_perf_data 120 120 0 0.00 2877 187671 14753 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 735 130101 37710 0 0
srv_find_qd_qs_mism 1085783 1085783 0 0.00 0 8541 9 0 0
srv_make_invnt_saldo 487 487 0 0.00 60 111760 3955 0 0
srv_make_money_saldo 873 873 0 0.00 1 13296 331 0 0
srv_recalc_idx_stat 4 4 0 0.00 80227 349654 243293 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5074 5074 0 0.00 1 23344 889 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 7162 479 0 0
t$perf-norm:sp_cancel_client_order 1774 1774 0 0.00 0 5975 33 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 9517 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 579 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 365 365 0 0.00 0 472 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 2177 34 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 109266 6018 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 1125 18 0 0
t$perf-norm:sp_client_order 20525 20525 0 0.00 0 28282 38 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 3028 17 0 0
t$perf-norm:sp_pay_from_customer 7237 7237 0 0.00 0 6222 18 0 0
t$perf-norm:sp_pay_to_supplier 5948 5948 0 0.00 0 3544 18 0 0
t$perf-norm:sp_reserve_write_off 9973 9973 0 0.00 0 7603 23 0 0
t$perf-norm:sp_supplier_invoice 5399 5399 0 0.00 0 5834 28 0 0
t$perf-norm:sp_supplier_order 5491 5491 0 0.00 0 5446 34 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 520 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 227 7 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 2075 24 0 0
t$perf-norm:srv_make_money_saldo 873 873 0 0.00 0 1024 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 180 76 0 0
v_add_invoice_to_stock 10214 10036 178 1.74 0 15399 27 0 178
v_all_customers 38202 38202 0 0.00 0 118 3 0 0
v_all_suppliers 31136 31136 0 0.00 0 281 4 0 0
v_all_wares 41050 41050 0 0.00 0 78 4 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 588 14 0 0
v_cancel_client_order 3548 3548 0 0.00 0 6866 12 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 5223 18 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 2896 11 0 0
v_cancel_payment_to_supplier 730 730 0 0.00 0 15899 34 0 0
v_cancel_supplier_invoice 788 762 26 3.29 0 312 19 0 26
v_cancel_supplier_order 844 844 0 0.00 0 14106 30 0 0
v_cancel_write_off 2000 2000 0 0.00 0 7068 26 0 0
v_max_id_clo_ord 5491 5491 0 0.00 0 139 4 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 5693 17 0 0
v_max_id_ord_sup 5395 5395 0 0.00 0 5774 7 0 0
v_max_non_paid_invoice 1266 1266 0 0.00 0 1369 11 0 0
v_max_non_paid_realizn 3514 3514 0 0.00 0 3642 8 0 0
v_min_id_clo_ord 5491 5491 0 0.00 0 2396 8 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 883 8 0 0
v_min_id_ord_sup 5395 5395 0 0.00 0 2944 8 0 0
v_min_non_paid_invoice 5948 5948 0 0.00 0 8845 17 0 0
v_min_non_paid_realizn 7237 7237 0 0.00 0 8178 18 0 0
v_our_firm 10294 10294 0 0.00 0 121 4 0 0
v_reserve_write_off 19946 19946 0 0.00 0 4538 10 0 0
x_lock_depdocs_on_canc_invoice 11916 11916 0 0.00 0 13941 118 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 15031 393 0 0
x_make_qstorno_1000_1200 5491 5491 0 0.00 2 26919 1172 0 0
x_make_qstorno_1200_2000 5399 5399 0 0.00 5 79940 2664 0 0
x_make_qstorno_2100_3300 475850 475850 0 0.00 0 135643 199 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-05-2022 04:39:25 to 05-05-2022 04:39:29.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:17 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:19 21172 8192000000 9719447456 10020442112 801897584 8.916 100 83 166 0 829545424 7114416 20533424
02:09:49 15439 8192000000 9761904528 10069483520 835851072 9.258 100 88 176 0 869980608 12996112 21133424
02:11:07 20748 8192000000 9795002944 10112319488 859760368 9.498 100 80 160 1 898712896 20271280 18681248
02:12:32 6612 8192000000 9859757008 10194116608 914916128 10.046 100 83 164 0 976063440 43013536 18133776
02:13:41 4677 8192000000 9932023776 10271903744 957922096 10.469 100 89 176 0 1035748960 59789712 18037152
02:14:49 2957 8192000000 9960834896 10314719232 993361232 10.814 100 90 173 0 1080489072 69118032 18009808
02:15:56 1227 8192000000 10014125936 10375942144 1022737616 11.098 100 88 170 1 1133915120 93795760 17381744
02:16:59 988 8192000000 10005982896 10376314880 1048446704 11.346 100 91 177 0 1129026112 62364192 18215216
02:18:01 1370 8192000000 10052626880 10432344064 1061249616 11.468 100 94 184 0 1171057408 91962192 17845600
02:19:04 1063 8192000000 10063794864 10441347072 1068951824 11.542 100 95 182 0 1180468144 93125648 18390672
02:20:07 1199 8192000000 10056396096 10439106560 1078384048 11.632 100 98 190 0 1172259248 75064704 18810496
02:21:08 1320 8192000000 10055956624 10438479872 1084480672 11.690 100 91 170 1 1177169200 76002192 16686336
02:22:12 1113 8192000000 10045556688 10432466944 1089476720 11.738 100 94 174 1 1166248864 57495728 19276416
02:23:13 1136 8192000000 10072966336 10462089216 1093047568 11.772 100 93 179 0 1191899664 79737216 19114880
02:24:15 1093 8192000000 10047881968 10436714496 1097713360 11.816 100 78 151 0 1167150320 55353872 14083088
02:25:17 1193 8192000000 10062556144 10451542016 1103126320 11.867 100 95 181 0 1183421472 61508464 18786688
02:26:21 954 8192000000 10066303504 10457292800 1109360496 11.926 100 91 178 0 1189393728 60955344 19077888
02:27:23 10149 8192000000 10047647808 10452045824 1099445264 11.832 100 75 147 1 1172942032 57687472 15809296
02:28:39 6483 8192000000 10070319904 10463051776 1100393488 11.841 100 65 122 3 1184133136 72119856 11619792
02:29:46 1121 8192000000 10013989856 10396921856 1071326896 11.565 100 91 176 0 1130359408 42270720 16761792
02:30:48 1033 8192000000 10060890592 10441957376 1076695648 11.616 100 94 190 0 1180447152 83960992 19790512
02:31:51 1351 8192000000 10033041792 10413391872 1067505616 11.528 100 82 157 1 1157612032 73509200 16597216
02:32:54 5785 8192000000 9991043744 10375876608 1046814784 11.330 99 47 94 0 1115091920 59191840 9085296
02:34:01 3176 8192000000 9940033808 10309103616 1005497536 10.932 97 72 133 4 1057936432 37256624 15182272
02:35:05 1159 8192000000 9973690752 10342641664 1038209104 11.247 100 91 175 1 1095981056 40922096 16849856
02:36:08 6367 8192000000 10007758704 10391420928 1049366320 11.355 100 68 129 1 1128282560 65936416 12979824
02:37:16 5664 8192000000 10018655168 10401353728 1062765984 11.483 100 78 151 0 1140320240 62263984 15290272
02:38:29 18395 8192000000 10024007872 10409672704 1086174496 11.706 100 60 117 1 1143396912 45821232 11401184
02:39:56 3314 8192000000 10063176640 10454700032 1104154576 11.877 100 75 135 3 1181653536 63080048 14418912
02:41:03 14421 8192000000 10117767120 10512265216 1110033392 11.933 100 76 147 0 1220595520 95216832 15345296
02:42:23 11211 8192000000 10132075216 10532126720 1123204608 12.057 100 60 120 1 1243827344 107847616 12775120
02:43:42 2216 8192000000 10207730832 10606157824 1137756880 12.194 100 81 156 0 1326494144 171621120 17116144
02:44:49 1994 8192000000 10232179808 10627088384 1151534112 12.324 100 86 165 1 1344516144 176042768 16939264
02:45:54 2709 8192000000 10276049424 10675900416 1160748608 12.410 100 92 182 0 1389869792 210230240 18890944
02:47:05 1175 8192000000 10319739104 10726596608 1172461136 12.520 100 90 177 1 1430705184 238312912 19931136
02:48:11 965 8192000000 10316166096 10719481856 1179875984 12.589 100 82 152 0 1425998656 230040400 16082272
02:49:19 730 8192000000 10383668848 10786332672 1194266704 12.723 100 94 185 1 1495057216 279838896 20951616
02:50:22 2192 8192000000 10425289424 10829135872 1195303152 12.733 100 92 181 0 1537858768 321187376 21368240
02:51:32 1748 8192000000 10452171744 10855337984 1195382992 12.733 100 93 179 2 1564674224 348147184 21144048
02:52:38 744 8192000000 10525982496 10939686912 1198088768 12.759 100 96 186 0 1631762240 412351680 21321792
02:53:42 1343 8192000000 10559206560 10966749184 1190319648 12.686 99 96 192 1 1667671664 454692368 22659648
02:54:46 1233 8192000000 10552282736 11001233408 1191786912 12.700 99 95 188 0 1655965952 441424912 22754128
02:55:51 994 8192000000 10579550832 11014070272 1207814640 12.849 100 98 192 1 1686526848 455820080 22892128
02:56:56 1072 8192000000 10637032592 11044483072 1216763216 12.932 100 96 190 3 1740351584 501280208 22308160
02:57:59 769 8192000000 10653457936 11081818112 1193715728 12.718 100 96 191 0 1757312592 539546448 24050416
02:59:02 3708 8192000000 10618825136 11082260480 1193568624 12.717 100 66 132 2 1725968768 515251776 17148368
03:00:07 1616 8192000000 10641117760 11089842176 1176912288 12.561 100 65 130 1 1748100256 554507088 16680880
03:01:20 15145 8192000000 10595647120 11035885568 1167159376 12.470 100 43 84 0 1700297904 521539280 11599248
03:02:36 6088 8192000000 10542857168 11057762304 1143283440 12.246 99 34 68 0 1639387280 486314352 9789488
03:03:44 8717 8192000000 10389321904 10877042688 1134683904 12.165 100 51 87 1 1497169328 351881120 10604304
03:04:55 12541 8192000000 10329665424 10795405312 1139367424 12.210 100 37 73 0 1456134576 308106832 8660320
03:06:09 28937 8192000000 10227320112 10673438720 1115338208 11.983 98 25 50 1 1310537072 189272304 5926560
03:07:43 54330 8192000000 10153050144 10602061824 1139545408 12.211 99 15 28 1 1290270096 147371648 3353040
03:09:39 59042 8192000000 10078527344 10549444608 1120382496 12.031 98 18 36 0 1208944336 84576864 3984976
03:11:39 19568 8192000000 10123068320 10565799936 1154312992 12.350 100 29 58 1 1229387248 69203616 5870640
03:12:59 68559 8192000000 10077506384 10574311424 1123050976 12.056 98 9 18 2 1174361376 49439072 1871328
03:15:07 70239 8192000000 10121187152 10552684544 1157043936 12.376 100 25 50 0 1206384528 43866560 5474032
03:17:20 86742 8192000000 10132220944 10612072448 1112745296 11.958 97 6 12 2 1213585248 99331088 1508864
03:20:10 96830 8192000000 10078267136 10517544960 1119545968 12.023 97 16 32 0 1153139856 30694480 2899408
03:22:48 80898 8192000000 10098361760 10540937216 1128539792 12.108 99 42 75 1 1189176112 52097600 8538720
03:25:13 98453 8192000000 10100804080 10520530944 1117239024 12.001 97 87 157 4 1169296672 34905504 17152144
03:27:52 22157 8192000000 10144152496 10582274048 1136741296 12.185 99 44 84 0 1214925296 68976880 9207120
03:29:25 27032 8192000000 10072395584 10507497472 1118112896 12.009 98 40 78 0 1165622848 38773552 8736400
03:30:53 118531 8192000000 10099433024 10556481536 1145554480 12.268 100 13 26 0 1189793872 41671152 2568240
03:33:54 69270 8192000000 10136475824 10606448640 1156142384 12.367 100 11 23 1 1244216704 85512992 2561328
03:36:21 64846 8192000000 10077659600 10515759104 1118221552 12.010 97 20 39 0 1153725712 31467216 4036944
03:38:27 86053 8192000000 10153365760 10648522752 1164817872 12.448 100 15 30 0 1278160608 109921584 3421152
03:41:28 26498 8192000000 10074538480 10524524544 1125509904 12.079 98 12 24 0 1152055680 24454640 2091136
03:43:01 20555 8192000000 10100438448 10547785728 1119889376 12.026 98 33 65 1 1182114720 55684544 6540800
03:44:24 27501 8192000000 10159310000 10630692864 1145193536 12.264 100 40 80 1 1245531808 91978096 8360176
03:46:31 7611 8192000000 10216152272 10651521024 1157268016 12.378 99 50 99 1 1305832304 137331424 11232864
03:47:42 28700 8192000000 10239680096 10691706880 1149255424 12.303 99 29 58 1 1315082496 158937184 6889888
03:49:11 37189 8192000000 10261217648 10704474112 1154682448 12.353 100 28 56 0 1349244464 187586512 6975504
03:50:50 20832 8192000000 10279517792 10710355968 1111348352 11.945 97 29 57 1 1354339200 236393984 6596864
03:52:22 47142 8192000000 10267666240 10712592384 1143024032 12.244 100 27 52 1 1356643584 207409984 6209568
03:54:13 34460 8192000000 10265186944 10767441920 1145358016 12.266 100 26 52 0 1351419600 199876544 6185040
03:55:54 110608 8192000000 10206131616 10681495552 1142808864 12.242 100 39 78 0 1366894432 215275344 8810224
03:59:08 103747 8192000000 10085887024 10530779136 1072781152 11.579 94 20 40 1 1130718064 53880256 4056656
04:01:59 56384 8192000000 10114188064 10543857664 1118262112 12.011 99 32 64 1 1177706320 52334656 7109552
04:03:59 69886 8192000000 10195559344 10680856576 1120241376 12.029 99 28 56 1 1273547728 147021824 6284528
04:06:16 57391 8192000000 10135237072 10594598912 1111410208 11.946 98 33 65 1 1201546944 83619536 6517200
04:08:15 75441 8192000000 10106776768 10573295616 1112331440 11.954 98 34 66 1 1183617152 64696176 6589536
04:10:32 68721 8192000000 10097760256 10526777344 1118659712 12.014 98 35 70 0 1167310208 41101600 7548896
04:12:51 130100 8192000000 10067855008 10507935744 1126706928 12.090 99 35 70 0 1176259392 42455104 7097360
04:16:08 46922 8192000000 10132537696 10573430784 1111733136 11.949 97 35 68 1 1205232416 86486336 7012944
04:17:55 66776 8192000000 10148207936 10623098880 1131758400 12.138 99 21 42 1 1260956192 124370976 4826816
04:20:03 115424 8192000000 10124349520 10605252608 1144201792 12.255 100 16 32 0 1223749472 75933024 3614656
04:23:07 55552 8192000000 10085674240 10587516928 1130446896 12.126 99 15 26 0 1196534704 63294064 2793744
04:25:08 30764 8192000000 10063184896 10519371776 1131712272 12.138 99 23 46 2 1171215616 34368288 5135056
04:26:39 36938 8192000000 10086883168 10538299392 1126288496 12.086 99 19 38 0 1185432848 55008960 4135392
04:28:19 33651 8192000000 10100548848 10554425344 1129203424 12.114 99 23 43 2 1185232528 50728944 5300160
04:29:54 43816 8192000000 10097857152 10549075968 1133690432 12.156 99 15 30 1 1200382800 63356528 3335840
04:31:39 33511 8192000000 10070787760 10498510848 1134171440 12.161 99 44 86 2 1158140928 14833136 9136352
04:33:19 1962 8192000000 10100543120 10527952896 1139424096 12.210 100 66 127 0 1192705072 41505952 11775024
04:34:25 23169 8192000000 10122076336 10543534080 1103976976 11.875 98 66 132 0 1194536752 77172192 13387584
04:36:06 55954 8192000000 10077278784 10558386176 1088303984 11.727 96 15 30 1 1156137616 65021296 2812336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 05-05-2022 04:39:31 to 05-05-2022 04:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-05-2022 04:39:48 to 05-05-2022 04:39:50.

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          227097
MON$OLDEST_ACTIVE               227098
MON$OLDEST_SNAPSHOT             227098
MON$NEXT_TRANSACTION            227098
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                       2081312
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             780
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'

05.05.22 04:39:51. 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-05 04:39:54 No severe PSQL-related problems occured
05.05.22 04:39:55. End of report.