Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.02.2023 22: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 15293504 kB
MemAvailable 30405480 kB
Buffers 0 kB
Cached 16402552 kB
SwapCached 3620 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-02-02 22:38:39
DTS_END                         2023-02-02 22:38:39.2060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56756
TRN_ID                          238720
ATT_ID                          676
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: 9135.42 18349 1096251
customer order: creation 175.74 372 21089
customer order: refuse 14.87 380 1784
order to supplier: creation 46.09 1290 5531
order to supplier: removal 3.60 94073 432
invoice (draft): creation 46.03 3691 5523
invoice (draft): removal 51.03 815 6123
invoice accept: apply 42.17 18352 5060
invoice accept: cancel 46.31 865 5557
customer reserve: creation 3872.60 181 464712
customer reserve: removal 4543.91 57 545269
realization accept: apply 85.73 192 10287
realization accept: cancel 76.49 119 9179
payment from customer: creation 59.43 108 7131
payment from customer: removal 5.53 77 664
payment to supplier: creation 50.24 154 6029
payment to supplier: removal 3.32 66 398
service: total inventory turnovers 4.89 1346 587
service: total monetary turnovers 6.78 37 814
service: aggregate perf. data 0.65 33157 78
service: refresh index statistics 0.03 211644 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-02-2023 22:39:43 to 02-02-2023 22:39:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5692
TEST_TIME 2 5180
TEST_TIME 3 5528
TEST_TIME 4 4992
TEST_TIME 5 4895
TEST_TIME 6 5226
TEST_TIME 7 5112
TEST_TIME 8 5192
TEST_TIME 9 5917
TEST_TIME 10 6853
TEST_TIME 11 6785
TEST_TIME 12 7277
TEST_TIME 13 7343
TEST_TIME 14 7374
TEST_TIME 15 7376
TEST_TIME 16 7702
TEST_TIME 17 7693
TEST_TIME 18 8000
TEST_TIME 19 8096
TEST_TIME 20 8384
TEST_TIME 21 8835
TEST_TIME 22 8862
TEST_TIME 23 8782
TEST_TIME 24 9006
TEST_TIME 25 9000
TEST_TIME 26 9218
TEST_TIME 27 9525
TEST_TIME 28 9399
TEST_TIME 29 9477
TEST_TIME 30 9793
TEST_TIME 31 11008
TEST_TIME 32 10857
TEST_TIME 33 10798
TEST_TIME 34 10694
TEST_TIME 35 10421
TEST_TIME 36 10253
TEST_TIME 37 10058
TEST_TIME 38 9946
TEST_TIME 39 9763
TEST_TIME 40 9664
TEST_TIME 41 9899
TEST_TIME 42 9713
TEST_TIME 43 9575
TEST_TIME 44 9570
TEST_TIME 45 9546
TEST_TIME 46 9605
TEST_TIME 47 9479
TEST_TIME 48 9352
TEST_TIME 49 9274
TEST_TIME 50 9230
TEST_TIME 51 9245
TEST_TIME 52 9196
TEST_TIME 53 9100
TEST_TIME 54 9081
TEST_TIME 55 9023
TEST_TIME 56 9026
TEST_TIME 57 8916
TEST_TIME 58 8880
TEST_TIME 59 8924
TEST_TIME 60 9067
TEST_TIME 61 9019
TEST_TIME 62 9068
TEST_TIME 63 9086
TEST_TIME 64 9147
TEST_TIME 65 9112
TEST_TIME 66 9103
TEST_TIME 67 9123
TEST_TIME 68 9217
TEST_TIME 69 9158
TEST_TIME 70 9268
TEST_TIME 71 9180
TEST_TIME 72 9185
TEST_TIME 73 9281
TEST_TIME 74 9357
TEST_TIME 75 9404
TEST_TIME 76 9301
TEST_TIME 77 9219
TEST_TIME 78 9208
TEST_TIME 79 9215
TEST_TIME 80 9155
TEST_TIME 81 9151
TEST_TIME 82 9159
TEST_TIME 83 9133
TEST_TIME 84 9069
TEST_TIME 85 9038
TEST_TIME 86 8999
TEST_TIME 87 9003
TEST_TIME 88 9004
TEST_TIME 89 8978
TEST_TIME 90 9031
TEST_TIME 91 9033
TEST_TIME 92 9046
TEST_TIME 93 9073
TEST_TIME 94 9157
TEST_TIME 95 9145
TEST_TIME 96 9185
TEST_TIME 97 9200
TEST_TIME 98 9252
TEST_TIME 99 9226
TEST_TIME 100 9245
TEST_TIME 101 9254
TEST_TIME 102 9301
TEST_TIME 103 9283
TEST_TIME 104 9329
TEST_TIME 105 9250
TEST_TIME 106 9249
TEST_TIME 107 9234
TEST_TIME 108 9197
TEST_TIME 109 9241
TEST_TIME 110 9236
TEST_TIME 111 9215
TEST_TIME 112 9184
TEST_TIME 113 9146
TEST_TIME 114 9102
TEST_TIME 115 9098
TEST_TIME 116 9107
TEST_TIME 117 9108
TEST_TIME 118 9099
TEST_TIME 119 9100
TEST_TIME 120 9150

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-02-2023 22:39:47 to 02-02-2023 22:39:51.

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 1526194 1526194 0 0.00 0 23665 6 0 0
doc_list_biud 1526194 1526194 0 0.00 0 40204 27 0 0
sp_add_invoice_to_stock 5178 5060 118 2.27 31 153562 18352 0 118
sp_cancel_adding_invoice 5557 5557 0 0.00 4 72279 865 0 0
sp_cancel_client_order 1784 1784 0 0.00 4 9373 381 0 0
sp_cancel_customer_reserve 545269 545269 0 0.00 1 24481 58 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 2 3997 78 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 1858 67 0 0
sp_cancel_supplier_invoice 6132 6123 9 0.14 1 40221 815 0 9
sp_cancel_supplier_order 432 432 0 0.00 17 1004059 94074 0 0
sp_cancel_write_off 9179 9179 0 0.00 1 7024 119 0 0
sp_client_order 21089 21089 0 0.00 3 24142 373 0 0
sp_customer_reserve 464712 464712 0 0.00 0 47407 182 0 0
sp_fill_shopping_cart 32142 32142 0 0.00 1 15009 45 0 0
sp_fill_shopping_cart_clo_res 464700 464700 0 0.00 0 16420 14 0 0
sp_get_clo_for_invoice 5043 5043 0 0.00 0 22129 360 0 0
sp_kill_cost_storno 15798 15798 0 0.00 0 7950 64 0 0
sp_kill_qstorno_ret_qs2qd 1106272 1106272 0 0.00 0 40196 31 0 0
sp_kill_qty_storno 583674 583674 0 0.00 0 40200 69 0 0
sp_lock_selected_doc 21111 21111 0 0.00 0 8545 6 0 0
sp_make_cost_storno 28490 28490 0 0.00 0 23656 64 0 0
sp_multiply_rows_for_pdistr 28490 28490 0 0.00 0 8172 31 0 0
sp_multiply_rows_for_qdistr 21089 21089 0 0.00 0 24132 165 0 0
sp_pay_from_customer 7131 7131 0 0.00 3 16077 109 0 0
sp_pay_to_supplier 6029 6029 0 0.00 5 17301 154 0 0
sp_payment_common 13160 13160 0 0.00 1 11426 27 0 0
sp_qd_handle_on_cancel_clo 1784 1784 0 0.00 0 8048 352 0 0
sp_qd_handle_on_invoice_upd_sts 10600 10600 0 0.00 0 24849 356 0 0
sp_qd_handle_on_reserve_upd_sts 10287 10287 0 0.00 0 16602 64 0 0
sp_reserve_write_off 10287 10287 0 0.00 6 19002 193 0 0
sp_supplier_invoice 5523 5523 0 0.00 7 88589 3691 0 0
sp_supplier_order 5531 5531 0 0.00 4 25581 1291 0 0
srv_aggregate_perf_data 78 78 0 0.00 2697 776994 33157 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 824 122589 42232 0 0
srv_find_qd_qs_mism 1047824 1047824 0 0.00 0 18403 5 0 0
srv_make_invnt_saldo 587 587 0 0.00 35 81318 1346 0 0
srv_make_money_saldo 814 814 0 0.00 1 6587 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 57542 409625 211644 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5060 5060 0 0.00 0 17899 174 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 2654 87 0 0
t$perf-norm:sp_cancel_client_order 1784 1784 0 0.00 0 10607 19 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 1658 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 1724 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 2932 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 657 19 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 20061 1658 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 8946 21 0 0
t$perf-norm:sp_client_order 21089 21089 0 0.00 0 13310 19 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 6236 12 0 0
t$perf-norm:sp_pay_from_customer 7131 7131 0 0.00 0 7959 10 0 0
t$perf-norm:sp_pay_to_supplier 6029 6029 0 0.00 0 10435 13 0 0
t$perf-norm:sp_reserve_write_off 10287 10287 0 0.00 0 5239 9 0 0
t$perf-norm:sp_supplier_invoice 5523 5523 0 0.00 0 11814 23 0 0
t$perf-norm:sp_supplier_order 5531 5531 0 0.00 0 3015 17 0 0
t$perf-norm:srv_aggregate_perf_data 78 78 0 0.00 0 127 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 33 1 0 0
t$perf-norm:srv_make_invnt_saldo 587 587 0 0.00 0 1734 22 0 0
t$perf-norm:srv_make_money_saldo 814 814 0 0.00 0 2670 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 16 9 0 0
v_add_invoice_to_stock 10322 10086 236 2.28 0 6059 17 0 236
v_all_customers 38452 38452 0 0.00 0 91 1 0 0
v_all_suppliers 30816 30816 0 0.00 0 24 1 0 0
v_all_wares 42178 42178 0 0.00 0 234 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 980 8 0 0
v_cancel_client_order 3568 3568 0 0.00 0 2333 5 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 677 4 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 1177 6 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 1850 9 0 0
v_cancel_supplier_invoice 822 804 18 2.18 0 1621 16 0 18
v_cancel_supplier_order 864 864 0 0.00 0 541 4 0 0
v_cancel_write_off 1996 1996 0 0.00 0 5413 19 0 0
v_max_id_clo_ord 5530 5530 0 0.00 0 1410 2 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 2987 18 0 0
v_max_id_ord_sup 5523 5523 0 0.00 0 4520 7 0 0
v_max_non_paid_invoice 1674 1674 0 0.00 0 2185 4 0 0
v_max_non_paid_realizn 3740 3740 0 0.00 0 1483 3 0 0
v_min_id_clo_ord 5530 5530 0 0.00 0 4643 7 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 3088 15 0 0
v_min_id_ord_sup 5523 5523 0 0.00 0 6224 7 0 0
v_min_non_paid_invoice 6029 6029 0 0.00 0 11422 23 0 0
v_min_non_paid_realizn 7131 7131 0 0.00 0 10246 17 0 0
v_our_firm 10508 10508 0 0.00 0 20 1 0 0
v_reserve_write_off 20574 20574 0 0.00 0 5166 6 0 0
x_lock_depdocs_on_canc_invoice 11278 11278 0 0.00 0 23659 127 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 7848 359 0 0
x_make_qstorno_1000_1200 5531 5531 0 0.00 1 25570 1208 0 0
x_make_qstorno_1200_2000 5523 5523 0 0.00 3 88546 3517 0 0
x_make_qstorno_2100_3300 464712 464712 0 0.00 0 47406 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-02-2023 22:39:52 to 02-02-2023 22:39:56.

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:43 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:44 28348 8192000000 9904148432 10213720064 985499024 10.738 100 88 176 0 1014954864 6023712 23432128
20:11:15 18855 8192000000 9944919232 10266705920 1027514496 11.144 100 84 168 0 1056614752 7738304 21361952
20:12:40 16041 8192000000 9983587776 10317246464 1071083744 11.562 100 83 166 1 1101112816 9675408 20353664
20:14:02 12470 8192000000 10038380192 10385469440 1122889344 12.054 100 84 166 0 1153730768 11445664 19395760
20:15:19 6634 8192000000 10082838896 10440962048 1171741728 12.513 100 88 176 0 1204621216 12602736 20276752
20:16:29 5221 8192000000 10122452720 10491752448 1213003424 12.897 100 89 176 0 1245216176 11856880 20355872
20:17:37 4860 8192000000 10147448720 10523455488 1238368560 13.131 100 68 136 1 1266628256 13173648 15086048
20:18:43 2944 8192000000 10176755616 10559594496 1268323504 13.406 100 70 138 0 1295162752 11946384 14892864
20:19:48 925 8192000000 10208067664 10595147776 1293829840 13.639 100 86 172 0 1326618464 13738848 19049776
20:20:50 2652 8192000000 10203084768 10605776896 1304124000 13.733 100 49 97 1 1324902816 10147536 10631280
20:21:54 5608 8192000000 10197979360 10613112832 1312245024 13.806 100 36 72 0 1328590416 8050608 8294784
20:23:01 59685 8192000000 10206904112 10629681152 1319796256 13.875 100 46 91 1 1337036320 5976656 11263408
20:25:01 6397 8192000000 10221765744 10643947520 1333271104 13.997 100 38 76 0 1347824048 5576112 8976832
20:26:12 9310 8192000000 10233990736 10653110272 1337790000 14.037 100 30 60 0 1351588864 6672304 7126560
20:27:23 49778 8192000000 10238209536 10654830592 1341809904 14.074 100 32 62 2 1355215296 5573568 7831824
20:29:14 4799 8192000000 10201477552 10610749440 1285172336 13.560 97 38 76 0 1300954464 7560176 8221952
20:30:23 10025 8192000000 10185907328 10594295808 1269408896 13.416 97 19 38 0 1279343488 5972240 3962352
20:31:33 21943 8192000000 10124992544 10526269440 1097627760 11.815 86 8 16 0 1103847216 4560832 1658624
20:32:56 31226 8192000000 10113332272 10510422016 1172467216 12.520 95 31 62 0 1188645392 9716160 6462016
20:34:29 6805 8192000000 10160158832 10558464000 1257581632 13.308 99 22 44 0 1272241536 9129840 5530064
20:35:41 5981 8192000000 10167403344 10572181504 1277434224 13.490 100 34 66 0 1290578016 5778640 7365152
20:36:50 59531 8192000000 10188867888 10600382464 1295499184 13.654 100 38 74 0 1309725312 4807792 9418336
20:38:55 3222 8192000000 10231507408 10637500416 1325063632 13.923 100 54 108 0 1345259120 6684256 13511232
20:40:04 3105 8192000000 10263630896 10664136704 1340638944 14.063 100 61 121 1 1364404064 10125920 13639200
20:41:11 7687 8192000000 10252906464 10670710784 1351021376 14.157 100 42 83 0 1369370272 8819296 9529600
20:43:01 64336 8192000000 10268230016 10697539584 1368515264 14.314 100 43 86 1 1392264080 13087328 10661488
20:45:10 12900 8192000000 10283116016 10709426176 1376553248 14.386 100 34 68 1 1396427984 11699472 8175264
20:46:26 9027 8192000000 10315538400 10734739456 1397713520 14.575 100 54 108 0 1428594288 18028080 12852688
20:47:38 6341 8192000000 10326377232 10750197760 1401183760 14.606 100 59 115 1 1436246240 21615664 13446816
20:48:49 9083 8192000000 10334931280 10761158656 1411729984 14.699 100 51 102 1 1443862064 19697616 12434464
20:50:05 4495 8192000000 10327208416 10748936192 1398852160 14.585 100 64 127 2 1436659680 22823696 14983824
20:51:15 4702 8192000000 10337185920 10758688768 1406889824 14.656 100 66 132 0 1445626480 23204304 15532352
20:52:27 2142 8192000000 10355705568 10773524480 1417403328 14.750 100 81 161 0 1463872112 26864256 19604528
20:53:37 2072 8192000000 10336327344 10752819200 1400264352 14.597 100 71 143 0 1443900096 25764480 17871264
20:54:45 3520 8192000000 10323169504 10730582016 1372700096 14.351 99 76 151 0 1423951904 30709328 20542480
20:56:01 823 8192000000 10356489760 10765430784 1399742160 14.593 100 82 160 1 1454990480 35062576 20185744
20:57:09 1758 8192000000 10332241616 10745536512 1375571904 14.377 99 74 148 1 1428360480 34035088 18753488
20:58:19 10276 8192000000 10295957472 10722836480 1361946144 14.255 99 41 80 1 1412331136 39770240 10614752
20:59:38 3163 8192000000 10323125952 10732146688 1365057312 14.283 100 71 137 2 1427480768 45511392 16912064
21:00:48 6197 8192000000 10307334160 10720899072 1345397680 14.106 99 57 112 0 1402035968 42872688 13765600
21:02:00 2310 8192000000 10340317456 10747949056 1375035536 14.372 100 70 138 1 1441729760 49700416 16993808
21:03:13 5430 8192000000 10323876000 10737283072 1359931776 14.237 99 51 98 0 1423711072 50523904 13255392
21:04:26 17611 8192000000 10327748416 10758586368 1375443760 14.376 100 30 60 0 1432712624 48564768 8704096
21:05:46 18257 8192000000 10308780208 10745626624 1350803248 14.155 98 18 36 0 1397684592 41823872 5057472
21:07:07 23792 8192000000 10314769600 10752094208 1357821952 14.218 99 22 44 1 1411665568 47287616 6556000
21:08:56 33463 8192000000 10288870704 10721742848 1307404096 13.763 97 21 42 0 1366668448 53078704 6185648
21:10:41 41848 8192000000 10268742704 10713387008 1363324320 14.267 100 36 70 0 1379114576 8333200 7457056
21:12:25 68985 8192000000 10267680672 10717597696 1364827152 14.281 100 11 22 0 1375069392 7726016 2516224
21:14:43 24669 8192000000 10283013536 10725142528 1358522480 14.224 99 31 62 0 1381452816 15524496 7405840
21:16:09 42952 8192000000 10287458864 10738462720 1376223280 14.383 100 17 34 0 1394948640 14955888 3769472
21:17:59 65615 8192000000 10301728672 10753249280 1384932608 14.461 100 26 53 0 1408873184 17456192 6484384
21:20:10 56965 8192000000 10306519504 10748469248 1384616304 14.458 99 31 61 0 1402924480 11516720 6791456
21:22:07 115977 8192000000 10284051248 10733600768 1358444224 14.223 98 15 30 0 1381113408 19091328 3577856
21:25:05 92601 8192000000 10290861248 10735304704 1376602224 14.386 100 17 34 0 1392287216 11213056 4471936
21:27:38 46864 8192000000 10309009936 10760413184 1391727776 14.521 100 27 51 1 1409305552 11815872 5761904
21:29:35 71504 8192000000 10298668624 10765582336 1358854288 14.227 97 13 26 0 1382083776 20507568 2721920
21:32:15 49040 8192000000 10299083872 10761211904 1396113280 14.560 100 16 32 0 1413473040 14107632 3252128
21:34:05 53989 8192000000 10299213296 10766077952 1373803888 14.361 98 12 24 1 1394403328 17498640 3100800
21:36:27 46116 8192000000 10300488896 10770968576 1402505280 14.617 100 16 32 0 1421916816 15413952 3997584
21:38:17 98165 8192000000 10302913648 10778480640 1368339520 14.312 97 14 28 0 1390207760 18586800 3281440
21:40:55 57917 8192000000 10289723968 10747113472 1351997120 14.165 96 8 16 0 1364784976 11347664 1440192
21:42:54 41768 8192000000 10303818928 10758881280 1387906928 14.487 99 23 46 1 1401650608 8737424 5006256
21:44:38 40096 8192000000 10314832192 10774704128 1376834032 14.388 98 24 48 0 1393414368 10943840 5636496
21:46:31 80251 8192000000 10283869664 10734587904 1282425888 13.535 92 12 24 0 1303707552 18487344 2794320
21:48:59 47661 8192000000 10298401264 10735828992 1330119872 13.968 96 36 72 0 1352725776 14805744 7800160
21:50:52 57376 8192000000 10258139696 10707705856 1301028464 13.705 95 16 32 0 1319067552 14505568 3533520
21:53:00 122588 8192000000 10259872160 10719158272 1345162784 14.104 99 11 22 0 1369470000 22141776 2165440
21:56:12 79828 8192000000 10260299152 10691919872 1282126240 13.532 94 29 58 0 1296703408 8478272 6098896
21:58:37 51411 8192000000 10246104240 10698489856 1337899264 14.038 99 29 59 0 1354545808 9876400 6770144
22:00:32 32973 8192000000 10239782208 10682806272 1322881728 13.903 99 10 20 1 1332756448 7776544 2098176
22:02:12 75492 8192000000 10237371728 10692018176 1346468192 14.116 100 14 28 0 1364164240 14070352 3625696
22:04:55 36344 8192000000 10248589040 10693632000 1317759472 13.856 98 35 70 3 1337304416 12343104 7201840
22:06:31 58860 8192000000 10238291104 10685341696 1317062240 13.850 98 21 40 0 1329457856 7384080 5011536
22:08:49 39726 8192000000 10268555344 10713133056 1350262048 14.150 99 29 58 0 1365957968 9116016 6579904
22:10:35 33218 8192000000 10274937728 10723373056 1350725760 14.154 98 17 33 0 1362121568 7318800 4077008
22:12:09 71219 8192000000 10279720432 10741690368 1356200560 14.203 98 18 35 1 1369525696 9378160 3946976
22:14:20 50319 8192000000 10278006640 10720079872 1336807232 14.029 97 20 40 0 1353436176 12304144 4324800
22:16:14 43723 8192000000 10286048496 10744733696 1364595648 14.279 99 15 30 0 1378273184 10530720 3146816
22:18:01 68425 8192000000 10295260320 10747691008 1377106016 14.391 100 26 52 0 1396271616 13662592 5503008
22:20:36 41608 8192000000 10302496768 10748329984 1376099312 14.382 99 33 66 1 1394117008 10644608 7373088
22:22:18 105929 8192000000 10301409904 10750795776 1389052560 14.497 100 32 64 0 1406106288 9549632 7504096
22:25:15 83298 8192000000 10293573728 10763321344 1381523552 14.430 99 19 38 1 1401577248 15922224 4131472
22:27:42 62755 8192000000 10290747344 10770006016 1375930352 14.380 99 4 8 0 1396290416 19484176 875888
22:30:35 38520 8192000000 10270905440 10729750528 1358326176 14.222 98 29 58 1 1371899536 6276192 7297168
22:32:16 65601 8192000000 10232754144 10691166208 1279277840 13.506 93 14 28 1 1296185136 13091424 3815872
22:35:37 31018 8192000000 10270064096 10718994432 1345506512 14.107 99 20 40 0 1358722240 8540560 4675168
22:37:09 96077 8192000000 9545230000 9901903872 657232288 7.426 48 6 12 0 668600144 10057568 1310288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-02-2023 22:39:58 to 02-02-2023 22:40:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

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

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          239028
MON$OLDEST_ACTIVE               239029
MON$OLDEST_SNAPSHOT             239029
MON$NEXT_TRANSACTION            239029
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                       2032672
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.33658 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33658 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33658 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33658 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'

02.02.23 22:40:17. 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-02-02 22:40:20 No severe PSQL-related problems occured
02.02.23 22:40:21. End of report.