Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.03.2023 16:39
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 15161824 kB
MemAvailable 30461936 kB
Buffers 0 kB
Cached 16569276 kB
SwapCached 3072 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-03-12 16:38:41
DTS_END                         2023-03-12 16:38:41.5260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44332
TRN_ID                          243980
ATT_ID                          730
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: 9362.35 20235 1123482
customer order: creation 170.68 316 20482
customer order: refuse 14.17 347 1700
order to supplier: creation 45.38 1160 5446
order to supplier: removal 3.70 100226 444
invoice (draft): creation 46.51 2942 5581
invoice (draft): removal 55.16 715 6619
invoice accept: apply 41.48 18118 4977
invoice accept: cancel 49.28 837 5913
customer reserve: creation 3790.54 182 454865
customer reserve: removal 4843.68 60 581241
realization accept: apply 83.19 179 9983
realization accept: cancel 89.02 109 10682
payment from customer: creation 59.70 108 7164
payment from customer: removal 5.67 90 680
payment to supplier: creation 49.26 120 5911
payment to supplier: removal 2.99 102 359
service: total inventory turnovers 4.43 1167 532
service: total monetary turnovers 6.78 23 813
service: aggregate perf. data 0.72 28272 86
service: refresh index statistics 0.03 249618 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-03-2023 16:40:18 to 12-03-2023 16:40:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3867
TEST_TIME 2 2982
TEST_TIME 3 4402
TEST_TIME 4 4855
TEST_TIME 5 5579
TEST_TIME 6 5668
TEST_TIME 7 5961
TEST_TIME 8 6109
TEST_TIME 9 5888
TEST_TIME 10 5881
TEST_TIME 11 5706
TEST_TIME 12 5792
TEST_TIME 13 5958
TEST_TIME 14 6012
TEST_TIME 15 6073
TEST_TIME 16 6690
TEST_TIME 17 6812
TEST_TIME 18 7117
TEST_TIME 19 7470
TEST_TIME 20 7380
TEST_TIME 21 7419
TEST_TIME 22 7445
TEST_TIME 23 7572
TEST_TIME 24 7638
TEST_TIME 25 8316
TEST_TIME 26 8198
TEST_TIME 27 8627
TEST_TIME 28 8498
TEST_TIME 29 8723
TEST_TIME 30 8695
TEST_TIME 31 9289
TEST_TIME 32 10046
TEST_TIME 33 9982
TEST_TIME 34 9953
TEST_TIME 35 10111
TEST_TIME 36 10004
TEST_TIME 37 9888
TEST_TIME 38 9932
TEST_TIME 39 9945
TEST_TIME 40 10013
TEST_TIME 41 9845
TEST_TIME 42 9701
TEST_TIME 43 9825
TEST_TIME 44 9785
TEST_TIME 45 9664
TEST_TIME 46 9626
TEST_TIME 47 9648
TEST_TIME 48 9677
TEST_TIME 49 9563
TEST_TIME 50 9572
TEST_TIME 51 9497
TEST_TIME 52 9483
TEST_TIME 53 9411
TEST_TIME 54 9337
TEST_TIME 55 9458
TEST_TIME 56 9586
TEST_TIME 57 9628
TEST_TIME 58 9572
TEST_TIME 59 9593
TEST_TIME 60 9599
TEST_TIME 61 9566
TEST_TIME 62 9697
TEST_TIME 63 9680
TEST_TIME 64 9701
TEST_TIME 65 9671
TEST_TIME 66 9705
TEST_TIME 67 9740
TEST_TIME 68 9827
TEST_TIME 69 9759
TEST_TIME 70 9789
TEST_TIME 71 9760
TEST_TIME 72 9664
TEST_TIME 73 9716
TEST_TIME 74 9629
TEST_TIME 75 9609
TEST_TIME 76 9570
TEST_TIME 77 9502
TEST_TIME 78 9492
TEST_TIME 79 9458
TEST_TIME 80 9391
TEST_TIME 81 9449
TEST_TIME 82 9490
TEST_TIME 83 9437
TEST_TIME 84 9485
TEST_TIME 85 9509
TEST_TIME 86 9507
TEST_TIME 87 9488
TEST_TIME 88 9530
TEST_TIME 89 9503
TEST_TIME 90 9567
TEST_TIME 91 9554
TEST_TIME 92 9586
TEST_TIME 93 9589
TEST_TIME 94 9618
TEST_TIME 95 9539
TEST_TIME 96 9511
TEST_TIME 97 9523
TEST_TIME 98 9467
TEST_TIME 99 9466
TEST_TIME 100 9444
TEST_TIME 101 9405
TEST_TIME 102 9383
TEST_TIME 103 9333
TEST_TIME 104 9295
TEST_TIME 105 9283
TEST_TIME 106 9272
TEST_TIME 107 9304
TEST_TIME 108 9327
TEST_TIME 109 9348
TEST_TIME 110 9413
TEST_TIME 111 9427
TEST_TIME 112 9445
TEST_TIME 113 9459
TEST_TIME 114 9465
TEST_TIME 115 9502
TEST_TIME 116 9483
TEST_TIME 117 9499
TEST_TIME 118 9477
TEST_TIME 119 9416
TEST_TIME 120 9370

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-03-2023 16:40:21 to 12-03-2023 16:40:25.

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 1545302 1545302 0 0.00 0 45144 6 0 0
doc_list_biud 1545293 1545293 0 0.00 0 28914 28 0 0
sp_add_invoice_to_stock 5136 4977 159 3.09 22 159199 18118 0 159
sp_cancel_adding_invoice 5913 5913 0 0.00 5 73505 837 0 0
sp_cancel_client_order 1700 1700 0 0.00 4 12588 347 0 0
sp_cancel_customer_reserve 581241 581241 0 0.00 0 28916 61 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 2 6325 90 0 0
sp_cancel_pay_to_supplier 359 359 0 0.00 3 5367 103 0 0
sp_cancel_supplier_invoice 6635 6619 16 0.24 2 13299 715 0 16
sp_cancel_supplier_order 444 444 0 0.00 4 847113 100226 0 0
sp_cancel_write_off 10682 10682 0 0.00 1 16645 109 0 0
sp_client_order 20482 20482 0 0.00 3 23710 316 0 0
sp_customer_reserve 454865 454865 0 0.00 0 43521 182 0 0
sp_fill_shopping_cart 31507 31507 0 0.00 1 10717 33 0 0
sp_fill_shopping_cart_clo_res 454864 454864 0 0.00 0 15209 13 0 0
sp_get_clo_for_invoice 4969 4969 0 0.00 0 28395 285 0 0
sp_kill_cost_storno 17634 17634 0 0.00 0 15082 54 0 0
sp_kill_qstorno_ret_qs2qd 1180227 1180227 0 0.00 0 28782 32 0 0
sp_kill_qty_storno 621546 621546 0 0.00 0 28914 70 0 0
sp_lock_selected_doc 20656 20656 0 0.00 0 10502 5 0 0
sp_make_cost_storno 28027 28027 0 0.00 0 45125 50 0 0
sp_multiply_rows_for_pdistr 28025 28025 0 0.00 0 17402 28 0 0
sp_multiply_rows_for_qdistr 20482 20482 0 0.00 0 23666 136 0 0
sp_pay_from_customer 7164 7164 0 0.00 4 17411 108 0 0
sp_pay_to_supplier 5911 5911 0 0.00 4 12869 120 0 0
sp_payment_common 13071 13071 0 0.00 1 14892 28 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 0 12583 316 0 0
sp_qd_handle_on_invoice_upd_sts 10880 10880 0 0.00 0 19843 333 0 0
sp_qd_handle_on_reserve_upd_sts 9980 9980 0 0.00 0 15451 73 0 0
sp_reserve_write_off 9983 9983 0 0.00 6 15534 179 0 0
sp_supplier_invoice 5581 5581 0 0.00 7 78301 2942 0 0
sp_supplier_order 5446 5446 0 0.00 5 41134 1161 0 0
srv_aggregate_perf_data 86 86 0 0.00 3045 656577 28273 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1524 150147 45234 0 0
srv_find_qd_qs_mism 1076366 1076366 0 0.00 0 13399 5 0 0
srv_make_invnt_saldo 532 532 0 0.00 43 68844 1167 0 0
srv_make_money_saldo 813 813 0 0.00 1 870 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 115684 407649 249619 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4977 4977 0 0.00 0 8689 159 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 2605 100 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 2283 11 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 3762 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 808 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 359 359 0 0.00 0 1120 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 784 10 0 0
t$perf-norm:sp_cancel_supplier_order 444 444 0 0.00 0 19737 1519 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 1017 5 0 0
t$perf-norm:sp_client_order 20482 20482 0 0.00 0 16812 16 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 4982 8 0 0
t$perf-norm:sp_pay_from_customer 7164 7164 0 0.00 0 7351 9 0 0
t$perf-norm:sp_pay_to_supplier 5911 5911 0 0.00 0 6351 11 0 0
t$perf-norm:sp_reserve_write_off 9983 9983 0 0.00 0 8530 10 0 0
t$perf-norm:sp_supplier_invoice 5581 5581 0 0.00 0 6728 20 0 0
t$perf-norm:sp_supplier_order 5446 5446 0 0.00 0 4809 17 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 594 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 780 14 0 0
t$perf-norm:srv_make_invnt_saldo 532 532 0 0.00 0 1886 24 0 0
t$perf-norm:srv_make_money_saldo 813 813 0 0.00 0 923 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10248 9930 318 3.10 0 6086 17 0 318
v_all_customers 37630 37630 0 0.00 0 24 0 0 0
v_all_suppliers 30810 30810 0 0.00 0 27 1 0 0
v_all_wares 40964 40964 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 2610 13 0 0
v_cancel_client_order 3400 3400 0 0.00 0 4424 8 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 2028 8 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 2827 7 0 0
v_cancel_payment_to_supplier 718 718 0 0.00 0 2500 13 0 0
v_cancel_supplier_invoice 880 848 32 3.63 0 3365 20 0 32
v_cancel_supplier_order 888 888 0 0.00 0 2540 10 0 0
v_cancel_write_off 1964 1964 0 0.00 0 2784 14 0 0
v_max_id_clo_ord 5446 5446 0 0.00 0 556 1 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 6418 21 0 0
v_max_id_ord_sup 5579 5579 0 0.00 0 6579 5 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 1310 3 0 0
v_max_non_paid_realizn 3689 3689 0 0.00 0 2079 4 0 0
v_min_id_clo_ord 5446 5446 0 0.00 0 5599 6 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 2652 5 0 0
v_min_id_ord_sup 5579 5579 0 0.00 0 7491 10 0 0
v_min_non_paid_invoice 5911 5911 0 0.00 0 8608 16 0 0
v_min_non_paid_realizn 7160 7160 0 0.00 0 14885 24 0 0
v_our_firm 10276 10276 0 0.00 0 13 1 0 0
v_reserve_write_off 19960 19960 0 0.00 0 4512 6 0 0
x_lock_depdocs_on_canc_invoice 12108 12108 0 0.00 0 22872 125 0 0
x_lock_depdocs_on_canc_sup_ord 444 444 0 0.00 0 11933 350 0 0
x_make_qstorno_1000_1200 5446 5446 0 0.00 1 41098 1089 0 0
x_make_qstorno_1200_2000 5581 5581 0 0.00 3 73148 2825 0 0
x_make_qstorno_2100_3300 454865 454865 0 0.00 0 43520 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-03-2023 16:40:26 to 12-03-2023 16:40:30.

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
14:08:45 16 8192000000 8811255200 8826630144 15632336 0.190 4 4 5 0 15859072 87456 139280
14:09:46 31125 8192000000 9905248288 10215743488 976895984 10.654 100 84 168 0 1005467296 5910752 22660560
14:11:21 24467 8192000000 9939263936 10260832256 1022862880 11.100 100 94 188 0 1055834144 8323600 24647664
14:12:50 19059 8192000000 9981734864 10313510912 1061699728 11.473 100 85 170 0 1092694912 9414816 21580368
14:14:15 7774 8192000000 10025516688 10369581056 1110958112 11.941 100 77 154 0 1140384368 11153328 18272928
14:15:26 3791 8192000000 10066782256 10424905728 1159326752 12.397 100 79 158 0 1190577424 12921264 18329408
14:16:31 3456 8192000000 10115543664 10481999872 1199509808 12.772 100 72 144 1 1228088064 12380848 16197408
14:17:39 1656 8192000000 10158631312 10531237888 1244699888 13.189 100 85 167 1 1276748048 13376864 18671296
14:18:43 1665 8192000000 10181693840 10561622016 1269039696 13.413 100 91 177 1 1301986112 12967424 19978992
14:19:45 809 8192000000 10208901296 10596884480 1295447152 13.654 100 82 166 2 1326531408 13035952 18048304
14:20:47 26386 8192000000 10188830144 10598768640 1296923488 13.667 100 22 44 0 1311757600 10291808 4542304
14:22:14 3328 8192000000 10195075696 10606030848 1307573120 13.764 100 36 73 1 1323920352 7688176 8659056
14:23:19 6892 8192000000 10212241888 10625351680 1315460032 13.836 100 29 59 0 1332991488 11356048 6175408
14:24:27 11312 8192000000 10209865648 10631524352 1324946000 13.921 100 17 34 0 1339448480 10501248 4001232
14:25:39 52722 8192000000 10231287408 10641567744 1328613536 13.955 100 51 102 0 1346476656 5693072 12170048
14:27:32 3705 8192000000 10224724096 10636517376 1325668976 13.928 100 44 87 0 1343462080 8376240 9416864
14:28:39 6382 8192000000 10193780448 10607726592 1283963360 13.549 98 23 46 1 1294675088 5425552 5286176
14:29:45 43750 8192000000 10161996144 10570887168 1209440528 12.864 93 38 75 0 1224554160 6609056 8504576
14:31:32 6513 8192000000 10144919232 10533609472 1231125856 13.064 99 30 58 0 1243316256 5862400 6328000
14:32:43 8031 8192000000 10138152480 10535804928 1236500688 13.114 99 19 38 0 1246722960 5890368 4331904
14:33:52 12347 8192000000 10160705168 10569482240 1276620000 13.482 100 21 41 0 1286994480 6040224 4334256
14:35:08 14023 8192000000 10175534320 10581078016 1281420064 13.526 100 15 29 0 1289991616 5217648 3353904
14:36:24 7675 8192000000 10184699248 10595786752 1293493792 13.636 100 23 45 1 1304329568 5414080 5421696
14:37:35 12776 8192000000 10200006528 10606559232 1300368304 13.699 100 17 34 0 1309761600 4997392 4395904
14:38:56 59190 8192000000 10237098720 10648649728 1331200416 13.978 100 46 90 0 1347498144 5436960 10860768
14:40:58 17305 8192000000 10255747312 10667966464 1348173744 14.131 100 57 114 0 1372369440 11330160 12865536
14:42:18 23808 8192000000 10258701072 10672574464 1345758784 14.109 100 38 75 0 1364458400 9873824 8825792
14:43:47 3861 8192000000 10266452960 10683924480 1359287296 14.231 100 43 87 0 1379880416 10482096 10111024
14:44:53 13527 8192000000 10282215968 10701836288 1367615424 14.306 100 49 96 0 1394278528 15911072 10752032
14:46:17 25492 8192000000 10298218944 10724274176 1383779760 14.450 100 39 74 1 1410033264 18042016 8211488
14:47:47 22152 8192000000 10297359472 10727419904 1388454032 14.492 100 39 78 0 1409622032 12057776 9110224
14:49:14 6021 8192000000 10315491360 10732191744 1393287680 14.535 100 66 133 1 1424759920 15976032 15496208
14:50:26 7087 8192000000 10319648256 10744143872 1398564016 14.582 100 48 96 1 1432355616 22154384 11637216
14:51:41 6977 8192000000 10332764880 10753941504 1402836624 14.620 100 58 116 0 1440450496 23076896 14536976
14:52:54 4172 8192000000 10333073088 10753507328 1399843440 14.594 100 58 116 1 1442002224 27350736 14808048
14:54:02 4779 8192000000 10315602880 10728402944 1374744192 14.370 99 71 141 1 1429759216 37471360 17543664
14:55:19 6497 8192000000 10320064528 10736263168 1382146832 14.436 100 55 110 1 1427247040 31111440 13988768
14:56:31 4699 8192000000 10330837200 10750357504 1388717136 14.494 100 57 111 1 1435454416 32395168 14342112
14:57:56 2444 8192000000 10302923120 10715615232 1365584368 14.287 100 49 96 1 1408228320 30904688 11739264
14:59:01 1522 8192000000 10308955376 10714746880 1359955248 14.237 100 52 98 3 1408597888 36432384 12210256
15:00:10 4363 8192000000 10308103776 10718531584 1363737200 14.271 100 53 106 1 1416256336 38853424 13665712
15:01:19 4303 8192000000 10329467728 10731413504 1365002624 14.282 100 65 127 1 1429498336 47808688 16687024
15:02:30 6972 8192000000 10328547424 10746998784 1375944608 14.380 100 47 92 0 1436475728 47841536 12689584
15:03:42 19855 8192000000 10314407808 10739929088 1381977968 14.434 100 38 74 1 1430858176 38826896 10053312
15:05:06 6354 8192000000 10304282512 10720104448 1355836944 14.200 100 42 84 0 1412545040 44693792 12014304
15:06:17 18714 8192000000 10306769376 10715996160 1344916768 14.102 99 44 88 2 1394160416 37531200 11712448
15:07:42 16351 8192000000 10292265088 10725801984 1355648272 14.198 100 24 48 0 1400775440 38059792 7067376
15:09:41 134671 8192000000 10295507104 10738761728 1362848608 14.263 100 16 32 0 1394229440 27602864 3777968
15:12:56 67203 8192000000 10282210544 10732167168 1354358384 14.187 98 6 12 0 1371605984 15981968 1265632
15:15:04 58280 8192000000 10304895040 10740977664 1373476768 14.358 99 38 80 1 1391830560 9210224 9143568
15:17:10 29778 8192000000 10308363568 10748416000 1386815680 14.477 100 32 64 0 1410700144 15775600 8108864
15:18:46 90786 8192000000 10306582960 10751426560 1391258128 14.517 100 29 58 0 1410120000 11951280 6910592
15:21:31 105937 8192000000 10302194512 10760007680 1390947952 14.514 100 12 24 0 1404083776 10350016 2785808
15:24:18 26005 8192000000 10295456288 10755616768 1388409616 14.492 99 13 26 0 1398568672 6957680 3201376
15:25:54 50707 8192000000 10300170832 10772291584 1402498672 14.617 100 30 60 0 1421481856 11618096 7365088
15:27:45 72504 8192000000 10297678624 10765111296 1375370752 14.375 98 16 32 0 1393039952 13739296 3929904
15:30:21 31144 8192000000 10333041904 10792407040 1413796688 14.718 100 17 36 0 1443933120 25765328 4371104
15:32:22 82945 8192000000 10299351760 10773565440 1382897184 14.442 98 23 46 0 1411633088 22567888 6168016
15:35:19 64936 8192000000 10295666640 10754904064 1359447728 14.232 97 18 36 0 1373753792 10171952 4134112
15:37:24 27872 8192000000 10305937152 10761551872 1397422624 14.572 100 27 53 1 1411935904 8858272 5655008
15:38:59 23141 8192000000 10311274640 10747252736 1358702064 14.226 97 42 85 1 1379749632 12104320 8943248
15:40:23 62747 8192000000 10253021776 10700230656 1298880416 13.685 94 12 24 0 1310562144 8721600 2960128
15:42:27 80136 8192000000 10278923360 10724540416 1308103392 13.769 95 16 32 0 1322397104 10703424 3590288
15:44:53 94137 8192000000 10257214928 10706190336 1261021424 13.339 92 10 20 0 1275674192 12568320 2084448
15:47:37 17447 8192000000 10259102304 10690764800 1322369168 13.898 98 27 53 0 1334907936 7201216 5337552
15:48:56 150146 8192000000 10244111328 10693713920 1293701520 13.638 96 28 56 0 1313158224 12678864 6777840
15:52:28 28870 8192000000 10220201440 10656325632 1285598688 13.564 96 26 49 1 1299609808 8632192 5378928
15:53:57 64412 8192000000 10230253456 10682859520 1273281744 13.452 95 9 18 0 1290954832 15544304 2128784
15:56:11 60431 8192000000 10246738000 10697424896 1339415008 14.052 100 22 44 1 1364026928 19127280 5484640
15:58:47 51343 8192000000 10263740576 10714206208 1357959600 14.219 100 14 28 0 1381506896 19704496 3842800
16:00:42 74293 8192000000 10282826512 10731839488 1349510736 14.143 99 13 24 0 1359222336 6867568 2844032
16:03:18 29271 8192000000 10255940192 10698022912 1306183840 13.751 96 32 65 0 1328340880 14307104 7849936
16:04:54 50370 8192000000 10281842976 10730168320 1368592416 14.314 100 15 30 0 1384357856 12590624 3174816
16:06:49 77107 8192000000 10289740192 10755121152 1367958256 14.309 99 13 26 0 1390469808 19579664 2931888
16:09:09 43799 8192000000 10316186096 10763747328 1387836832 14.487 99 24 48 0 1403760768 10585488 5338448
16:10:58 66497 8192000000 10302516016 10752516096 1362273328 14.258 97 11 20 1 1372144976 7964096 1907552
16:13:04 90374 8192000000 10312604128 10765213696 1397865856 14.576 99 29 56 1 1414619088 9930704 6822528
16:15:37 62641 8192000000 10322199760 10783760384 1397388928 14.572 99 26 52 0 1417012672 13315888 6307856
16:17:40 29528 8192000000 10304554816 10770182144 1396850976 14.567 99 15 29 0 1410007968 10125008 3031984
16:19:12 59333 8192000000 10296334048 10762313728 1378363696 14.402 98 14 26 0 1389758480 8109072 3285712
16:21:13 42741 8192000000 10273150176 10739326976 1367280864 14.303 98 6 12 0 1377579856 9030224 1268768
16:23:11 58830 8192000000 10307301936 10777710592 1399674624 14.592 100 12 24 0 1415778000 12822336 3281040
16:25:34 54385 8192000000 10287946176 10757345280 1368569200 14.314 98 20 40 0 1384383552 11430080 4384272
16:27:34 67706 8192000000 10309333776 10778746880 1360677088 14.243 97 25 50 0 1378635840 12537392 5421360
16:30:10 84338 8192000000 10278181104 10730811392 1308166544 13.769 94 23 46 0 1327695952 14464656 5064752
16:32:41 95924 8192000000 10278052016 10715598848 1290922560 13.613 94 25 49 1 1307366496 10855824 5588112
16:35:17 48634 8192000000 10258551568 10704306176 1281348400 13.525 94 25 49 0 1298813568 12256640 5208528
16:37:09 97101 8192000000 9656431024 10013356032 794475232 8.840 58 16 32 0 806898688 8862240 3561216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-03-2023 16:40:32 to 12-03-2023 16:40:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-03-2023 16:40:48 to 12-03-2023 16:40:50.

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          242992
MON$OLDEST_ACTIVE               244181
MON$OLDEST_SNAPSHOT             244181
MON$NEXT_TRANSACTION            244181
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                       2053792
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.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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 ---
:58: 
:59: mscs-test-161.budzdorov.ru    Sun Mar 12 16:38:51 2023
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:61: 
:62: 
:63: mscs-test-161.budzdorov.ru    Sun Mar 12 16:38:53 2023
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Sun Mar 12 16:38:56 2023
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:69: 
:70: 
:71: mscs-test-161.budzdorov.ru    Sun Mar 12 16:38:56 2023
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:73: 
--- end of diff output ---

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

12.03.23 16:40:51. 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-03-12 16:40:54 No severe PSQL-related problems occured
12.03.23 16:40:55. End of report.