Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.12.2021 04: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 16444804 kB
MemAvailable 31306968 kB
Buffers 20 kB
Cached 15151396 kB
SwapCached 488 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.1
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 2021-12-28 04:37:52
DTS_END                         2021-12-28 04:37:10.9610
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/47332
TRN_ID                          232382
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: 8788.96 23477 1054675
customer order: creation 173.91 403 20869
customer order: refuse 14.88 387 1785
order to supplier: creation 45.35 1441 5442
order to supplier: removal 2.98 122647 358
invoice (draft): creation 46.56 3731 5587
invoice (draft): removal 49.35 996 5922
invoice accept: apply 41.16 19881 4939
invoice accept: cancel 44.31 945 5317
customer reserve: creation 3764.48 198 451738
customer reserve: removal 4323.51 67 518821
realization accept: apply 82.76 210 9931
realization accept: cancel 72.10 129 8652
payment from customer: creation 58.90 117 7068
payment from customer: removal 5.66 78 679
payment to supplier: creation 48.65 140 5838
payment to supplier: removal 3.35 72 402
service: total inventory turnovers 4.00 3667 480
service: total monetary turnovers 6.18 586 741
service: aggregate perf. data 0.85 18012 102
service: refresh index statistics 0.03 295824 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-12-2021 04:38:17 to 28-12-2021 04:38:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5255
TEST_TIME 2 5052
TEST_TIME 3 5212
TEST_TIME 4 5534
TEST_TIME 5 5453
TEST_TIME 6 5287
TEST_TIME 7 5067
TEST_TIME 8 5097
TEST_TIME 9 5431
TEST_TIME 10 5966
TEST_TIME 11 6160
TEST_TIME 12 6560
TEST_TIME 13 6711
TEST_TIME 14 6926
TEST_TIME 15 7037
TEST_TIME 16 7052
TEST_TIME 17 7670
TEST_TIME 18 8094
TEST_TIME 19 8633
TEST_TIME 20 8842
TEST_TIME 21 8723
TEST_TIME 22 8680
TEST_TIME 23 8997
TEST_TIME 24 9718
TEST_TIME 25 9858
TEST_TIME 26 9923
TEST_TIME 27 9956
TEST_TIME 28 10756
TEST_TIME 29 10576
TEST_TIME 30 10568
TEST_TIME 31 10523
TEST_TIME 32 10506
TEST_TIME 33 10664
TEST_TIME 34 10518
TEST_TIME 35 10447
TEST_TIME 36 10175
TEST_TIME 37 10077
TEST_TIME 38 9887
TEST_TIME 39 9822
TEST_TIME 40 9809
TEST_TIME 41 9770
TEST_TIME 42 9733
TEST_TIME 43 9658
TEST_TIME 44 9546
TEST_TIME 45 9514
TEST_TIME 46 9502
TEST_TIME 47 9404
TEST_TIME 48 9345
TEST_TIME 49 9265
TEST_TIME 50 9289
TEST_TIME 51 9244
TEST_TIME 52 9320
TEST_TIME 53 9279
TEST_TIME 54 9260
TEST_TIME 55 9238
TEST_TIME 56 9237
TEST_TIME 57 9254
TEST_TIME 58 9252
TEST_TIME 59 9321
TEST_TIME 60 9326
TEST_TIME 61 9250
TEST_TIME 62 9437
TEST_TIME 63 9462
TEST_TIME 64 9401
TEST_TIME 65 9427
TEST_TIME 66 9590
TEST_TIME 67 9658
TEST_TIME 68 9521
TEST_TIME 69 9437
TEST_TIME 70 9354
TEST_TIME 71 9314
TEST_TIME 72 9342
TEST_TIME 73 9281
TEST_TIME 74 9230
TEST_TIME 75 9174
TEST_TIME 76 9094
TEST_TIME 77 9104
TEST_TIME 78 9136
TEST_TIME 79 9072
TEST_TIME 80 9177
TEST_TIME 81 9184
TEST_TIME 82 9201
TEST_TIME 83 9288
TEST_TIME 84 9283
TEST_TIME 85 9270
TEST_TIME 86 9307
TEST_TIME 87 9283
TEST_TIME 88 9308
TEST_TIME 89 9229
TEST_TIME 90 9160
TEST_TIME 91 9127
TEST_TIME 92 9094
TEST_TIME 93 9069
TEST_TIME 94 9020
TEST_TIME 95 8992
TEST_TIME 96 8980
TEST_TIME 97 8937
TEST_TIME 98 8972
TEST_TIME 99 8958
TEST_TIME 100 9011
TEST_TIME 101 8992
TEST_TIME 102 8988
TEST_TIME 103 9035
TEST_TIME 104 9047
TEST_TIME 105 9046
TEST_TIME 106 9037
TEST_TIME 107 9026
TEST_TIME 108 8978
TEST_TIME 109 8940
TEST_TIME 110 8896
TEST_TIME 111 8855
TEST_TIME 112 8850
TEST_TIME 113 8828
TEST_TIME 114 8828
TEST_TIME 115 8801
TEST_TIME 116 8805
TEST_TIME 117 8822
TEST_TIME 118 8853
TEST_TIME 119 8821
TEST_TIME 120 8789

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-12-2021 04:38:21 to 28-12-2021 04:38: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 1474060 1474060 0 0.00 0 26017 7 0 0
doc_list_biud 1474060 1474060 0 0.00 0 35228 31 0 0
sp_add_invoice_to_stock 5022 4939 83 1.65 51 245436 19882 0 83
sp_cancel_adding_invoice 5317 5317 0 0.00 5 84103 946 0 0
sp_cancel_client_order 1785 1785 0 0.00 4 10390 388 0 0
sp_cancel_customer_reserve 518821 518821 0 0.00 1 30081 67 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 2957 79 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 1057 72 0 0
sp_cancel_supplier_invoice 5934 5922 12 0.20 2 28883 996 0 12
sp_cancel_supplier_order 358 358 0 0.00 17 1103120 122647 0 0
sp_cancel_write_off 8652 8652 0 0.00 1 13117 129 0 0
sp_client_order 20869 20869 0 0.00 4 18216 404 0 0
sp_customer_reserve 451738 451738 0 0.00 0 103485 198 0 0
sp_fill_shopping_cart 31881 31881 0 0.00 1 17487 43 0 0
sp_fill_shopping_cart_clo_res 451736 451736 0 0.00 0 18622 17 0 0
sp_get_clo_for_invoice 4937 4937 0 0.00 0 22269 349 0 0
sp_kill_cost_storno 15050 15050 0 0.00 0 7569 66 0 0
sp_kill_qstorno_ret_qs2qd 1052574 1052574 0 0.00 0 30074 37 0 0
sp_kill_qty_storno 555723 555723 0 0.00 0 35228 80 0 0
sp_lock_selected_doc 20674 20674 0 0.00 0 6273 5 0 0
sp_make_cost_storno 27774 27774 0 0.00 0 26003 60 0 0
sp_multiply_rows_for_pdistr 27774 27774 0 0.00 0 13963 37 0 0
sp_multiply_rows_for_qdistr 20869 20869 0 0.00 0 18021 221 0 0
sp_pay_from_customer 7068 7068 0 0.00 4 16887 118 0 0
sp_pay_to_supplier 5838 5838 0 0.00 4 13980 140 0 0
sp_payment_common 12906 12906 0 0.00 1 8548 20 0 0
sp_qd_handle_on_cancel_clo 1785 1785 0 0.00 0 10383 347 0 0
sp_qd_handle_on_invoice_upd_sts 10254 10254 0 0.00 0 35227 386 0 0
sp_qd_handle_on_reserve_upd_sts 9931 9931 0 0.00 0 18151 70 0 0
sp_reserve_write_off 9931 9931 0 0.00 7 18211 211 0 0
sp_supplier_invoice 5587 5587 0 0.00 7 104999 3732 0 0
sp_supplier_order 5442 5442 0 0.00 6 34925 1442 0 0
srv_aggregate_perf_data 102 102 0 0.00 2549 293747 18012 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1352 129166 44126 0 0
srv_find_qd_qs_mism 1007906 1007906 0 0.00 0 21081 6 0 0
srv_make_invnt_saldo 480 480 0 0.00 51 99995 3667 0 0
srv_make_money_saldo 741 741 0 0.00 1 13893 586 0 0
srv_recalc_idx_stat 4 4 0 0.00 152990 468836 295824 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4939 4939 0 0.00 0 13175 173 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 3511 94 0 0
t$perf-norm:sp_cancel_client_order 1785 1785 0 0.00 0 3792 14 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 2804 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 2532 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 4484 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 508 10 0 0
t$perf-norm:sp_cancel_supplier_order 358 358 0 0.00 0 28382 1729 0 0
t$perf-norm:sp_cancel_write_off 1067 1067 0 0.00 0 3008 7 0 0
t$perf-norm:sp_client_order 20869 20869 0 0.00 0 17098 22 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 16795 20 0 0
t$perf-norm:sp_pay_from_customer 7068 7068 0 0.00 0 4280 7 0 0
t$perf-norm:sp_pay_to_supplier 5838 5838 0 0.00 0 2797 8 0 0
t$perf-norm:sp_reserve_write_off 9931 9931 0 0.00 0 7442 10 0 0
t$perf-norm:sp_supplier_invoice 5587 5587 0 0.00 0 7132 22 0 0
t$perf-norm:sp_supplier_order 5442 5442 0 0.00 0 8152 20 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 64 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 85 3 0 0
t$perf-norm:srv_make_invnt_saldo 480 480 0 0.00 0 440 6 0 0
t$perf-norm:srv_make_money_saldo 741 741 0 0.00 0 3310 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 4 0 0
v_add_invoice_to_stock 10040 9874 166 1.65 0 5875 16 0 166
v_all_customers 37996 37996 0 0.00 0 91 1 0 0
v_all_suppliers 30404 30404 0 0.00 0 19 1 0 0
v_all_wares 41738 41738 0 0.00 0 143 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 1472 11 0 0
v_cancel_client_order 3570 3570 0 0.00 0 10171 10 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 2587 7 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 7094 12 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 434 4 0 0
v_cancel_supplier_invoice 876 852 24 2.73 0 1126 13 0 24
v_cancel_supplier_order 716 716 0 0.00 0 7097 17 0 0
v_cancel_write_off 2134 2134 0 0.00 0 1572 16 0 0
v_max_id_clo_ord 5440 5440 0 0.00 0 1611 2 0 0
v_max_id_clo_res 990 990 0 0.00 0 3977 14 0 0
v_max_id_ord_sup 5572 5572 0 0.00 0 6567 7 0 0
v_max_non_paid_invoice 1648 1648 0 0.00 0 2345 3 0 0
v_max_non_paid_realizn 3840 3840 0 0.00 0 532 3 0 0
v_min_id_clo_ord 5440 5440 0 0.00 0 2680 6 0 0
v_min_id_clo_res 990 990 0 0.00 0 9434 27 0 0
v_min_id_ord_sup 5572 5572 0 0.00 0 5028 8 0 0
v_min_non_paid_invoice 5838 5838 0 0.00 0 3706 13 0 0
v_min_non_paid_realizn 7068 7068 0 0.00 0 5452 14 0 0
v_our_firm 10198 10198 0 0.00 0 19 1 0 0
v_reserve_write_off 19862 19862 0 0.00 0 10172 7 0 0
x_lock_depdocs_on_canc_invoice 10813 10813 0 0.00 0 12465 112 0 0
x_lock_depdocs_on_canc_sup_ord 358 358 0 0.00 0 5567 351 0 0
x_make_qstorno_1000_1200 5442 5442 0 0.00 2 34916 1356 0 0
x_make_qstorno_1200_2000 5587 5587 0 0.00 3 104620 3568 0 0
x_make_qstorno_2100_3300 451738 451738 0 0.00 0 103449 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-12-2021 04:38:25 to 28-12-2021 04:38: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:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:15 23747 8192000000 9714289248 10010411008 787526128 8.770 100 84 168 0 815868080 7465632 20876320
02:09:53 28529 8192000000 9761555904 10068418560 835509936 9.255 100 87 174 0 868986144 12839008 20637200
02:11:28 14615 8192000000 9807345952 10127556608 875642928 9.656 100 81 162 0 916973712 22746944 18583840
02:12:49 12760 8192000000 9878869152 10213396480 924869104 10.144 100 78 156 0 984117392 42497856 16750432
02:14:05 10566 8192000000 9926945760 10273222656 961987312 10.508 100 80 160 1 1040428720 61138288 17303120
02:15:23 6630 8192000000 9981632816 10337697792 994807488 10.828 100 77 154 0 1094060704 82965904 16287312
02:16:32 3231 8192000000 9988736256 10352558080 1023750064 11.108 100 82 162 1 1108286768 67962560 16574144
02:17:37 1991 8192000000 10018842544 10387247104 1046081136 11.323 100 89 179 1 1126738544 62456480 18200928
02:18:42 1828 8192000000 10059198736 10431606784 1071050816 11.562 100 95 189 2 1165566784 75870128 18645840
02:19:45 1625 8192000000 10026891712 10409279488 1078553424 11.634 100 66 131 0 1147604224 56011616 13039184
02:20:47 8789 8192000000 10000977904 10394980352 1084082880 11.686 100 38 76 0 1114251008 22074352 8093776
02:21:57 8891 8192000000 9996768624 10396962816 1089603984 11.739 100 19 38 2 1117572512 24115264 3853264
02:23:08 19020 8192000000 10014921392 10409132032 1100089968 11.838 100 46 92 2 1127815808 17517600 10208240
02:24:28 6338 8192000000 10012772208 10419273728 1096696064 11.806 100 33 66 0 1138374768 35076016 6602688
02:25:35 6707 8192000000 10026459344 10435612672 1100090608 11.839 100 38 76 1 1133836432 25622896 8122928
02:26:42 3420 8192000000 10011528704 10413506560 1089954336 11.742 99 37 73 2 1123949920 25968256 8027328
02:27:46 17755 8192000000 10025163216 10452639744 1083236656 11.678 99 21 42 0 1125799504 38579312 3983536
02:29:05 6256 8192000000 10017629152 10412875776 1078886384 11.637 99 48 92 0 1120177072 31208624 10082064
02:30:16 6254 8192000000 9966132160 10352009216 1024989504 11.120 99 49 98 1 1043111872 6939808 11182560
02:31:27 71838 8192000000 9928204560 10324312064 992176912 10.803 96 23 46 0 1019025840 22185984 4662944
02:33:41 6703 8192000000 9962040576 10346995712 1028687328 11.156 99 42 84 1 1060058272 22770336 8600608
02:34:51 7378 8192000000 9975895584 10369290240 1056347568 11.422 100 50 100 0 1083922560 15522384 12052608
02:36:00 9640 8192000000 9969291632 10365988864 1062396640 11.479 100 22 43 2 1092822544 25965232 4460672
02:37:11 32808 8192000000 10003630480 10395025408 1079240400 11.640 100 36 70 1 1107895872 21567728 7087744
02:38:54 9085 8192000000 10041416192 10437775360 1098264560 11.821 100 52 104 2 1145860320 36636336 10959424
02:40:15 40185 8192000000 10105062304 10509750272 1119532576 12.023 100 43 86 0 1221063360 91682720 9848064
02:41:58 5541 8192000000 10121489696 10523721728 1135980080 12.178 100 60 117 0 1231627664 82786288 12861296
02:43:11 6841 8192000000 10157847184 10565283840 1149482368 12.305 100 48 95 1 1266458880 107196032 9780480
02:44:23 14496 8192000000 10199001168 10610200576 1155754560 12.363 100 47 92 0 1311402832 145610272 10038000
02:45:48 21310 8192000000 10254220352 10681380864 1166122528 12.461 100 60 119 1 1365696864 185948848 13625488
02:47:16 1352 8192000000 10294607504 10694844416 1154842000 12.355 99 89 178 0 1392023952 218335472 18846480
02:48:21 3866 8192000000 10323499184 10729336832 1172764672 12.523 99 85 167 1 1417873616 227023104 18085840
02:49:27 2625 8192000000 10356055888 10764390400 1186841152 12.654 100 74 143 1 1451235680 248602464 15792064
02:50:35 2785 8192000000 10372785760 10789543936 1182055776 12.609 99 59 118 1 1476446288 281230592 13159920
02:51:42 1917 8192000000 10406016624 10808315904 1177395632 12.566 100 90 176 1 1501328768 303725440 20207696
02:52:49 4887 8192000000 10408032496 10815315968 1164217072 12.443 100 71 141 3 1503873760 322769280 16887408
02:53:58 1792 8192000000 10407480640 10827894784 1161523680 12.418 100 82 160 0 1507704000 327307280 18873040
02:55:05 1923 8192000000 10359578128 10793512960 1151088928 12.320 99 85 169 2 1451689808 280851472 19749408
02:56:10 5474 8192000000 10375777312 10785882112 1160036192 12.404 100 64 128 0 1473915360 298556400 15322768
02:57:20 12765 8192000000 10260997648 10695438336 1131474720 12.135 98 28 53 1 1348743312 210468848 6799744
02:58:48 8608 8192000000 10299141216 10724376576 1116381488 11.993 98 36 71 2 1387115904 261296048 9438368
03:00:11 8774 8192000000 10297380848 10741350400 1112731008 11.958 98 38 76 1 1383533776 260452608 10350160
03:01:27 33229 8192000000 10245510224 10716663808 1134037760 12.159 100 23 44 2 1421003200 280685376 6280064
03:03:07 9164 8192000000 10212728640 10658963456 1121082336 12.037 99 27 54 0 1294744416 167023632 6638448
03:04:18 33206 8192000000 10199235536 10634342400 1101078672 11.848 100 12 24 0 1276124960 171721952 3324336
03:05:53 57296 8192000000 10116613424 10549841920 1116378288 11.993 100 19 38 0 1209220928 88824752 4017888
03:08:12 36141 8192000000 10150443936 10575646720 1141132176 12.226 100 47 92 3 1244920272 92977072 10811024
03:09:52 32691 8192000000 10171978752 10660065280 1144440512 12.257 100 10 20 0 1263186912 116469024 2277376
03:11:25 70167 8192000000 10073699504 10489765888 1106772080 11.902 97 45 85 4 1166464528 49927424 9765024
03:13:36 42563 8192000000 10065337648 10497093632 1123655056 12.062 99 49 96 1 1157333888 23692128 9986704
03:15:21 51223 8192000000 10068482624 10505011200 1112112112 11.952 98 27 54 1 1155109392 37152784 5844496
03:17:14 63040 8192000000 10098373696 10537181184 1137566192 12.193 100 36 72 2 1224662624 79000240 8096192
03:19:36 119457 8192000000 10097695648 10537541632 1087878544 11.722 95 16 32 1 1134682368 43603040 3200784
03:22:57 101195 8192000000 10124849392 10580566016 1135023888 12.169 99 17 34 0 1206395872 67346224 4025760
03:25:40 73573 8192000000 10117645488 10565758976 1145548176 12.268 100 27 54 0 1229291664 78301696 5441792
03:27:55 36635 8192000000 10088017440 10529665024 1134813232 12.167 99 29 58 0 1177740848 37020256 5907360
03:29:34 67701 8192000000 10093122880 10603495424 1144693648 12.260 100 2 4 1 1191518496 46518608 306240
03:31:56 52046 8192000000 10088770400 10545688576 1152317808 12.331 100 15 29 0 1184861488 29711104 2832576
03:33:50 41033 8192000000 10115367280 10547961856 1113665136 11.967 96 34 66 1 1171345056 51136528 6543392
03:35:40 80866 8192000000 10081414176 10551488512 1110134688 11.934 97 13 24 1 1150377088 38021056 2221344
03:38:25 38654 8192000000 10166840384 10600390656 1145797856 12.270 100 39 78 2 1218085584 64512336 7775392
03:40:04 58439 8192000000 10081712880 10519601152 1132019904 12.140 98 14 28 0 1177156752 42562848 2574000
03:42:18 41067 8192000000 10148311504 10604011520 1064334416 11.498 93 14 28 0 1201099680 133271712 3493552
03:43:59 94781 8192000000 10080332416 10606653440 1116056592 11.990 99 17 34 0 1184256528 64852176 3347760
03:46:44 69935 8192000000 10080625152 10519592960 1100453424 11.842 97 24 46 1 1152940320 47856640 4630256
03:48:55 87052 8192000000 10035333328 10522198016 1067031904 11.524 95 15 29 2 1127364592 57154144 3178544
03:51:23 69835 8192000000 10093647872 10547351552 1120528976 12.032 100 18 36 0 1177711904 53372064 3810864
03:53:39 80503 8192000000 10137325968 10614382592 1103853632 11.874 97 15 30 2 1219913456 112275168 3784656
03:56:06 62844 8192000000 10069611360 10523504640 1078218208 11.630 97 27 53 3 1183324784 99027984 6078592
03:58:23 91329 8192000000 10092990848 10570055680 1076060848 11.610 96 17 34 0 1177878640 97696640 4121152
04:01:00 29563 8192000000 10063080064 10490638336 1113698736 11.967 100 40 80 1 1142986960 21340256 7947968
04:02:31 48943 8192000000 10093744960 10535211008 1120102128 12.028 100 43 84 1 1182228480 53982288 8144064
04:04:34 129165 8192000000 10079599712 10529742848 1075227536 11.602 95 26 52 0 1121332528 40511232 5593760
04:08:21 8690 8192000000 10069509296 10505150464 1114068528 11.971 99 37 73 1 1170861248 49488096 7304624
04:09:30 58331 8192000000 10072133152 10540785664 1112334080 11.955 98 15 31 0 1181083280 65744800 3004400
04:11:40 61216 8192000000 10066829312 10516070400 1116836560 11.997 99 17 35 0 1174139456 53704288 3598608
04:14:19 36549 8192000000 10121833744 10564362240 1139766160 12.213 100 23 46 0 1216151056 71066304 5318592
04:15:58 66665 8192000000 10121775216 10556391424 1126498640 12.088 99 31 62 0 1211895536 78718528 6678368
04:18:14 31557 8192000000 10084963248 10513330176 1116435488 11.993 98 36 70 2 1180356304 56731904 7188912
04:19:49 57050 8192000000 10068393280 10509004800 1114413856 11.974 98 18 36 0 1176750832 58414624 3922352
04:21:51 70313 8192000000 10066669168 10508451840 1098810672 11.826 97 36 73 2 1144977792 38206992 7960128
04:24:04 43595 8192000000 10083132816 10529009664 1118388352 12.012 98 24 48 0 1166858704 44053968 4416384
04:25:53 92264 8192000000 10062896240 10522353664 1117299376 12.001 98 18 36 1 1149617984 28678256 3640352
04:28:26 72496 8192000000 10105127616 10559254528 1120011664 12.027 98 10 20 0 1198646768 76391456 2243648
04:30:41 42903 8192000000 10078432208 10519113728 1082098944 11.667 96 17 35 0 1146513568 60921632 3492992
04:32:25 34804 8192000000 10088508240 10529984512 1093904816 11.780 97 27 54 1 1167991472 67573184 6513472
04:34:04 50124 8192000000 10128269344 10601639936 1124261696 12.067 100 22 44 1 1214186672 84797424 5127552
04:35:55 51146 8192000000 10116199184 10553081856 1090413136 11.747 96 28 55 0 1168320064 72406064 5500864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-12-2021 04:38:31 to 28-12-2021 04:38:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-12-2021 04:38:47 to 28-12-2021 04:38:49.

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          257036
MON$OLDEST_ACTIVE               257037
MON$OLDEST_SNAPSHOT             257037
MON$NEXT_TRANSACTION            257037
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                       2039520
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

28.12.21 04:38:50. 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
2021-12-28 04:38:53 No severe PSQL-related problems occured
28.12.21 04:38:54. End of report.