Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.05.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 15819168 kB
MemAvailable 31432044 kB
Buffers 0 kB
Cached 15906008 kB
SwapCached 2544 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-25 13:37:17
DTS_END                         2022-05-25 13:37:17.5710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39768
TRN_ID                          221264
ATT_ID                          610
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: 9320.70 26031 1118484
customer order: creation 166.94 430 20033
customer order: refuse 14.05 444 1686
order to supplier: creation 44.31 1335 5317
order to supplier: removal 3.34 183285 401
invoice (draft): creation 46.33 3124 5559
invoice (draft): removal 52.71 987 6325
invoice accept: apply 41.34 26358 4961
invoice accept: cancel 47.66 1245 5719
customer reserve: creation 3922.94 246 470753
customer reserve: removal 4690.30 103 562836
realization accept: apply 82.88 277 9946
realization accept: cancel 78.69 161 9443
payment from customer: creation 58.43 167 7012
payment from customer: removal 5.94 136 713
payment to supplier: creation 49.51 193 5941
payment to supplier: removal 3.36 115 403
service: total inventory turnovers 3.80 4565 456
service: total monetary turnovers 7.25 359 870
service: aggregate perf. data 0.88 16697 106
service: refresh index statistics 0.03 280395 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-05-2022 13:38:53 to 25-05-2022 13:38:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6225
TEST_TIME 2 5296
TEST_TIME 3 5172
TEST_TIME 4 5411
TEST_TIME 5 5726
TEST_TIME 6 6047
TEST_TIME 7 6118
TEST_TIME 8 6036
TEST_TIME 9 5999
TEST_TIME 10 6073
TEST_TIME 11 6323
TEST_TIME 12 6367
TEST_TIME 13 6549
TEST_TIME 14 6929
TEST_TIME 15 6998
TEST_TIME 16 7143
TEST_TIME 17 7381
TEST_TIME 18 7718
TEST_TIME 19 7680
TEST_TIME 20 7788
TEST_TIME 21 7828
TEST_TIME 22 8024
TEST_TIME 23 8128
TEST_TIME 24 8203
TEST_TIME 25 8205
TEST_TIME 26 8134
TEST_TIME 27 8380
TEST_TIME 28 8695
TEST_TIME 29 8884
TEST_TIME 30 9060
TEST_TIME 31 9308
TEST_TIME 32 9720
TEST_TIME 33 10214
TEST_TIME 34 10566
TEST_TIME 35 10758
TEST_TIME 36 10946
TEST_TIME 37 11059
TEST_TIME 38 11014
TEST_TIME 39 11174
TEST_TIME 40 11427
TEST_TIME 41 11302
TEST_TIME 42 11206
TEST_TIME 43 11130
TEST_TIME 44 11046
TEST_TIME 45 10958
TEST_TIME 46 11048
TEST_TIME 47 11100
TEST_TIME 48 10906
TEST_TIME 49 10736
TEST_TIME 50 10711
TEST_TIME 51 10627
TEST_TIME 52 10652
TEST_TIME 53 10541
TEST_TIME 54 10442
TEST_TIME 55 10401
TEST_TIME 56 10399
TEST_TIME 57 10316
TEST_TIME 58 10339
TEST_TIME 59 10318
TEST_TIME 60 10298
TEST_TIME 61 10230
TEST_TIME 62 10241
TEST_TIME 63 10159
TEST_TIME 64 10029
TEST_TIME 65 9979
TEST_TIME 66 9895
TEST_TIME 67 9783
TEST_TIME 68 9704
TEST_TIME 69 9794
TEST_TIME 70 9871
TEST_TIME 71 9804
TEST_TIME 72 9795
TEST_TIME 73 9861
TEST_TIME 74 9889
TEST_TIME 75 9883
TEST_TIME 76 9874
TEST_TIME 77 9869
TEST_TIME 78 9911
TEST_TIME 79 9920
TEST_TIME 80 9935
TEST_TIME 81 9879
TEST_TIME 82 9912
TEST_TIME 83 9937
TEST_TIME 84 9874
TEST_TIME 85 9871
TEST_TIME 86 9803
TEST_TIME 87 9729
TEST_TIME 88 9770
TEST_TIME 89 9684
TEST_TIME 90 9730
TEST_TIME 91 9681
TEST_TIME 92 9622
TEST_TIME 93 9600
TEST_TIME 94 9553
TEST_TIME 95 9473
TEST_TIME 96 9482
TEST_TIME 97 9495
TEST_TIME 98 9483
TEST_TIME 99 9471
TEST_TIME 100 9446
TEST_TIME 101 9438
TEST_TIME 102 9397
TEST_TIME 103 9393
TEST_TIME 104 9496
TEST_TIME 105 9456
TEST_TIME 106 9603
TEST_TIME 107 9589
TEST_TIME 108 9587
TEST_TIME 109 9573
TEST_TIME 110 9623
TEST_TIME 111 9543
TEST_TIME 112 9546
TEST_TIME 113 9514
TEST_TIME 114 9511
TEST_TIME 115 9456
TEST_TIME 116 9403
TEST_TIME 117 9371
TEST_TIME 118 9345
TEST_TIME 119 9390
TEST_TIME 120 9350

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-05-2022 13:38:57 to 25-05-2022 13:39:00.

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 1550356 1550356 0 0.00 0 23941 14 0 0
doc_list_biud 1550346 1550346 0 0.00 0 29597 43 0 0
sp_add_invoice_to_stock 5108 4961 147 2.87 113 196124 26359 0 147
sp_cancel_adding_invoice 5719 5719 0 0.00 6 108010 1245 0 0
sp_cancel_client_order 1686 1686 0 0.00 6 10212 444 0 0
sp_cancel_customer_reserve 562836 562836 0 0.00 1 29610 103 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 3 4962 137 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 4 1889 116 0 0
sp_cancel_supplier_invoice 6337 6325 12 0.18 3 14900 988 0 12
sp_cancel_supplier_order 401 401 0 0.00 7 1526613 183285 0 0
sp_cancel_write_off 9443 9443 0 0.00 2 8085 162 0 0
sp_client_order 20033 20033 0 0.00 6 23569 431 0 0
sp_customer_reserve 470753 470753 0 0.00 1 36352 246 0 0
sp_fill_shopping_cart 30901 30901 0 0.00 2 24137 81 0 0
sp_fill_shopping_cart_clo_res 470719 470719 0 0.00 0 16722 17 0 0
sp_get_clo_for_invoice 4924 4924 0 0.00 0 33694 281 0 0
sp_kill_cost_storno 16278 16278 0 0.00 0 8747 76 0 0
sp_kill_qstorno_ret_qs2qd 1141841 1141841 0 0.00 0 29591 44 0 0
sp_kill_qty_storno 601280 601280 0 0.00 0 29596 101 0 0
sp_lock_selected_doc 20553 20553 0 0.00 0 9226 9 0 0
sp_make_cost_storno 27823 27823 0 0.00 0 14607 79 0 0
sp_multiply_rows_for_pdistr 27823 27823 0 0.00 0 6138 37 0 0
sp_multiply_rows_for_qdistr 20032 20032 0 0.00 0 13625 152 0 0
sp_pay_from_customer 7012 7012 0 0.00 5 16214 168 0 0
sp_pay_to_supplier 5941 5941 0 0.00 6 14226 194 0 0
sp_payment_common 12953 12953 0 0.00 2 6183 44 0 0
sp_qd_handle_on_cancel_clo 1686 1686 0 0.00 0 9873 342 0 0
sp_qd_handle_on_invoice_upd_sts 10643 10643 0 0.00 0 21330 537 0 0
sp_qd_handle_on_reserve_upd_sts 9946 9946 0 0.00 0 14625 63 0 0
sp_reserve_write_off 9946 9946 0 0.00 8 14660 278 0 0
sp_supplier_invoice 5559 5559 0 0.00 10 84508 3124 0 0
sp_supplier_order 5317 5317 0 0.00 8 24227 1336 0 0
srv_aggregate_perf_data 106 106 0 0.00 2253 209778 16698 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 762 132893 31842 0 0
srv_find_qd_qs_mism 1068796 1068796 0 0.00 0 23941 10 0 0
srv_make_invnt_saldo 456 456 0 0.00 89 85975 4565 0 0
srv_make_money_saldo 870 870 0 0.00 1 10821 360 0 0
srv_recalc_idx_stat 4 4 0 0.00 206100 415736 280395 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 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 4969 4969 0 0.00 1 17012 976 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 7955 564 0 0
t$perf-norm:sp_cancel_client_order 1686 1686 0 0.00 0 2735 29 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 961 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 3276 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 1340 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 716 30 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 111435 8058 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 1596 20 0 0
t$perf-norm:sp_client_order 20034 20034 0 0.00 0 25346 36 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 5736 20 0 0
t$perf-norm:sp_pay_from_customer 7012 7012 0 0.00 0 8155 19 0 0
t$perf-norm:sp_pay_to_supplier 5941 5941 0 0.00 0 9449 22 0 0
t$perf-norm:sp_reserve_write_off 9946 9946 0 0.00 0 9111 24 0 0
t$perf-norm:sp_supplier_invoice 5559 5559 0 0.00 0 6478 35 0 0
t$perf-norm:sp_supplier_order 5317 5317 0 0.00 0 6762 31 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 423 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 94 6 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 6071 27 0 0
t$perf-norm:srv_make_money_saldo 870 870 0 0.00 0 437 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 169 50 0 0
v_add_invoice_to_stock 10136 9842 294 2.90 0 5922 25 0 294
v_all_customers 36802 36802 0 0.00 0 77 4 0 0
v_all_suppliers 30764 30764 0 0.00 0 70 4 0 0
v_all_wares 40064 40064 0 0.00 0 209 4 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 1095 16 0 0
v_cancel_client_order 3372 3372 0 0.00 0 4598 13 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 4887 21 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 1 2445 12 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 523 11 0 0
v_cancel_supplier_invoice 824 800 24 2.91 0 688 22 0 24
v_cancel_supplier_order 802 802 0 0.00 0 350 11 0 0
v_cancel_write_off 1952 1952 0 0.00 0 3589 26 0 0
v_max_id_clo_ord 5314 5314 0 0.00 0 219 5 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 4738 19 0 0
v_max_id_ord_sup 5554 5554 0 0.00 0 2496 5 0 0
v_max_non_paid_invoice 1427 1427 0 0.00 0 615 8 0 0
v_max_non_paid_realizn 3617 3617 0 0.00 0 2125 8 0 0
v_min_id_clo_ord 5314 5314 0 0.00 0 23904 13 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 3355 20 0 0
v_min_id_ord_sup 5554 5554 0 0.00 0 4600 7 0 0
v_min_non_paid_invoice 5941 5941 0 0.00 0 6141 15 0 0
v_min_non_paid_realizn 7012 7012 0 0.00 0 3553 13 0 0
v_our_firm 10052 10052 0 0.00 0 60 4 0 0
v_reserve_write_off 19892 19892 0 0.00 0 4596 10 0 0
x_lock_depdocs_on_canc_invoice 11644 11644 0 0.00 0 13389 126 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 19747 446 0 0
x_make_qstorno_1000_1200 5317 5317 0 0.00 3 18348 1155 0 0
x_make_qstorno_1200_2000 5559 5559 0 0.00 5 84491 2924 0 0
x_make_qstorno_2100_3300 470752 470752 0 0.00 0 36340 216 0 0

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

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 2 8192000000 8799383152 8810651648 5260816 0.064 2 1 2 0 5360240 63648 35776
11:08:22 29758 8192000000 9720851952 10017112064 796995600 8.866 100 88 176 0 826198176 7561456 21641120
11:10:00 22471 8192000000 9764906256 10069753856 839373584 9.293 100 89 178 1 872790944 12527728 20889632
11:11:27 19215 8192000000 9787694752 10107756544 859322976 9.493 100 73 146 1 898667152 22843488 16500688
11:12:49 12966 8192000000 9861415440 10191405056 914155104 10.038 100 84 167 0 964113184 31202608 18755472
11:14:05 6973 8192000000 9911116032 10253926400 958156192 10.471 100 82 164 0 1027628304 52123024 17349088
11:15:15 3132 8192000000 9968628112 10321674240 994819408 10.828 100 90 179 0 1086338144 74100352 17418384
11:16:22 1255 8192000000 10002921136 10364248064 1017784992 11.051 100 92 182 0 1127340560 92213344 17342224
11:17:25 5220 8192000000 9999619808 10366910464 1031631184 11.184 100 89 170 1 1119287744 71568320 16088240
11:18:31 1231 8192000000 10013468336 10388238336 1057654192 11.434 100 92 180 0 1133043584 56584928 18804464
11:19:34 1139 8192000000 10029718672 10409713664 1076212288 11.611 100 94 177 2 1152388128 57364176 18811664
11:20:37 1142 8192000000 10069300528 10451959808 1080095456 11.648 100 96 189 1 1190236496 91751568 18389472
11:21:39 4620 8192000000 10045022624 10434748416 1082559952 11.672 100 77 145 1 1178280192 81879424 13840816
11:22:44 1076 8192000000 10043850960 10430775296 1091637536 11.758 100 86 170 2 1166302368 57928384 16736448
11:23:46 885 8192000000 10035264720 10423185408 1094348464 11.784 100 83 160 1 1157349040 46912384 16088192
11:24:48 1446 8192000000 10039668720 10424926208 1100240096 11.840 100 91 171 1 1157377136 40699808 16437232
11:25:50 1516 8192000000 10060984176 10451382272 1099277632 11.831 100 93 178 1 1181701728 64493728 17930368
11:26:53 1137 8192000000 10058794768 10452488192 1098190384 11.820 100 91 175 1 1181200880 64517424 18493072
11:27:56 1409 8192000000 10064444032 10455887872 1098337888 11.822 100 91 175 0 1180694720 64891856 17464976
11:28:58 6839 8192000000 10028947488 10433974272 1071096000 11.563 98 52 103 0 1127128016 46028512 10003504
11:30:07 1225 8192000000 10056547472 10442129408 1083295776 11.679 100 96 187 1 1180117664 79067888 17754000
11:31:09 1350 8192000000 10022582944 10401378304 1058552448 11.443 100 93 177 1 1136467968 61225616 16689904
11:32:12 1085 8192000000 10003282128 10385018880 1048494528 11.346 100 82 155 2 1126171280 61931232 15745520
11:33:14 1074 8192000000 9993197200 10371129344 1049926384 11.360 99 84 157 4 1115886240 50937376 15022480
11:34:16 4042 8192000000 9957939152 10329808896 1015248512 11.026 98 69 133 1 1072484512 43969600 13266400
11:35:20 843 8192000000 9992107136 10364112896 1019347568 11.066 100 84 165 0 1113242400 77193056 16701776
11:36:25 911 8192000000 9991155472 10364096512 1044972848 11.312 100 92 178 2 1109709104 45977584 18758672
11:37:30 40393 8192000000 9987386912 10371194880 1047358448 11.335 100 56 98 1 1107740144 50412496 9969200
11:39:16 4775 8192000000 9992723808 10373427200 1056078064 11.419 99 67 127 0 1119919424 50724208 13117152
11:40:28 5204 8192000000 10052560544 10439360512 1070905808 11.561 100 66 125 1 1171345680 87849488 12590384
11:41:36 8071 8192000000 10089852656 10483912704 1082343776 11.670 100 70 135 0 1211859872 115872400 13643696
11:42:47 1201 8192000000 10115186640 10498596864 1096095024 11.801 100 84 159 1 1230560336 119023120 15442192
11:43:54 3595 8192000000 10142404912 10534821888 1110968064 11.942 100 76 145 0 1261975760 134828656 16179040
11:45:07 10420 8192000000 10180547232 10573713408 1126092640 12.085 100 78 154 0 1286930464 144606624 16231200
11:46:22 12323 8192000000 10190748224 10585657344 1124085984 12.066 99 78 147 2 1292645776 153271360 15288432
11:47:45 1178 8192000000 10263303968 10661244928 1154338272 12.350 100 85 162 1 1378768352 207143488 17286592
11:48:49 2821 8192000000 10297619344 10705338368 1167894272 12.477 100 81 161 1 1410519232 224597760 18027200
11:49:55 874 8192000000 10339685200 10747490304 1173970736 12.534 100 94 180 2 1448868032 254139744 20757552
11:50:59 1184 8192000000 10339875584 10759294976 1166756928 12.467 99 93 177 0 1448619312 261831488 20030896
11:52:02 3269 8192000000 10373090640 10797649920 1187011728 12.656 100 89 172 0 1485437312 279022224 19403360
11:53:09 1176 8192000000 10422004432 10831638528 1191184224 12.694 100 96 186 0 1528714000 314865728 22664048
11:54:14 1135 8192000000 10462492944 10882904064 1201959904 12.795 100 97 191 0 1569001504 344763840 22277760
11:55:18 972 8192000000 10459779056 10877116416 1198206384 12.760 100 94 186 3 1565454176 346454160 20793632
11:56:24 965 8192000000 10469075104 10884567040 1183336688 12.621 100 96 185 1 1571506368 366868784 21300896
11:57:27 750 8192000000 10489434624 10908663808 1183349920 12.621 100 96 189 0 1602119792 394913296 23856576
11:58:32 1862 8192000000 10549442848 10957303808 1189872176 12.682 100 95 190 0 1651531952 439685888 21973888
11:59:36 896 8192000000 10558323872 11004862464 1195522592 12.735 100 98 195 2 1663981360 443769024 24689744
12:00:40 1246 8192000000 10560085312 10994262016 1196591600 12.745 100 97 191 4 1665749552 445702384 23455568
12:01:45 2638 8192000000 10625040640 11048476672 1185751280 12.644 100 76 149 0 1728999840 524665328 18583232
12:02:50 7956 8192000000 10597235392 11048132608 1175038016 12.544 99 53 104 0 1704399296 514747968 14613312
12:04:12 6382 8192000000 10592506176 11109838848 1184284672 12.630 100 54 106 0 1723404560 524641040 14478848
12:05:24 2413 8192000000 10610581296 11082178560 1188637232 12.671 100 58 116 0 1711679664 507967008 15075424
12:06:39 8861 8192000000 10613080976 11095310336 1186089712 12.647 100 35 70 0 1729417104 533821520 9505872
12:08:11 13853 8192000000 10558030352 11063500800 1177040496 12.563 100 32 64 1 1658932336 472983776 8908064
12:10:03 21133 8192000000 10350836960 10868289536 1152329824 12.331 100 21 41 0 1512284624 354223376 5731424
12:11:25 8704 8192000000 10316691472 10790256640 1143643472 12.250 100 36 69 1 1417140368 264972064 8524832
12:12:36 23048 8192000000 10228388896 10694922240 1146273920 12.275 99 36 71 1 1322042544 167422240 8346384
12:14:03 18226 8192000000 10221795504 10693505024 1145885008 12.271 100 24 48 1 1308779200 156774384 6119808
12:15:23 35055 8192000000 10222504160 10674483200 1152454672 12.333 100 44 83 2 1335532496 172999728 10078096
12:17:02 98315 8192000000 10064163872 10514644992 1084752560 11.693 95 10 18 0 1153951184 67331056 1867568
12:19:42 55789 8192000000 10077878656 10533863424 1100383968 11.841 96 17 33 0 1162608608 59254384 2970256
12:21:38 51827 8192000000 10129275792 10571034624 1142191008 12.236 99 20 38 1 1212941456 66354960 4395488
12:23:50 81090 8192000000 10101562608 10627264512 1120241728 12.029 98 12 24 0 1170098336 47638864 2217744
12:26:11 83217 8192000000 10077498784 10529173504 1130062960 12.122 99 32 64 2 1154818288 17823904 6931424
12:28:36 32525 8192000000 10102824992 10560659456 1147154000 12.283 100 61 118 2 1216029104 55180032 13695072
12:30:19 17430 8192000000 10059298736 10491281408 1123235856 12.058 99 41 78 1 1142310592 10127552 8947184
12:31:39 8386 8192000000 10093429920 10525437952 1135254608 12.171 100 43 81 1 1191655680 47682816 8718256
12:32:50 29987 8192000000 10125891296 10590236672 1149457424 12.304 100 37 74 0 1233409184 75210608 8741152
12:34:38 32468 8192000000 10151087872 10632499200 1128097824 12.103 98 45 88 3 1239965088 101821200 10046064
12:36:30 66923 8192000000 10072317024 10537795584 1108583664 11.919 98 12 24 1 1179045088 66923968 3537456
12:38:37 69003 8192000000 10084098656 10567237632 1113613264 11.967 98 12 24 0 1190972512 74856160 2503088
12:40:47 56183 8192000000 10071645904 10511171584 1134432048 12.163 100 22 40 2 1168134464 29839904 3862512
12:42:43 55818 8192000000 10083809376 10546110464 1107145904 11.905 97 20 38 2 1155903040 44594736 4162400
12:45:39 83663 8192000000 10090485008 10610143232 1071913440 11.570 95 23 46 0 1222009152 144975088 5120624
12:48:03 28832 8192000000 10068644768 10511626240 1109472432 11.927 98 19 38 0 1141178816 28125440 3580944
12:49:44 23111 8192000000 10088802512 10547351552 1123063840 12.056 99 29 57 0 1203079920 73666240 6349840
12:51:10 24109 8192000000 10059842816 10518650880 1086020368 11.705 97 42 83 1 1157924720 63195600 8708752
12:52:36 7988 8192000000 10086694096 10521755648 1112172592 11.953 99 47 92 1 1186807264 65134912 9499760
12:53:45 10070 8192000000 10093250768 10523127808 1104646112 11.882 99 47 92 1 1174688384 60534480 9507792
12:54:55 66135 8192000000 10075231968 10513920000 1110874480 11.941 99 28 56 2 1197576064 81154128 5547456
12:57:02 47463 8192000000 10095414608 10530521088 1112482608 11.956 99 39 78 0 1182325120 62105344 7737168
12:58:56 16163 8192000000 10102019664 10549936128 1107182448 11.906 99 53 104 3 1190380096 72602608 10595040
13:01:12 132893 8192000000 10103567312 10592694272 1111784336 11.949 98 29 58 2 1203330368 85260480 6285552
13:04:26 8428 8192000000 10101142144 10542186496 1119624816 12.023 99 40 76 1 1191640288 64199568 7815904
13:05:38 107968 8192000000 10119919392 10589437952 1115037024 11.980 98 12 24 0 1218166944 100334464 2795456
13:08:27 99347 8192000000 10050276752 10500481024 1075510016 11.605 95 21 42 1 1112816592 32983360 4323216
13:11:08 41470 8192000000 10054611136 10507730944 1109574720 11.928 98 23 46 1 1141275760 26915104 4785936
13:13:16 109121 8192000000 10061720704 10549628928 1135544624 12.174 100 15 28 1 1194940320 56330656 3065040
13:16:07 15665 8192000000 10096528400 10536366080 1141231424 12.227 100 54 103 1 1196970096 44449168 11289504
13:17:30 28228 8192000000 10114147392 10569945088 1148277120 12.293 100 37 72 0 1208789792 52926320 7586352
13:19:00 19396 8192000000 10148992416 10579574784 1146079584 12.273 100 49 97 1 1238650112 82984720 9585808
13:20:20 32585 8192000000 10204778848 10657263616 1151841376 12.327 99 42 84 1 1290815360 130385200 8588784
13:22:08 46106 8192000000 10142589712 10597064704 1072970320 11.580 93 39 74 2 1186635920 105654272 8011328
13:24:01 19971 8192000000 10106611440 10529800192 1140103056 12.217 100 47 91 1 1205383968 55183248 10097664
13:25:24 5086 8192000000 10131186720 10563637248 1154721360 12.354 100 49 97 1 1226037920 60963936 10352624
13:26:30 15120 8192000000 10157237616 10600308736 1144469216 12.258 99 49 98 1 1235352704 80940608 9942880
13:27:48 107140 8192000000 10137398704 10593058816 1122989328 12.055 97 21 43 1 1216830224 88852656 4988240
13:31:20 69186 8192000000 10080687376 10560163840 1133133888 12.151 99 10 20 0 1167917728 32330704 2453136
13:33:29 88022 8192000000 10125075520 10597629952 1132577808 12.146 99 14 28 0 1219934864 84002128 3354928
13:36:02 79382 8192000000 9528017232 9957646336 627471840 7.114 55 10 20 1 706159296 76534752 2152704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-05-2022 13:39:07 to 25-05-2022 13:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 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 25-05-2022 13:39:24 to 25-05-2022 13:39:26.

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          221500
MON$OLDEST_ACTIVE               221501
MON$OLDEST_SNAPSHOT             221501
MON$NEXT_TRANSACTION            221501
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                       2111872
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

25.05.22 13:39:27. 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-25 13:39:30 No severe PSQL-related problems occured
25.05.22 13:39:31. End of report.