Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.06.2022 13: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 15457840 kB
MemAvailable 31324416 kB
Buffers 0 kB
Cached 16244136 kB
SwapCached 2448 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-06-08 13:37:17
DTS_END                         2022-06-08 13:37:17.3540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57506
TRN_ID                          222000
ATT_ID                          660
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: 8970.88 24683 1076506
customer order: creation 178.12 510 21374
customer order: refuse 14.98 472 1798
order to supplier: creation 46.22 1531 5546
order to supplier: removal 3.55 183276 426
invoice (draft): creation 47.72 3548 5726
invoice (draft): removal 49.33 1191 5919
invoice accept: apply 42.73 28148 5127
invoice accept: cancel 45.21 1604 5425
customer reserve: creation 3931.11 266 471733
customer reserve: removal 4314.32 118 517718
realization accept: apply 85.98 334 10317
realization accept: cancel 79.16 199 9499
payment from customer: creation 60.20 212 7224
payment from customer: removal 6.00 144 720
payment to supplier: creation 50.22 228 6026
payment to supplier: removal 3.38 139 406
service: total inventory turnovers 3.50 5868 420
service: total monetary turnovers 8.24 419 989
service: aggregate perf. data 0.91 18880 109
service: refresh index statistics 0.03 246566 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-06-2022 13:38:38 to 08-06-2022 13:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9374
TEST_TIME 2 7468
TEST_TIME 3 6760
TEST_TIME 4 7171
TEST_TIME 5 6610
TEST_TIME 6 6299
TEST_TIME 7 6312
TEST_TIME 8 6576
TEST_TIME 9 6683
TEST_TIME 10 6779
TEST_TIME 11 6777
TEST_TIME 12 6897
TEST_TIME 13 7094
TEST_TIME 14 7159
TEST_TIME 15 7354
TEST_TIME 16 7478
TEST_TIME 17 7463
TEST_TIME 18 7500
TEST_TIME 19 7500
TEST_TIME 20 7839
TEST_TIME 21 7931
TEST_TIME 22 8125
TEST_TIME 23 8169
TEST_TIME 24 8234
TEST_TIME 25 8380
TEST_TIME 26 8695
TEST_TIME 27 8662
TEST_TIME 28 8821
TEST_TIME 29 8747
TEST_TIME 30 8687
TEST_TIME 31 8782
TEST_TIME 32 8804
TEST_TIME 33 9030
TEST_TIME 34 9190
TEST_TIME 35 9188
TEST_TIME 36 9272
TEST_TIME 37 9280
TEST_TIME 38 9865
TEST_TIME 39 9787
TEST_TIME 40 9894
TEST_TIME 41 10027
TEST_TIME 42 10554
TEST_TIME 43 10683
TEST_TIME 44 10826
TEST_TIME 45 10775
TEST_TIME 46 10771
TEST_TIME 47 10770
TEST_TIME 48 10633
TEST_TIME 49 10498
TEST_TIME 50 10446
TEST_TIME 51 10366
TEST_TIME 52 10301
TEST_TIME 53 10220
TEST_TIME 54 10169
TEST_TIME 55 10112
TEST_TIME 56 9985
TEST_TIME 57 9925
TEST_TIME 58 9882
TEST_TIME 59 9827
TEST_TIME 60 9757
TEST_TIME 61 9822
TEST_TIME 62 9704
TEST_TIME 63 9598
TEST_TIME 64 9829
TEST_TIME 65 9758
TEST_TIME 66 9708
TEST_TIME 67 9662
TEST_TIME 68 9699
TEST_TIME 69 9744
TEST_TIME 70 9732
TEST_TIME 71 9714
TEST_TIME 72 9744
TEST_TIME 73 9713
TEST_TIME 74 9725
TEST_TIME 75 9667
TEST_TIME 76 9649
TEST_TIME 77 9696
TEST_TIME 78 9778
TEST_TIME 79 9737
TEST_TIME 80 9799
TEST_TIME 81 9697
TEST_TIME 82 9658
TEST_TIME 83 9622
TEST_TIME 84 9564
TEST_TIME 85 9582
TEST_TIME 86 9531
TEST_TIME 87 9478
TEST_TIME 88 9481
TEST_TIME 89 9467
TEST_TIME 90 9415
TEST_TIME 91 9357
TEST_TIME 92 9350
TEST_TIME 93 9319
TEST_TIME 94 9271
TEST_TIME 95 9288
TEST_TIME 96 9364
TEST_TIME 97 9353
TEST_TIME 98 9387
TEST_TIME 99 9351
TEST_TIME 100 9402
TEST_TIME 101 9405
TEST_TIME 102 9396
TEST_TIME 103 9348
TEST_TIME 104 9455
TEST_TIME 105 9452
TEST_TIME 106 9443
TEST_TIME 107 9414
TEST_TIME 108 9359
TEST_TIME 109 9319
TEST_TIME 110 9288
TEST_TIME 111 9269
TEST_TIME 112 9236
TEST_TIME 113 9255
TEST_TIME 114 9201
TEST_TIME 115 9169
TEST_TIME 116 9125
TEST_TIME 117 9078
TEST_TIME 118 9060
TEST_TIME 119 9049
TEST_TIME 120 8999

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-06-2022 13:38:41 to 08-06-2022 13:38:45.

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 1511593 1511593 0 0.00 0 21249 16 0 0
doc_list_biud 1511590 1511590 0 0.00 0 28276 47 0 0
sp_add_invoice_to_stock 5254 5127 127 2.41 51 186520 28149 0 127
sp_cancel_adding_invoice 5425 5425 0 0.00 7 101796 1605 0 0
sp_cancel_client_order 1798 1798 0 0.00 7 9541 472 0 0
sp_cancel_customer_reserve 517718 517718 0 0.00 1 22945 119 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 4 5199 145 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 4 1819 140 0 0
sp_cancel_supplier_invoice 5929 5919 10 0.16 3 15642 1191 0 10
sp_cancel_supplier_order 426 426 0 0.00 19 1656157 183276 0 0
sp_cancel_write_off 9499 9499 0 0.00 2 7843 199 0 0
sp_client_order 21374 21374 0 0.00 5 21263 511 0 0
sp_customer_reserve 471733 471733 0 0.00 1 74467 266 0 0
sp_fill_shopping_cart 32639 32639 0 0.00 2 21591 101 0 0
sp_fill_shopping_cart_clo_res 471716 471716 0 0.00 0 11853 18 0 0
sp_get_clo_for_invoice 5089 5089 0 0.00 0 19484 277 0 0
sp_kill_cost_storno 16050 16050 0 0.00 0 7578 91 0 0
sp_kill_qstorno_ret_qs2qd 1051280 1051280 0 0.00 0 22865 50 0 0
sp_kill_qty_storno 556190 556190 0 0.00 0 28274 117 0 0
sp_lock_selected_doc 21293 21293 0 0.00 0 8144 8 0 0
sp_make_cost_storno 28655 28655 0 0.00 0 21230 98 0 0
sp_multiply_rows_for_pdistr 28655 28655 0 0.00 0 7419 42 0 0
sp_multiply_rows_for_qdistr 21374 21374 0 0.00 0 11363 172 0 0
sp_pay_from_customer 7224 7224 0 0.00 6 15111 213 0 0
sp_pay_to_supplier 6026 6026 0 0.00 6 14492 229 0 0
sp_payment_common 13250 13250 0 0.00 2 7336 58 0 0
sp_qd_handle_on_cancel_clo 1798 1798 0 0.00 1 9484 384 0 0
sp_qd_handle_on_invoice_upd_sts 10513 10513 0 0.00 1 28272 648 0 0
sp_qd_handle_on_reserve_upd_sts 10317 10317 0 0.00 0 12948 76 0 0
sp_reserve_write_off 10317 10317 0 0.00 9 13556 335 0 0
sp_supplier_invoice 5726 5726 0 0.00 11 110845 3548 0 0
sp_supplier_order 5546 5546 0 0.00 7 30694 1531 0 0
srv_aggregate_perf_data 109 109 0 0.00 2902 184646 18881 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 781 116422 30538 0 0
srv_find_qd_qs_mism 1026712 1026712 0 0.00 0 13657 12 0 0
srv_make_invnt_saldo 420 420 0 0.00 51 110839 5869 0 0
srv_make_money_saldo 989 989 0 0.00 0 14130 420 0 0
srv_recalc_idx_stat 4 4 0 0.00 77326 381108 246566 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5129 5129 0 0.00 1 10243 1258 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 2 7996 676 0 0
t$perf-norm:sp_cancel_client_order 1798 1798 0 0.00 0 1558 32 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 2601 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 1048 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 609 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 3568 58 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 103434 9218 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 2836 32 0 0
t$perf-norm:sp_client_order 21374 21374 0 0.00 0 7442 46 0 0
t$perf-norm:sp_customer_reserve 1053 1053 0 0.00 0 4352 24 0 0
t$perf-norm:sp_pay_from_customer 7224 7224 0 0.00 0 6039 26 0 0
t$perf-norm:sp_pay_to_supplier 6026 6026 0 0.00 0 10152 28 0 0
t$perf-norm:sp_reserve_write_off 10317 10317 0 0.00 0 4703 27 0 0
t$perf-norm:sp_supplier_invoice 5726 5726 0 0.00 0 2689 36 0 0
t$perf-norm:sp_supplier_order 5546 5546 0 0.00 0 7536 40 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 1080 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 359 15 0 0
t$perf-norm:srv_make_invnt_saldo 420 420 0 0.00 0 498 18 0 0
t$perf-norm:srv_make_money_saldo 989 989 0 0.00 0 346 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 213 82 0 0
v_add_invoice_to_stock 10428 10174 254 2.43 0 6447 32 0 254
v_all_customers 39084 39084 0 0.00 0 92 5 0 0
v_all_suppliers 31188 31188 0 0.00 0 95 5 0 0
v_all_wares 42748 42748 0 0.00 0 65 5 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 1346 17 0 0
v_cancel_client_order 3596 3596 0 0.00 0 3580 12 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 3457 15 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 2197 17 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 225 12 0 0
v_cancel_supplier_invoice 768 748 20 2.60 0 2366 27 0 20
v_cancel_supplier_order 852 852 0 0.00 0 1917 16 0 0
v_cancel_write_off 2072 2072 0 0.00 0 5195 34 0 0
v_max_id_clo_ord 5546 5546 0 0.00 0 410 6 0 0
v_max_id_clo_res 1053 1053 0 0.00 0 2360 20 0 0
v_max_id_ord_sup 5719 5719 0 0.00 0 1466 7 0 0
v_max_non_paid_invoice 1696 1696 0 0.00 0 954 10 0 0
v_max_non_paid_realizn 3772 3772 0 0.00 0 4054 10 0 0
v_min_id_clo_ord 5546 5546 0 0.00 0 2567 11 0 0
v_min_id_clo_res 1053 1053 0 0.00 0 1657 12 0 0
v_min_id_ord_sup 5719 5719 0 0.00 0 6303 11 0 0
v_min_non_paid_invoice 6026 6026 0 0.00 0 6886 20 0 0
v_min_non_paid_realizn 7224 7224 0 0.00 0 7328 24 0 0
v_our_firm 10568 10568 0 0.00 0 51 6 0 0
v_reserve_write_off 20634 20634 0 0.00 0 6497 13 0 0
x_lock_depdocs_on_canc_invoice 10970 10970 0 0.00 0 12413 135 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 3404 397 0 0
x_make_qstorno_1000_1200 5546 5546 0 0.00 2 30679 1311 0 0
x_make_qstorno_1200_2000 5726 5726 0 0.00 6 107288 3292 0 0
x_make_qstorno_2100_3300 471733 471733 0 0.00 0 74462 233 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-06-2022 13:38:46 to 08-06-2022 13:38:50.

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
11:07:21 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:21 17994 8192000000 9710161968 10007601152 786925008 8.764 100 80 158 1 814297184 6885328 20486848
11:09:44 29253 8192000000 9767413696 10077315072 842425088 9.324 100 91 182 0 874969744 10557216 21987440
11:11:20 16193 8192000000 9803768512 10123911168 873997952 9.640 100 81 162 0 909068640 16566864 18503824
11:12:39 16535 8192000000 9866807264 10199646208 922403168 10.120 100 85 170 0 971284448 30439984 18441296
11:13:58 8430 8192000000 9928931520 10271244288 956579472 10.456 100 88 175 0 1033187504 57694848 18913184
11:15:14 4089 8192000000 9954269552 10306453504 989342816 10.775 100 85 164 0 1076517120 70003888 17170416
11:16:22 1391 8192000000 9985691296 10347315200 1020365184 11.076 100 95 181 0 1109927824 71135600 18427040
11:17:26 1562 8192000000 10012314448 10378555392 1039081328 11.256 100 90 178 1 1141543136 84287408 18174400
11:18:30 1036 8192000000 10018980064 10390724608 1054730784 11.406 100 94 186 0 1142057280 68867312 18459184
11:19:32 1761 8192000000 10022609712 10399145984 1064657712 11.501 100 83 160 1 1144068448 63385840 16024896
11:20:37 1322 8192000000 10054311760 10430185472 1079533344 11.643 100 96 181 2 1175860256 79494976 16831936
11:21:40 1530 8192000000 10047757824 10432425984 1082682832 11.673 100 84 159 1 1173770336 74310848 16776656
11:22:44 1349 8192000000 10041005232 10432528384 1088948560 11.733 100 73 137 0 1160898944 58590896 13359488
11:23:47 755 8192000000 10053790288 10445910016 1092751488 11.769 100 85 160 3 1177837744 67313552 17772704
11:24:49 1256 8192000000 10050953552 10440605696 1097047200 11.810 100 92 180 1 1177221776 59868864 20305712
11:25:52 1245 8192000000 10079496464 10466443264 1098877200 11.827 100 96 191 0 1198249120 81113408 18258512
11:26:55 1781 8192000000 10084426096 10484674560 1107838624 11.912 100 83 166 0 1207087024 82882912 16365488
11:27:59 1338 8192000000 10076834192 10466029568 1101511536 11.852 100 98 195 0 1195759280 75932464 18315280
11:29:02 2789 8192000000 10066902160 10453127168 1101387152 11.851 100 88 161 3 1180596800 63811600 15398048
11:30:06 1281 8192000000 10063576800 10454437888 1088322864 11.727 100 84 160 0 1185017968 79736464 16958640
11:31:09 1269 8192000000 10033817616 10415931392 1068853872 11.541 100 90 168 0 1154609712 68100512 17655328
11:32:11 1453 8192000000 10024356912 10412359680 1065314352 11.507 99 86 161 2 1154103344 68783840 20005152
11:33:13 1087 8192000000 10060873872 10441658368 1072649072 11.577 99 95 186 0 1176810368 85188592 18972704
11:34:15 1219 8192000000 10000133776 10382049280 1054585152 11.405 100 74 132 0 1130048256 61567856 13895248
11:35:17 5796 8192000000 10001386160 10380402688 1032700016 11.194 98 67 133 1 1116008976 70790320 12518640
11:36:24 1026 8192000000 9990810896 10362507264 1042937888 11.293 100 93 181 1 1109768736 49311024 17519824
11:37:29 18842 8192000000 9994625872 10377687040 1053282000 11.392 100 64 104 1 1119404256 56596224 9526032
11:38:58 8236 8192000000 10012889520 10397249536 1070501840 11.557 100 65 123 1 1134133024 51605584 12025600
11:40:11 10540 8192000000 10065254896 10452779008 1089576128 11.739 100 69 130 0 1190055200 87833136 12645936
11:41:35 4845 8192000000 10088726128 10483777536 1100905808 11.846 100 77 146 1 1216605952 100423040 15277104
11:42:44 23221 8192000000 10114559232 10515402752 1107502080 11.909 100 64 125 0 1228934848 108019376 13413392
11:44:13 6158 8192000000 10144018944 10550353920 1120626736 12.033 100 78 151 0 1256012000 118342032 17043232
11:45:26 947 8192000000 10164932128 10563534848 1129686320 12.118 100 96 180 0 1277995360 129316880 18992160
11:46:30 6700 8192000000 10166975264 10568499200 1139037248 12.206 100 68 134 1 1283552672 129964848 14550576
11:47:42 1414 8192000000 10195875248 10603966464 1143174032 12.245 100 91 176 0 1310840992 148309728 19357232
11:48:49 2797 8192000000 10210204448 10620514304 1154145728 12.348 100 92 180 2 1316659984 142171248 20343008
11:49:58 1320 8192000000 10240820912 10657894400 1161562960 12.418 100 83 158 1 1355089888 175458464 18068464
11:51:04 1497 8192000000 10274057968 10682449920 1173881600 12.533 100 94 178 0 1387433568 192814304 20737664
11:52:08 2378 8192000000 10300551952 10715955200 1181501712 12.604 100 88 175 7 1414706080 213287504 19916864
11:53:15 994 8192000000 10321097008 10730573824 1170865024 12.505 100 98 188 2 1428153632 236950016 20338592
11:54:19 1938 8192000000 10334362176 10731663360 1172903744 12.524 100 92 183 1 1443237328 249658272 20675312
11:55:25 855 8192000000 10366403456 10778525696 1180306512 12.593 100 96 185 1 1476598896 274475568 21816816
11:56:28 926 8192000000 10362942624 10783240192 1165754272 12.457 100 96 186 0 1470045216 283112272 21178672
11:57:32 1402 8192000000 10373192832 10807726080 1182436208 12.613 100 93 183 0 1486470160 282180848 21853104
11:58:38 1216 8192000000 10414914912 10847993856 1182609104 12.615 100 91 179 1 1518104592 314524016 20971472
11:59:44 2107 8192000000 10404539776 10826301440 1180139136 12.591 100 78 153 0 1510175696 311111120 18925440
12:00:52 2424 8192000000 10395890928 10833305600 1167610512 12.474 99 81 162 0 1496345824 309896288 18839024
12:01:57 1186 8192000000 10406243744 10851168256 1165012560 12.450 100 95 180 0 1512054656 325448304 21593792
12:03:03 972 8192000000 10409753776 10872745984 1181226016 12.602 100 94 186 0 1515454416 312616320 21612080
12:04:08 1001 8192000000 10418293472 10831208448 1180381712 12.594 100 95 179 0 1521218208 320632416 20204080
12:05:12 1287 8192000000 10434805136 10858201088 1186687664 12.653 100 97 191 0 1534975600 325328096 22959840
12:06:16 1138 8192000000 10465538848 10871885824 1186797936 12.654 100 96 188 2 1565854480 356728624 22327920
12:07:24 2381 8192000000 10513567232 10941337600 1194650672 12.727 100 91 177 0 1603124976 388187712 20286592
12:08:33 772 8192000000 10533641504 10989445120 1207989968 12.850 100 94 180 0 1635087120 404815872 22281280
12:09:36 2351 8192000000 10583534448 11020451840 1204011840 12.814 100 72 144 0 1681072480 459244208 17816432
12:10:45 14191 8192000000 10587790656 11050614784 1195825680 12.738 99 60 115 1 1687882288 477103488 14953120
12:12:03 10996 8192000000 10568264976 11011211264 1199098064 12.768 100 48 96 0 1674232400 462257824 12876512
12:13:26 13601 8192000000 10546207360 11021557760 1178224976 12.574 99 42 82 1 1653007840 463867776 10915088
12:14:43 8974 8192000000 10514057680 11004497920 1182255120 12.611 100 59 115 0 1605125136 407752256 15117760
12:15:59 21194 8192000000 10449578960 10922577920 1178117952 12.573 100 35 68 1 1538095456 351230192 8747312
12:17:36 28913 8192000000 10408109264 10941587456 1157477280 12.380 100 22 44 0 1523475584 359718816 6279488
12:19:06 39739 8192000000 10274519312 10819747840 1161717200 12.419 100 22 44 2 1390428096 222994784 5716112
12:20:46 43537 8192000000 10139636880 10662412288 1132460992 12.145 98 21 42 1 1253634688 116440768 4732928
12:22:30 54558 8192000000 10130920176 10570321920 1117223184 12.001 97 39 69 1 1199637472 74816992 7597296
12:24:34 91757 8192000000 10052761680 10485215232 1107831488 11.912 97 33 65 1 1130616768 16216832 6568448
12:27:11 6427 8192000000 10102590256 10517659648 1119843952 12.026 100 59 115 1 1195667904 63644016 12179936
12:28:56 41765 8192000000 10054620192 10492309504 1091476480 11.757 97 41 80 1 1136299920 35796192 9027248
12:30:43 46585 8192000000 10065790592 10504011776 1087568352 11.720 97 53 105 1 1146344752 48282656 10493744
12:32:33 45447 8192000000 10071077808 10500485120 1107192704 11.906 99 36 69 0 1162016240 47089728 7733808
12:34:27 15790 8192000000 10075332832 10506592256 1111070640 11.943 100 39 77 2 1174737488 55412592 8254256
12:35:44 14984 8192000000 10107135904 10532696064 1099107328 11.829 97 53 103 0 1191056144 81831328 10117488
12:37:03 9986 8192000000 10147656080 10581524480 1129472336 12.116 100 58 111 1 1256429168 114622208 12334624
12:38:18 42228 8192000000 10156526384 10639319040 1125739344 12.081 99 27 54 1 1270124832 138535264 5850224
12:40:10 41005 8192000000 10204328496 10645848064 1133126384 12.151 100 26 49 4 1295135280 155555312 6453584
12:41:52 39503 8192000000 10119321232 10582921216 1114034560 11.971 98 16 32 0 1215495712 98089792 3371360
12:43:32 73508 8192000000 10117373072 10602921984 1121641344 12.042 99 15 30 1 1239980800 114101952 4237504
12:45:46 54961 8192000000 10103479328 10557022208 1102727184 11.864 97 22 44 0 1172285280 64888848 4669248
12:47:42 20978 8192000000 10067960384 10518982656 1124567696 12.070 99 42 79 1 1163823488 29911216 9344576
12:49:04 49603 8192000000 10071668400 10520649728 1074900592 11.599 95 34 68 1 1143522544 61499968 7121984
12:50:56 50597 8192000000 10115912960 10575929344 1132185328 12.142 100 39 78 2 1219185200 78827360 8172512
12:52:59 19048 8192000000 10162670208 10589163520 1139754384 12.213 100 53 104 0 1244006512 93499872 10752256
12:54:21 33545 8192000000 10113966464 10595139584 1115447712 11.984 99 21 42 4 1220434352 100481920 4504720
12:56:00 67932 8192000000 10084167552 10556661760 1113706304 11.967 98 15 29 2 1183771280 66831376 3233600
12:58:10 116009 8192000000 10065997376 10538811392 1129058096 12.112 100 45 81 2 1162726464 25019744 8648624
13:01:06 94407 8192000000 10082838480 10553229312 1097725808 11.816 97 39 77 1 1173031648 68079552 7226288
13:03:41 33503 8192000000 10097360448 10535854080 1137466880 12.192 100 32 63 0 1182002560 37584960 6950720
13:05:20 81663 8192000000 10094130544 10563411968 1129553840 12.117 99 19 38 0 1223932720 90074640 4304240
13:08:23 76589 8192000000 10058026128 10525433856 1106740096 11.902 97 13 25 0 1127939248 19131264 2067888
13:10:40 31280 8192000000 10110034608 10551861248 1121372400 12.040 98 27 53 3 1181451952 54534240 5545312
13:12:20 47693 8192000000 10159716000 10613198848 1141917904 12.234 100 28 57 3 1276099088 127674432 6506752
13:14:16 75715 8192000000 10065054128 10501488640 1051408160 11.374 92 15 30 0 1090315968 36004672 2903136
13:16:39 15734 8192000000 10096615168 10522734592 1106157808 11.896 99 46 93 1 1183959520 68256224 9545488
13:18:04 116421 8192000000 10104781440 10548158464 1095007920 11.790 97 16 32 1 1173588016 75490992 3089104
13:21:06 61231 8192000000 10076295792 10531303424 1084257104 11.688 96 13 26 0 1142960896 55967216 2736576
13:23:08 88122 8192000000 10071383088 10547601408 1123873328 12.064 100 18 36 0 1183813792 55948912 3991552
13:26:01 65656 8192000000 10084785040 10534576128 1116787440 11.997 98 42 83 1 1163126192 37525136 8813616
13:28:09 68096 8192000000 10087527840 10567880704 1127451008 12.097 99 15 30 1 1188805328 58081200 3273120
13:30:21 65762 8192000000 10048960176 10557423616 1127454144 12.097 99 11 21 1 1181572752 52121856 1996752
13:32:50 63303 8192000000 10045066864 10512785408 1136676688 12.184 100 17 34 1 1172832512 32631504 3524320
13:35:09 57204 8192000000 10056827616 10525442048 1130950800 12.130 100 25 50 1 1189218000 52745616 5521584
13:37:08 18503 8192000000 9180796816 9485475840 331627136 3.890 30 4 4 1 342906080 10930144 348800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-06-2022 13:38:52 to 08-06-2022 13:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-06-2022 13:39:09 to 08-06-2022 13:39:11.

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          222317
MON$OLDEST_ACTIVE               222318
MON$OLDEST_SNAPSHOT             222318
MON$NEXT_TRANSACTION            222318
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                       2140416
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

08.06.22 13:39:12. 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-06-08 13:39:14 No severe PSQL-related problems occured
08.06.22 13:39:15. End of report.