Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.01.2023 22: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 15240568 kB
MemAvailable 30449548 kB
Buffers 0 kB
Cached 16488004 kB
SwapCached 3732 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-31 22:38:37
DTS_END                         2023-01-31 22:38:37.4850
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53950
TRN_ID                          243766
ATT_ID                          660
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9469.37 20849 1136324
customer order: creation 176.21 394 21145
customer order: refuse 14.59 389 1751
order to supplier: creation 47.52 1239 5702
order to supplier: removal 3.49 99665 419
invoice (draft): creation 47.62 3584 5714
invoice (draft): removal 54.78 668 6573
invoice accept: apply 42.63 16623 5115
invoice accept: cancel 49.56 799 5947
customer reserve: creation 3882.68 166 465922
customer reserve: removal 4836.36 58 580363
realization accept: apply 85.78 187 10293
realization accept: cancel 97.19 105 11663
payment from customer: creation 60.43 120 7251
payment from customer: removal 6.27 68 752
payment to supplier: creation 49.14 133 5897
payment to supplier: removal 3.46 46 415
service: total inventory turnovers 4.51 1318 541
service: total monetary turnovers 6.33 33 759
service: aggregate perf. data 0.82 22877 98
service: refresh index statistics 0.03 268516 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-01-2023 22:40:37 to 31-01-2023 22:40:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5011
TEST_TIME 2 3916
TEST_TIME 3 5115
TEST_TIME 4 4746
TEST_TIME 5 4992
TEST_TIME 6 5758
TEST_TIME 7 5503
TEST_TIME 8 5965
TEST_TIME 9 5924
TEST_TIME 10 6299
TEST_TIME 11 6262
TEST_TIME 12 6247
TEST_TIME 13 6512
TEST_TIME 14 6782
TEST_TIME 15 7009
TEST_TIME 16 7168
TEST_TIME 17 7355
TEST_TIME 18 7679
TEST_TIME 19 7932
TEST_TIME 20 8344
TEST_TIME 21 8597
TEST_TIME 22 9335
TEST_TIME 23 9339
TEST_TIME 24 9702
TEST_TIME 25 9732
TEST_TIME 26 9874
TEST_TIME 27 10192
TEST_TIME 28 10685
TEST_TIME 29 10553
TEST_TIME 30 10368
TEST_TIME 31 10342
TEST_TIME 32 10485
TEST_TIME 33 10357
TEST_TIME 34 10395
TEST_TIME 35 10402
TEST_TIME 36 10363
TEST_TIME 37 10139
TEST_TIME 38 10059
TEST_TIME 39 10175
TEST_TIME 40 10050
TEST_TIME 41 10040
TEST_TIME 42 9906
TEST_TIME 43 9832
TEST_TIME 44 9931
TEST_TIME 45 9855
TEST_TIME 46 9861
TEST_TIME 47 9858
TEST_TIME 48 9748
TEST_TIME 49 9619
TEST_TIME 50 9735
TEST_TIME 51 9950
TEST_TIME 52 9877
TEST_TIME 53 9918
TEST_TIME 54 9995
TEST_TIME 55 9972
TEST_TIME 56 9985
TEST_TIME 57 9975
TEST_TIME 58 10007
TEST_TIME 59 9953
TEST_TIME 60 9992
TEST_TIME 61 9992
TEST_TIME 62 10093
TEST_TIME 63 9998
TEST_TIME 64 9937
TEST_TIME 65 9825
TEST_TIME 66 9788
TEST_TIME 67 9799
TEST_TIME 68 9764
TEST_TIME 69 9707
TEST_TIME 70 9721
TEST_TIME 71 9679
TEST_TIME 72 9709
TEST_TIME 73 9629
TEST_TIME 74 9584
TEST_TIME 75 9580
TEST_TIME 76 9590
TEST_TIME 77 9540
TEST_TIME 78 9576
TEST_TIME 79 9643
TEST_TIME 80 9640
TEST_TIME 81 9623
TEST_TIME 82 9582
TEST_TIME 83 9659
TEST_TIME 84 9706
TEST_TIME 85 9779
TEST_TIME 86 9767
TEST_TIME 87 9735
TEST_TIME 88 9733
TEST_TIME 89 9649
TEST_TIME 90 9615
TEST_TIME 91 9555
TEST_TIME 92 9591
TEST_TIME 93 9578
TEST_TIME 94 9555
TEST_TIME 95 9529
TEST_TIME 96 9542
TEST_TIME 97 9591
TEST_TIME 98 9577
TEST_TIME 99 9540
TEST_TIME 100 9593
TEST_TIME 101 9570
TEST_TIME 102 9650
TEST_TIME 103 9634
TEST_TIME 104 9681
TEST_TIME 105 9675
TEST_TIME 106 9749
TEST_TIME 107 9715
TEST_TIME 108 9725
TEST_TIME 109 9642
TEST_TIME 110 9629
TEST_TIME 111 9593
TEST_TIME 112 9582
TEST_TIME 113 9551
TEST_TIME 114 9552
TEST_TIME 115 9547
TEST_TIME 116 9507
TEST_TIME 117 9480
TEST_TIME 118 9466
TEST_TIME 119 9523
TEST_TIME 120 9470

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-01-2023 22:40:41 to 31-01-2023 22:40:44.

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 1569091 1569091 0 0.00 0 16876 6 0 0
doc_list_biud 1569091 1569091 0 0.00 0 32230 27 0 0
sp_add_invoice_to_stock 5240 5115 125 2.38 40 210265 16624 0 125
sp_cancel_adding_invoice 5947 5947 0 0.00 4 76350 800 0 0
sp_cancel_client_order 1751 1751 0 0.00 4 18697 389 0 0
sp_cancel_customer_reserve 580363 580363 0 0.00 1 32232 58 0 0
sp_cancel_pay_from_customer 752 752 0 0.00 2 6922 68 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 720 47 0 0
sp_cancel_supplier_invoice 6581 6573 8 0.12 1 25965 668 0 8
sp_cancel_supplier_order 419 419 0 0.00 6 978176 99665 0 0
sp_cancel_write_off 11663 11663 0 0.00 1 10862 105 0 0
sp_client_order 21145 21145 0 0.00 3 28432 394 0 0
sp_customer_reserve 465922 465922 0 0.00 0 83865 166 0 0
sp_fill_shopping_cart 32531 32531 0 0.00 1 13647 36 0 0
sp_fill_shopping_cart_clo_res 465918 465918 0 0.00 0 20447 14 0 0
sp_get_clo_for_invoice 5111 5111 0 0.00 0 37889 346 0 0
sp_kill_cost_storno 18777 18777 0 0.00 0 14052 51 0 0
sp_kill_qstorno_ret_qs2qd 1179381 1179381 0 0.00 0 32188 31 0 0
sp_kill_qty_storno 622120 622120 0 0.00 0 32229 67 0 0
sp_lock_selected_doc 21428 21428 0 0.00 0 10088 7 0 0
sp_make_cost_storno 28552 28552 0 0.00 0 16868 52 0 0
sp_multiply_rows_for_pdistr 28552 28552 0 0.00 0 11905 27 0 0
sp_multiply_rows_for_qdistr 21145 21145 0 0.00 0 28426 173 0 0
sp_pay_from_customer 7251 7251 0 0.00 4 12772 121 0 0
sp_pay_to_supplier 5897 5897 0 0.00 5 12221 134 0 0
sp_payment_common 13148 13148 0 0.00 1 12766 34 0 0
sp_qd_handle_on_cancel_clo 1751 1751 0 0.00 0 18529 355 0 0
sp_qd_handle_on_invoice_upd_sts 11058 11058 0 0.00 0 32024 317 0 0
sp_qd_handle_on_reserve_upd_sts 10293 10293 0 0.00 0 22425 62 0 0
sp_reserve_write_off 10293 10293 0 0.00 6 22487 187 0 0
sp_supplier_invoice 5714 5714 0 0.00 4 89842 3584 0 0
sp_supplier_order 5702 5702 0 0.00 5 47052 1240 0 0
srv_aggregate_perf_data 98 98 0 0.00 2464 598883 22878 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1274 158344 48229 0 0
srv_find_qd_qs_mism 1088459 1088459 0 0.00 0 16507 5 0 0
srv_make_invnt_saldo 541 541 0 0.00 51 95785 1319 0 0
srv_make_money_saldo 759 759 0 0.00 1 2232 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 199034 398322 268517 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5115 5115 0 0.00 0 13608 157 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 7682 103 0 0
t$perf-norm:sp_cancel_client_order 1751 1751 0 0.00 0 2403 12 0 0
t$perf-norm:sp_cancel_customer_reserve 767 767 0 0.00 0 5772 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 752 752 0 0.00 0 473 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 828 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 945 13 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 20246 1605 0 0
t$perf-norm:sp_cancel_write_off 1097 1097 0 0.00 0 5737 16 0 0
t$perf-norm:sp_client_order 21145 21145 0 0.00 0 16733 19 0 0
t$perf-norm:sp_customer_reserve 1059 1059 0 0.00 0 5013 10 0 0
t$perf-norm:sp_pay_from_customer 7251 7251 0 0.00 0 8133 8 0 0
t$perf-norm:sp_pay_to_supplier 5897 5897 0 0.00 0 6487 9 0 0
t$perf-norm:sp_reserve_write_off 10293 10293 0 0.00 0 5236 11 0 0
t$perf-norm:sp_supplier_invoice 5714 5714 0 0.00 0 9876 21 0 0
t$perf-norm:sp_supplier_order 5702 5702 0 0.00 0 9428 20 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 401 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1512 26 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 1827 27 0 0
t$perf-norm:srv_make_money_saldo 759 759 0 0.00 0 1450 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 1 0 0
v_add_invoice_to_stock 10472 10222 250 2.38 0 7223 23 0 250
v_all_customers 38532 38532 0 0.00 0 21 1 0 0
v_all_suppliers 31108 31108 0 0.00 0 20 1 0 0
v_all_wares 42290 42290 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 3631 12 0 0
v_cancel_client_order 3502 3502 0 0.00 0 4307 9 0 0
v_cancel_customer_prepayment 1504 1504 0 0.00 0 2011 6 0 0
v_cancel_customer_reserve 1534 1534 0 0.00 0 3553 9 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 714 5 0 0
v_cancel_supplier_invoice 860 844 16 1.86 0 5586 30 0 16
v_cancel_supplier_order 838 838 0 0.00 0 3598 19 0 0
v_cancel_write_off 2194 2194 0 0.00 0 5014 21 0 0
v_max_id_clo_ord 5702 5702 0 0.00 0 1156 1 0 0
v_max_id_clo_res 1059 1059 0 0.00 0 3068 17 0 0
v_max_id_ord_sup 5684 5684 0 0.00 0 3593 3 0 0
v_max_non_paid_invoice 1729 1729 0 0.00 0 3727 10 0 0
v_max_non_paid_realizn 3916 3916 0 0.00 0 3486 4 0 0
v_min_id_clo_ord 5702 5702 0 0.00 0 2758 7 0 0
v_min_id_clo_res 1059 1059 0 0.00 0 2418 10 0 0
v_min_id_ord_sup 5684 5684 0 0.00 0 8214 9 0 0
v_min_non_paid_invoice 5897 5897 0 0.00 0 9753 24 0 0
v_min_non_paid_realizn 7251 7251 0 0.00 0 8370 24 0 0
v_our_firm 10428 10428 0 0.00 0 16 1 0 0
v_reserve_write_off 20586 20586 0 0.00 0 6367 10 0 0
x_lock_depdocs_on_canc_invoice 12098 12098 0 0.00 0 23529 122 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 16163 328 0 0
x_make_qstorno_1000_1200 5702 5702 0 0.00 1 47044 1167 0 0
x_make_qstorno_1200_2000 5714 5714 0 0.00 1 89835 3451 0 0
x_make_qstorno_2100_3300 465922 465922 0 0.00 0 83864 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-01-2023 22:40:45 to 31-01-2023 22:40:49.

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
20:08:41 4 8192000000 8802620480 8815284224 8261760 0.100 3 1 2 0 8367456 62912 42784
20:09:42 37987 8192000000 9904445952 10213597184 984041296 10.724 100 87 174 0 1013595488 6262272 23291920
20:11:25 7564 8192000000 9936751104 10252902400 1019123680 11.064 100 93 186 0 1050575136 7968144 23483312
20:12:42 9580 8192000000 9967336096 10298286080 1056874560 11.427 100 80 160 0 1086115408 9045456 20195392
20:13:56 10188 8192000000 10025121856 10365104128 1110318320 11.935 100 79 158 0 1139295104 10392000 18584784
20:15:09 10577 8192000000 10070309088 10423623680 1152439120 12.332 100 77 154 0 1182289856 12126528 17724208
20:16:22 4012 8192000000 10113014528 10477494272 1197782944 12.756 100 80 160 0 1229254176 13024144 18447088
20:17:32 2597 8192000000 10149699968 10523467776 1242218064 13.167 100 83 164 3 1272384928 12259552 17907312
20:18:37 1344 8192000000 10184568464 10561818624 1271998800 13.440 100 91 181 0 1305546160 13896800 19650560
20:19:40 1195 8192000000 10206979136 10591199232 1293776272 13.639 100 87 175 1 1326877408 14382720 18718416
20:20:42 4954 8192000000 10203762736 10594914304 1295940416 13.658 100 57 115 0 1316590160 7457664 13192080
20:21:48 5644 8192000000 10195548336 10604515328 1302654800 13.719 100 32 64 1 1317079248 7704880 6719568
20:22:55 9410 8192000000 10202970880 10612760576 1312383952 13.808 100 19 36 1 1322178112 5445792 4348368
20:24:08 4200 8192000000 10217568048 10631671808 1322333568 13.898 100 30 60 0 1336072960 6456848 7282544
20:25:15 21769 8192000000 10231641056 10637156352 1326166224 13.933 100 51 100 2 1343827616 5445344 12216048
20:26:38 4397 8192000000 10228820368 10644905984 1334337088 14.006 100 24 49 0 1345496784 6018304 5141392
20:27:42 6369 8192000000 10226692992 10646073344 1311430528 13.799 98 29 58 0 1323929648 5943232 6555888
20:28:56 61111 8192000000 10169483280 10580017152 1171268928 12.509 89 25 49 0 1182218112 5689200 5259984
20:30:57 5173 8192000000 10164287728 10574569472 1276348416 13.480 100 19 38 0 1286657072 5912640 4396016
20:32:03 6932 8192000000 10136727840 10542071808 1247370320 13.214 100 22 44 1 1258219296 5714704 5134272
20:33:12 64350 8192000000 10169180688 10575945728 1271998320 13.440 100 32 62 0 1284295056 4740432 7556304
20:35:19 4979 8192000000 10174159552 10584973312 1273014176 13.449 99 29 58 0 1286481936 6299776 7167984
20:36:26 4963 8192000000 10187523136 10603618304 1298565376 13.682 100 25 50 0 1309405072 4989808 5849888
20:37:32 10748 8192000000 10198699584 10616336384 1308750304 13.775 100 17 32 0 1317940224 5349376 3840544
20:38:44 41929 8192000000 10236363168 10649444352 1332185776 13.987 100 40 78 1 1349784128 8424128 9174224
20:40:31 21595 8192000000 10265417200 10680344576 1353276672 14.177 100 58 117 2 1379727072 13503936 12946464
20:41:58 47299 8192000000 10279116464 10704314368 1374869728 14.371 100 45 82 0 1399028960 14364576 9794656
20:44:08 22818 8192000000 10292342496 10722091008 1372185120 14.347 99 43 85 0 1400767536 18000624 10581792
20:45:41 6758 8192000000 10317296432 10737467392 1395531136 14.555 100 61 122 2 1428874880 19400688 13943056
20:46:56 4173 8192000000 10325697408 10751844352 1403182688 14.623 100 47 94 0 1434915232 20683328 11049216
20:48:08 7454 8192000000 10329884704 10752888832 1393405712 14.536 99 52 104 0 1430348560 24541216 12401632
20:49:21 5859 8192000000 10325346272 10744176640 1385665792 14.467 98 61 122 0 1423593184 23343920 14583472
20:50:32 5397 8192000000 10302366816 10717306880 1361694928 14.253 98 58 114 1 1402005056 26157280 14152848
20:51:43 5599 8192000000 10319310864 10737668096 1382584736 14.440 99 51 101 1 1426318048 31061312 12672000
20:52:57 1453 8192000000 10356380240 10759094272 1399953888 14.595 100 86 167 1 1455886720 35858512 20074320
20:54:04 11784 8192000000 10358166016 10774949888 1409372512 14.678 100 73 145 0 1465123024 37427264 18323248
20:55:25 2450 8192000000 10350517584 10763808768 1390178848 14.507 99 76 149 0 1444441200 35436096 18826256
20:56:35 1271 8192000000 10336494544 10748628992 1374896544 14.371 99 85 171 0 1434183440 38188752 21098144
20:57:40 14469 8192000000 10338428176 10757894144 1388776496 14.495 100 39 78 1 1437037552 37115952 11145104
20:59:00 11205 8192000000 10325834800 10748444672 1372731536 14.352 99 39 77 0 1417384112 33919136 10733440
21:00:14 10013 8192000000 10319723216 10754301952 1385056576 14.462 100 36 72 0 1432199872 37115088 10028208
21:01:28 3001 8192000000 10323094160 10742181888 1384182624 14.454 100 56 111 0 1427138224 29493600 13462000
21:02:34 19262 8192000000 10324218864 10759471104 1394759056 14.548 100 33 65 0 1432217376 28649360 8808960
21:04:13 43589 8192000000 10300266448 10746892288 1376947568 14.389 99 18 36 0 1407849072 26001344 4900160
21:06:13 49944 8192000000 10249496016 10681016320 1272629680 13.446 94 11 22 0 1290449888 14953296 2866912
21:08:06 60937 8192000000 10232277872 10647691264 1288114608 13.587 95 22 43 0 1300255024 7735344 4405072
21:10:21 146855 8192000000 10291331776 10736902144 1353953600 14.183 99 9 16 0 1370110768 14455888 1701280
21:13:51 158343 8192000000 10306658112 10746642432 1370134944 14.328 98 30 56 1 1388158544 12228816 5794784
21:17:50 79719 8192000000 10295867296 10754793472 1390735344 14.512 100 20 40 0 1407911264 12689024 4486896
21:20:12 9256 8192000000 10318476736 10763321344 1405811776 14.647 100 39 72 1 1425053136 10797728 8443632
21:21:31 25814 8192000000 10329849568 10765262848 1377022448 14.390 98 49 98 0 1406285872 17283072 11980352
21:23:02 66968 8192000000 10302214896 10764177408 1385639808 14.467 99 21 42 1 1405479552 14265344 5574400
21:25:11 56134 8192000000 10311608624 10775216128 1396024208 14.560 99 17 34 0 1419276560 19193664 4058688
21:27:09 87134 8192000000 10313223376 10789072896 1403661536 14.628 100 19 38 0 1428818432 20252864 4904032
21:29:36 34354 8192000000 10322767088 10766725120 1384609792 14.458 99 22 44 0 1398498576 8819280 5069504
21:31:12 45246 8192000000 10297718896 10758971392 1377805312 14.397 99 20 40 0 1396802832 14208288 4789232
21:32:58 60699 8192000000 10286770240 10740637696 1361335472 14.249 99 18 36 1 1377033552 11176624 4521456
21:35:30 117334 8192000000 10245865728 10691854336 1228735568 13.042 89 5 10 0 1239346752 9667744 943440
21:38:29 95619 8192000000 10288277936 10729345024 1314516272 13.827 96 22 43 1 1327727632 9073168 4138192
21:41:06 121291 8192000000 10267082864 10720391168 1341974784 14.075 98 18 36 0 1356856528 11032128 3849616
21:44:14 41921 8192000000 10270341392 10724728832 1358904864 14.228 100 20 40 0 1375654432 12114240 4635328
21:45:58 54529 8192000000 10249272672 10711408640 1317988864 13.858 96 11 22 0 1332544832 11781600 2774368
21:47:53 36199 8192000000 10252966272 10702618624 1345777792 14.109 99 19 38 0 1363668560 12988560 4902208
21:49:51 60167 8192000000 10233394960 10696531968 1309142032 13.778 97 15 30 0 1328905104 16403376 3359696
21:51:54 23525 8192000000 10255710656 10695421952 1344822624 14.101 99 41 76 1 1363736304 9578288 9335392
21:53:18 77732 8192000000 10236565984 10701316096 1329085776 13.959 99 18 36 0 1345209536 11780256 4343504
21:55:41 67127 8192000000 10270984176 10725003264 1365285872 14.285 100 24 49 0 1382544416 11509520 5749024
21:57:53 43976 8192000000 10275406656 10720858112 1356981968 14.210 99 15 30 0 1374962832 14811536 3169328
21:59:45 36545 8192000000 10284057472 10727469056 1364024672 14.273 100 36 72 0 1384775344 12713024 8037648
22:01:37 23483 8192000000 10276800752 10715844608 1348171744 14.131 99 32 62 0 1368416208 12745728 7498736
22:03:09 100531 8192000000 10278611600 10731102208 1368995680 14.318 100 8 16 0 1384609312 13788416 1825216
22:05:54 37928 8192000000 10290131248 10732589056 1357260752 14.213 98 23 44 0 1372269024 9931008 5077264
22:07:38 67605 8192000000 10290636176 10740715520 1366915600 14.299 99 28 56 0 1380472592 7430832 6126160
22:09:47 28127 8192000000 10288559904 10751381504 1390381200 14.509 100 26 52 1 1410299008 14061456 5856352
22:11:45 31151 8192000000 10287904368 10763468800 1388851808 14.496 100 5 10 0 1403114896 13378304 884784
22:13:21 30306 8192000000 10296266720 10753032192 1397083104 14.569 100 24 48 0 1410178624 7928416 5167104
22:15:03 105660 8192000000 10300391056 10749644800 1335052272 14.013 95 22 44 0 1350545712 10708208 4785232
22:17:50 28346 8192000000 10326228480 10770694144 1397404784 14.572 100 37 74 1 1417590464 11749536 8436144
22:19:25 90999 8192000000 10299181104 10772676608 1370904656 14.335 98 12 24 0 1395332720 21627808 2800256
22:22:07 55594 8192000000 10284137200 10747121664 1318214816 13.861 94 32 64 0 1339908096 14585920 7107360
22:24:05 97022 8192000000 10267719248 10712567808 1275513344 13.472 92 9 18 0 1289386528 11654336 2218848
22:26:42 142512 8192000000 10283239120 10735071232 1360622624 14.243 99 22 44 0 1377092704 11410080 5060000
22:30:10 16144 8192000000 10283742528 10751430656 1384181296 14.454 100 13 26 0 1402614656 15270912 3162448
22:31:27 58279 8192000000 10287681232 10756947968 1387352032 14.482 100 14 28 0 1406161056 15597856 3211168
22:33:25 31413 8192000000 10276095376 10735603712 1375876816 14.380 99 14 29 2 1391027424 12287344 2863264
22:34:57 70970 8192000000 10272547632 10741415936 1362133568 14.257 99 8 16 0 1382571968 18044928 2393472
22:37:21 77484 8192000000 9914900064 10331422720 1011013632 10.985 74 7 14 0 1024095040 11763392 1318016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-01-2023 22:40:51 to 31-01-2023 22:41:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-01-2023 22:41:07 to 31-01-2023 22:41:09.

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          242726
MON$OLDEST_ACTIVE               243948
MON$OLDEST_SNAPSHOT             243948
MON$NEXT_TRANSACTION            243948
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                       2040800
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.33657 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33657 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33657 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33657 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 ---
:1070: 
:1071: mscs-test-161.budzdorov.ru    Tue Jan 31 22:38:55 2023
:1072:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1073: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

31.01.23 22:41:10. 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-31 22:41:13 No severe PSQL-related problems occured
31.01.23 22:41:14. End of report.