Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.01.2023 01:40
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 15213040 kB
MemAvailable 30435396 kB
Buffers 0 kB
Cached 16521160 kB
SwapCached 2284 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2023-01-16 01:39:04
DTS_END                         2023-01-16 01:38:36.8900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35414
TRN_ID                          251271
ATT_ID                          274
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: 9905.50 18578 1188660
customer order: creation 187.85 322 22542
customer order: refuse 15.98 338 1917
order to supplier: creation 48.93 1213 5871
order to supplier: removal 3.65 100839 438
invoice (draft): creation 48.99 2950 5879
invoice (draft): removal 56.88 821 6825
invoice accept: apply 45.24 16043 5429
invoice accept: cancel 50.79 727 6095
customer reserve: creation 4140.11 161 496813
customer reserve: removal 4986.44 57 598373
realization accept: apply 89.00 162 10680
realization accept: cancel 90.92 105 10910
payment from customer: creation 64.29 103 7715
payment from customer: removal 6.39 53 767
payment to supplier: creation 53.21 114 6385
payment to supplier: removal 3.64 72 437
service: total inventory turnovers 4.78 1062 573
service: total monetary turnovers 7.33 28 880
service: aggregate perf. data 1.06 12975 127
service: refresh index statistics 0.03 233422 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-01-2023 01:40:24 to 16-01-2023 01:40:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3690
TEST_TIME 2 5004
TEST_TIME 3 4508
TEST_TIME 4 4859
TEST_TIME 5 5448
TEST_TIME 6 5452
TEST_TIME 7 5350
TEST_TIME 8 5713
TEST_TIME 9 5656
TEST_TIME 10 5820
TEST_TIME 11 6140
TEST_TIME 12 6507
TEST_TIME 13 6434
TEST_TIME 14 6903
TEST_TIME 15 7034
TEST_TIME 16 7387
TEST_TIME 17 7282
TEST_TIME 18 7236
TEST_TIME 19 7569
TEST_TIME 20 8093
TEST_TIME 21 8378
TEST_TIME 22 8596
TEST_TIME 23 8663
TEST_TIME 24 8710
TEST_TIME 25 8811
TEST_TIME 26 9202
TEST_TIME 27 9936
TEST_TIME 28 10796
TEST_TIME 29 10723
TEST_TIME 30 11055
TEST_TIME 31 10883
TEST_TIME 32 10696
TEST_TIME 33 10608
TEST_TIME 34 10823
TEST_TIME 35 10738
TEST_TIME 36 10742
TEST_TIME 37 10712
TEST_TIME 38 10948
TEST_TIME 39 10906
TEST_TIME 40 10742
TEST_TIME 41 10502
TEST_TIME 42 10459
TEST_TIME 43 10407
TEST_TIME 44 10376
TEST_TIME 45 10260
TEST_TIME 46 10227
TEST_TIME 47 10130
TEST_TIME 48 10060
TEST_TIME 49 10149
TEST_TIME 50 10244
TEST_TIME 51 10209
TEST_TIME 52 10131
TEST_TIME 53 10055
TEST_TIME 54 10027
TEST_TIME 55 9971
TEST_TIME 56 9896
TEST_TIME 57 9782
TEST_TIME 58 9835
TEST_TIME 59 9821
TEST_TIME 60 9762
TEST_TIME 61 9768
TEST_TIME 62 9796
TEST_TIME 63 9911
TEST_TIME 64 9849
TEST_TIME 65 9947
TEST_TIME 66 9991
TEST_TIME 67 10097
TEST_TIME 68 10042
TEST_TIME 69 10105
TEST_TIME 70 10127
TEST_TIME 71 10183
TEST_TIME 72 10157
TEST_TIME 73 10217
TEST_TIME 74 10120
TEST_TIME 75 10052
TEST_TIME 76 10042
TEST_TIME 77 9978
TEST_TIME 78 9974
TEST_TIME 79 9956
TEST_TIME 80 9886
TEST_TIME 81 9829
TEST_TIME 82 9859
TEST_TIME 83 9802
TEST_TIME 84 9838
TEST_TIME 85 9836
TEST_TIME 86 9890
TEST_TIME 87 9856
TEST_TIME 88 9872
TEST_TIME 89 9833
TEST_TIME 90 9940
TEST_TIME 91 9891
TEST_TIME 92 9984
TEST_TIME 93 9990
TEST_TIME 94 10012
TEST_TIME 95 9989
TEST_TIME 96 10034
TEST_TIME 97 10012
TEST_TIME 98 9938
TEST_TIME 99 9894
TEST_TIME 100 9912
TEST_TIME 101 9897
TEST_TIME 102 9872
TEST_TIME 103 9845
TEST_TIME 104 9864
TEST_TIME 105 9812
TEST_TIME 106 9745
TEST_TIME 107 9761
TEST_TIME 108 9742
TEST_TIME 109 9775
TEST_TIME 110 9793
TEST_TIME 111 9819
TEST_TIME 112 9856
TEST_TIME 113 9893
TEST_TIME 114 9897
TEST_TIME 115 9887
TEST_TIME 116 9888
TEST_TIME 117 9868
TEST_TIME 118 9869
TEST_TIME 119 9948
TEST_TIME 120 9910

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-01-2023 01:40:27 to 16-01-2023 01:40:31.

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 1648390 1648390 0 0.00 0 17496 6 0 0
doc_list_biud 1648390 1648390 0 0.00 0 71128 26 0 0
sp_add_invoice_to_stock 5558 5429 129 2.32 32 153260 16043 0 129
sp_cancel_adding_invoice 6095 6095 0 0.00 4 67296 728 0 0
sp_cancel_client_order 1917 1917 0 0.00 4 28552 338 0 0
sp_cancel_customer_reserve 598373 598373 0 0.00 0 23731 58 0 0
sp_cancel_pay_from_customer 767 767 0 0.00 2 5079 53 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 4239 72 0 0
sp_cancel_supplier_invoice 6836 6825 11 0.16 2 71141 822 0 11
sp_cancel_supplier_order 438 438 0 0.00 8 1103093 100839 0 0
sp_cancel_write_off 10910 10910 0 0.00 1 14209 105 0 0
sp_client_order 22542 22542 0 0.00 3 22563 323 0 0
sp_customer_reserve 496813 496813 0 0.00 0 84552 161 0 0
sp_fill_shopping_cart 34292 34292 0 0.00 1 13056 34 0 0
sp_fill_shopping_cart_clo_res 496807 496807 0 0.00 0 19669 12 0 0
sp_get_clo_for_invoice 5417 5417 0 0.00 0 22459 262 0 0
sp_kill_cost_storno 18209 18209 0 0.00 0 4978 52 0 0
sp_kill_qstorno_ret_qs2qd 1214919 1214919 0 0.00 0 71118 32 0 0
sp_kill_qty_storno 640653 640653 0 0.00 0 71125 67 0 0
sp_lock_selected_doc 22301 22301 0 0.00 0 9525 6 0 0
sp_make_cost_storno 30195 30195 0 0.00 0 15716 43 0 0
sp_multiply_rows_for_pdistr 30195 30195 0 0.00 0 14477 25 0 0
sp_multiply_rows_for_qdistr 22542 22542 0 0.00 0 13408 141 0 0
sp_pay_from_customer 7715 7715 0 0.00 3 24654 104 0 0
sp_pay_to_supplier 6385 6385 0 0.00 4 14557 115 0 0
sp_payment_common 14100 14100 0 0.00 1 24617 24 0 0
sp_qd_handle_on_cancel_clo 1917 1917 0 0.00 0 28543 285 0 0
sp_qd_handle_on_invoice_upd_sts 11510 11510 0 0.00 0 15866 291 0 0
sp_qd_handle_on_reserve_upd_sts 10680 10680 0 0.00 0 12592 52 0 0
sp_reserve_write_off 10680 10680 0 0.00 6 14241 162 0 0
sp_supplier_invoice 5879 5879 0 0.00 6 96449 2950 0 0
sp_supplier_order 5871 5871 0 0.00 5 37408 1214 0 0
srv_aggregate_perf_data 127 127 0 0.00 2573 90396 12976 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1111 113829 42515 0 0
srv_find_qd_qs_mism 1136872 1136872 0 0.00 0 17483 5 0 0
srv_make_invnt_saldo 573 573 0 0.00 27 37762 1062 0 0
srv_make_money_saldo 880 880 0 0.00 0 7044 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 178066 324969 233423 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5429 5429 0 0.00 0 9609 143 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 7421 108 0 0
t$perf-norm:sp_cancel_client_order 1917 1917 0 0.00 0 1836 9 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 4645 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 767 767 0 0.00 0 2109 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 2537 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 2493 18 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 20901 1667 0 0
t$perf-norm:sp_cancel_write_off 1084 1084 0 0.00 0 5440 16 0 0
t$perf-norm:sp_client_order 22542 22542 0 0.00 0 8406 18 0 0
t$perf-norm:sp_customer_reserve 1131 1131 0 0.00 0 2097 7 0 0
t$perf-norm:sp_pay_from_customer 7715 7715 0 0.00 0 6467 12 0 0
t$perf-norm:sp_pay_to_supplier 6385 6385 0 0.00 0 8591 14 0 0
t$perf-norm:sp_reserve_write_off 10680 10680 0 0.00 0 12290 13 0 0
t$perf-norm:sp_supplier_invoice 5879 5879 0 0.00 0 7182 19 0 0
t$perf-norm:sp_supplier_order 5871 5871 0 0.00 0 4545 15 0 0
t$perf-norm:srv_aggregate_perf_data 127 127 0 0.00 0 933 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 644 11 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 1299 19 0 0
t$perf-norm:srv_make_money_saldo 880 880 0 0.00 0 4982 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 6 0 0
v_add_invoice_to_stock 11088 10830 258 2.32 0 7981 19 0 258
v_all_customers 41324 41324 0 0.00 0 23 0 0 0
v_all_suppliers 33086 33086 0 0.00 0 22 1 0 0
v_all_wares 45084 45084 0 0.00 0 58 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 235 5 0 0
v_cancel_client_order 3834 3834 0 0.00 0 2658 4 0 0
v_cancel_customer_prepayment 1534 1534 0 0.00 0 5043 8 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 1282 4 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 4225 14 0 0
v_cancel_supplier_invoice 860 838 22 2.55 0 2844 20 0 22
v_cancel_supplier_order 876 876 0 0.00 0 1421 7 0 0
v_cancel_write_off 2168 2168 0 0.00 0 5083 14 0 0
v_max_id_clo_ord 5871 5871 0 0.00 0 1999 2 0 0
v_max_id_clo_res 1131 1131 0 0.00 0 4733 25 0 0
v_max_id_ord_sup 5879 5879 0 0.00 0 4499 4 0 0
v_max_non_paid_invoice 1592 1592 0 0.00 0 5829 6 0 0
v_max_non_paid_realizn 3974 3974 0 0.00 0 556 3 0 0
v_min_id_clo_ord 5871 5871 0 0.00 0 3312 5 0 0
v_min_id_clo_res 1131 1131 0 0.00 0 941 2 0 0
v_min_id_ord_sup 5879 5879 0 0.00 0 6114 7 0 0
v_min_non_paid_invoice 6385 6385 0 0.00 0 6279 15 0 0
v_min_non_paid_realizn 7715 7715 0 0.00 0 24602 19 0 0
v_our_firm 11242 11242 0 0.00 0 14 1 0 0
v_reserve_write_off 21360 21360 0 0.00 0 4293 6 0 0
x_lock_depdocs_on_canc_invoice 12501 12501 0 0.00 0 29647 103 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 6564 321 0 0
x_make_qstorno_1000_1200 5871 5871 0 0.00 1 37381 1146 0 0
x_make_qstorno_1200_2000 5879 5879 0 0.00 2 96436 2823 0 0
x_make_qstorno_2100_3300 496813 496813 0 0.00 0 84549 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-01-2023 01:40:32 to 16-01-2023 01:40:36.

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
23:08:40 13 8192000000 8819140912 8837242880 22146400 0.269 7 5 6 0 22418656 100800 171456
23:09:41 17768 8192000000 9890579584 10198900736 972161056 10.608 100 90 180 0 1001868192 5570784 24136352
23:11:09 21103 8192000000 9938901968 10259312640 1019410672 11.066 100 89 178 0 1049890896 7651776 22828448
23:12:36 15122 8192000000 9979074928 10309935104 1063006768 11.485 100 79 158 0 1091740224 8817792 19915664
23:13:55 10219 8192000000 10020982704 10368634880 1112339616 11.955 100 78 156 0 1140677040 9740784 18596640
23:15:09 8214 8192000000 10086393728 10445193216 1173089936 12.526 100 72 142 0 1200723952 11578640 16055376
23:16:20 5765 8192000000 10131001328 10505195520 1217288336 12.937 100 67 132 1 1244045056 12098144 14658576
23:17:27 2301 8192000000 10155249904 10536067072 1246971632 13.210 100 88 176 0 1281579040 15670224 18937184
23:18:31 1473 8192000000 10194203888 10573094912 1281383328 13.526 100 86 171 0 1312464432 12648784 18432320
23:19:34 754 8192000000 10216436640 10599915520 1301580848 13.710 100 84 168 0 1334062240 13839504 18641888
23:20:36 5726 8192000000 10194283232 10598268928 1303238256 13.725 100 25 50 0 1315889792 7286144 5365392
23:21:44 6975 8192000000 10203302832 10615582720 1316435728 13.844 100 28 55 0 1329358688 5960592 6962368
23:22:52 9539 8192000000 10211678576 10626928640 1322319408 13.898 100 15 30 0 1335746608 10139696 3287504
23:24:08 13764 8192000000 10207559488 10628673536 1326292464 13.934 100 21 43 3 1336891216 5267680 5331072
23:25:22 5221 8192000000 10244140080 10650349568 1332186176 13.987 100 68 139 1 1353742704 5629040 15927488
23:26:28 6574 8192000000 10230919776 10652860416 1342971760 14.084 100 26 52 0 1354748688 6221376 5555552
23:27:38 8944 8192000000 10230807312 10654949376 1341170416 14.068 100 14 28 0 1349809312 5678576 2960320
23:28:47 53717 8192000000 10167365472 10583355392 1203145040 12.806 90 25 49 1 1212781920 4000016 5636864
23:30:45 3973 8192000000 10158121472 10555060224 1252727680 13.263 99 27 51 1 1264841552 6060768 6053104
23:31:49 6997 8192000000 10143850512 10542551040 1243055376 13.174 99 25 52 0 1254214704 4977008 6182320
23:32:57 7579 8192000000 10144675648 10552668160 1252076528 13.257 99 31 62 0 1265398416 5524224 7797664
23:34:06 10540 8192000000 10160949440 10566012928 1269623280 13.418 100 16 32 0 1278645984 5522512 3500192
23:35:17 6122 8192000000 10192135344 10593710080 1291830080 13.621 100 36 72 1 1306594224 7057696 7706448
23:36:24 8288 8192000000 10188331840 10601238528 1299015152 13.686 100 17 34 0 1308865568 5645744 4204672
23:37:33 77946 8192000000 10207670496 10626224128 1312637600 13.810 100 23 43 0 1322127872 4547856 4942416
23:39:59 26178 8192000000 10246949648 10664529920 1344318336 14.096 100 42 82 1 1365379904 11686448 9375120
23:41:37 28129 8192000000 10283522560 10698911744 1366883008 14.299 100 48 94 1 1397194496 18976896 11334592
23:43:09 23698 8192000000 10281973728 10709250048 1374216624 14.365 100 34 66 1 1399521136 17209760 8094752
23:44:41 27283 8192000000 10301876384 10722648064 1384599376 14.458 100 38 76 0 1414217408 20288128 9329904
23:46:13 9471 8192000000 10326595536 10746040320 1397355744 14.571 100 51 96 1 1432869776 23969680 11544352
23:47:29 3624 8192000000 10333274144 10755432448 1406486304 14.653 100 59 118 1 1449244368 28465152 14292912
23:48:37 14896 8192000000 10344069872 10767101952 1401830384 14.611 99 52 104 0 1446646048 31149104 13666560
23:49:55 2884 8192000000 10369854096 10792374272 1427102272 14.836 100 66 130 0 1479055856 35653728 16299856
23:51:01 6870 8192000000 10364872256 10783883264 1418989200 14.764 100 59 116 1 1470659920 36884592 14786128
23:52:11 2015 8192000000 10373155168 10795515904 1415156112 14.730 99 67 133 0 1471798608 39592656 17049840
23:53:18 6242 8192000000 10367799200 10786938880 1400229328 14.597 99 67 132 0 1459980272 42828576 16922368
23:54:27 1095 8192000000 10398747808 10812604416 1429933520 14.861 100 84 170 1 1498701328 48052672 20715136
23:55:38 3850 8192000000 10340884800 10754871296 1373967712 14.363 99 62 123 0 1439569392 48790384 16811296
23:56:45 2544 8192000000 10369767168 10782572544 1395934320 14.559 100 71 140 2 1471389376 57565376 17889680
23:57:54 10726 8192000000 10352727056 10775613440 1390954656 14.514 100 54 108 0 1463294768 58039120 14300992
23:59:09 15969 8192000000 10349490960 10780966912 1403420304 14.625 100 42 85 1 1470500640 55221728 11858608
00:00:30 5894 8192000000 10356286720 10779639808 1392794608 14.531 100 46 89 0 1458505488 53130928 12579952
00:01:40 16270 8192000000 10351381728 10778705920 1387645440 14.485 99 39 78 0 1446551152 48214384 10691328
00:03:03 14682 8192000000 10314321168 10746142720 1338738448 14.046 98 31 62 1 1402462608 54295264 9428896
00:04:21 31219 8192000000 10309643792 10755137536 1349802992 14.146 100 29 58 0 1427455216 68431632 9220592
00:05:55 21127 8192000000 10297731296 10742124544 1357005888 14.210 100 23 46 0 1415938624 52523696 6409040
00:07:17 35527 8192000000 10275716128 10715185152 1344168144 14.095 99 17 35 1 1371731056 23182752 4380160
00:08:59 31146 8192000000 10259838800 10694918144 1310892496 13.794 96 24 47 0 1322456160 6590320 4973344
00:10:34 70783 8192000000 10282369168 10722717696 1354111808 14.184 99 26 52 2 1372212048 12061440 6038800
00:12:49 80956 8192000000 10286524176 10740187136 1374010848 14.363 100 8 16 0 1386295088 10745968 1538272
00:15:13 63727 8192000000 10287197376 10727571456 1309432176 13.781 95 10 20 0 1330633408 18733664 2467568
00:17:17 105987 8192000000 10290892384 10745540608 1346282224 14.114 98 13 26 1 1369097600 20409920 2405456
00:20:06 75009 8192000000 10301832976 10751987712 1384290400 14.455 100 26 50 0 1396754400 6835264 5628736
00:22:26 96893 8192000000 10318324992 10775162880 1405515824 14.644 100 38 76 0 1425749984 11657216 8576944
00:25:05 27059 8192000000 10335752512 10791866368 1415197824 14.730 100 29 58 1 1440430192 18600560 6631808
00:26:35 20467 8192000000 10333857472 10776952832 1397556416 14.573 99 48 96 0 1427181504 17799536 11825552
00:27:57 54284 8192000000 10300164576 10760896512 1371564608 14.341 98 14 28 0 1391416064 15445136 4406320
00:29:53 43558 8192000000 10315103504 10784014336 1413433632 14.714 100 10 20 0 1428316928 12696016 2187280
00:31:38 65803 8192000000 10293652704 10766753792 1392890816 14.532 99 24 49 0 1409765872 11634784 5240272
00:33:44 31970 8192000000 10298954368 10761187328 1388981600 14.497 99 29 59 0 1405938048 10546896 6409552
00:35:31 56503 8192000000 10272763824 10734993408 1358288224 14.222 97 11 22 0 1370526944 9966944 2271776
00:37:43 36216 8192000000 10287343840 10749095936 1324311360 13.916 95 16 32 0 1333812256 6251472 3249424
00:39:34 32633 8192000000 10269548768 10723950592 1315636560 13.837 95 30 62 1 1332633008 10621760 6374688
00:41:11 30559 8192000000 10256490160 10715914240 1329888960 13.966 97 23 47 1 1346210272 10854784 5466528
00:42:42 39199 8192000000 10260109568 10723024896 1342769952 14.082 98 23 46 0 1357870944 9991616 5109376
00:44:28 32900 8192000000 10280673360 10735718400 1368870800 14.317 100 16 30 0 1384320432 11698976 3750656
00:46:02 100246 8192000000 10271438432 10715267072 1307254736 13.761 95 17 34 0 1329412752 17763568 4394448
00:48:45 77918 8192000000 10266322784 10724933632 1304599968 13.737 95 8 16 0 1320234592 13913360 1721264
00:51:03 28313 8192000000 10276772000 10723545088 1344907840 14.102 99 31 62 0 1362721120 11387504 6425776
00:52:32 80325 8192000000 10257464496 10714234880 1348950640 14.138 99 17 34 0 1359859056 7084160 3824256
00:54:57 67199 8192000000 10269131216 10718547968 1337796560 14.038 98 21 41 0 1355957136 13248096 4912480
00:57:04 48955 8192000000 10243863248 10708754432 1329638832 13.964 98 16 32 0 1343416272 10126544 3650896
00:58:53 24565 8192000000 10259758976 10709745664 1348365136 14.133 99 33 66 0 1364372496 8274944 7732416
01:00:21 92226 8192000000 10254197504 10723401728 1353591536 14.180 99 12 24 0 1369246192 12441728 3212928
01:02:57 41539 8192000000 10267428144 10714054656 1351716560 14.163 100 40 80 1 1371352224 10610928 9024736
01:04:40 50177 8192000000 10267097232 10717233152 1358894992 14.227 100 14 28 0 1374543280 12449584 3198704
01:06:34 40397 8192000000 10274707056 10726842368 1354157568 14.185 99 25 50 0 1372322544 12311920 5853056
01:08:15 49018 8192000000 10282670752 10747404288 1365080784 14.283 99 19 38 0 1381947456 12510768 4355904
01:10:04 58554 8192000000 10284688672 10747396096 1337505776 14.035 97 16 31 0 1350669184 10335232 2828176
01:12:04 80990 8192000000 10307051056 10757668864 1365778592 14.289 98 13 26 0 1380323744 11601792 2943360
01:14:30 78751 8192000000 10292411664 10737606656 1322259744 13.897 95 12 24 0 1335093344 10321616 2511984
01:16:50 78653 8192000000 10291334272 10747940864 1371044880 14.336 99 31 62 0 1391485776 13397744 7043152
01:19:17 30499 8192000000 10292287488 10743910400 1358089504 14.220 98 25 47 0 1375513968 12065600 5358864
01:20:50 55656 8192000000 10268705024 10734903296 1351325440 14.159 98 17 34 0 1363625792 8189040 4111312
01:22:54 56280 8192000000 10276321920 10728079360 1356564944 14.207 98 13 25 0 1367263344 7790880 2907520
01:24:51 55774 8192000000 10283883504 10755584000 1370091152 14.328 99 17 34 0 1383743216 9718048 3934016
01:27:32 59142 8192000000 10256874240 10721665024 1323334896 13.907 96 12 24 0 1336879440 10561728 2982816
01:29:35 113829 8192000000 10299223600 10775760896 1379314832 14.410 100 17 34 0 1403107040 20177760 3614448
01:32:29 48496 8192000000 10270722352 10728706048 1352394576 14.169 99 19 38 1 1371312112 14996976 3920560
01:34:18 10172 8192000000 10283806688 10728390656 1365170096 14.284 100 36 70 1 1383269248 10545904 7553248
01:35:30 22265 8192000000 10263153216 10700890112 1326883200 13.939 98 41 82 1 1345864976 10160448 8821328
01:36:55 57242 8192000000 10276913776 10735124480 1347744128 14.127 99 34 68 0 1368750240 14018688 6987424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-01-2023 01:40:38 to 16-01-2023 01:40:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-01-2023 01:40:55 to 16-01-2023 01:40:57.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          258334
MON$OLDEST_ACTIVE               258335
MON$OLDEST_SNAPSHOT             258335
MON$NEXT_TRANSACTION            258335
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-03 16:41:55.3470
MON$PAGES                       2043168
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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-oltp30 according to config parameter 'remove_isql_logs'

16.01.23 01:40:58. There are 3 logs in /var/tmp/logs-oltp30 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
2023-01-16 01:41:00 No severe PSQL-related problems occured
16.01.23 01:41:02. End of report.