Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.01.2023 19: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 15357492 kB
MemAvailable 30365880 kB
Buffers 0 kB
Cached 16331296 kB
SwapCached 5564 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-03 19:38:31
DTS_END                         2023-01-03 19:38:31.6300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46842
TRN_ID                          245377
ATT_ID                          700
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: 9305.42 19276 1116651
customer order: creation 178.25 379 21390
customer order: refuse 15.12 347 1814
order to supplier: creation 46.96 1298 5635
order to supplier: removal 3.33 90377 400
invoice (draft): creation 47.64 3216 5717
invoice (draft): removal 52.89 707 6347
invoice accept: apply 42.77 16887 5132
invoice accept: cancel 47.67 805 5720
customer reserve: creation 3882.53 170 465904
customer reserve: removal 4679.88 50 561585
realization accept: apply 86.42 182 10370
realization accept: cancel 89.99 103 10799
payment from customer: creation 60.43 112 7251
payment from customer: removal 6.13 68 736
payment to supplier: creation 49.14 162 5897
payment to supplier: removal 3.56 80 427
service: total inventory turnovers 4.65 1354 558
service: total monetary turnovers 7.18 23 861
service: aggregate perf. data 0.87 19819 104
service: refresh index statistics 0.03 249371 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-01-2023 19:39:24 to 03-01-2023 19:39:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4760
TEST_TIME 2 3443
TEST_TIME 3 3882
TEST_TIME 4 4034
TEST_TIME 5 4646
TEST_TIME 6 4690
TEST_TIME 7 4654
TEST_TIME 8 5000
TEST_TIME 9 4963
TEST_TIME 10 5397
TEST_TIME 11 5234
TEST_TIME 12 5613
TEST_TIME 13 5893
TEST_TIME 14 6041
TEST_TIME 15 6452
TEST_TIME 16 6589
TEST_TIME 17 6698
TEST_TIME 18 7345
TEST_TIME 19 7324
TEST_TIME 20 7694
TEST_TIME 21 7735
TEST_TIME 22 8106
TEST_TIME 23 8516
TEST_TIME 24 8646
TEST_TIME 25 9270
TEST_TIME 26 9459
TEST_TIME 27 10265
TEST_TIME 28 10196
TEST_TIME 29 9953
TEST_TIME 30 9942
TEST_TIME 31 9891
TEST_TIME 32 9844
TEST_TIME 33 9729
TEST_TIME 34 9928
TEST_TIME 35 9842
TEST_TIME 36 9727
TEST_TIME 37 9599
TEST_TIME 38 9526
TEST_TIME 39 9569
TEST_TIME 40 9585
TEST_TIME 41 9634
TEST_TIME 42 9516
TEST_TIME 43 9474
TEST_TIME 44 9382
TEST_TIME 45 9469
TEST_TIME 46 9454
TEST_TIME 47 9372
TEST_TIME 48 9367
TEST_TIME 49 9311
TEST_TIME 50 9314
TEST_TIME 51 9282
TEST_TIME 52 9277
TEST_TIME 53 9403
TEST_TIME 54 9401
TEST_TIME 55 9481
TEST_TIME 56 9534
TEST_TIME 57 9522
TEST_TIME 58 9505
TEST_TIME 59 9554
TEST_TIME 60 9582
TEST_TIME 61 9629
TEST_TIME 62 9579
TEST_TIME 63 9467
TEST_TIME 64 9430
TEST_TIME 65 9405
TEST_TIME 66 9366
TEST_TIME 67 9329
TEST_TIME 68 9255
TEST_TIME 69 9228
TEST_TIME 70 9240
TEST_TIME 71 9410
TEST_TIME 72 9366
TEST_TIME 73 9367
TEST_TIME 74 9338
TEST_TIME 75 9300
TEST_TIME 76 9325
TEST_TIME 77 9389
TEST_TIME 78 9399
TEST_TIME 79 9414
TEST_TIME 80 9529
TEST_TIME 81 9475
TEST_TIME 82 9378
TEST_TIME 83 9350
TEST_TIME 84 9325
TEST_TIME 85 9328
TEST_TIME 86 9294
TEST_TIME 87 9230
TEST_TIME 88 9230
TEST_TIME 89 9221
TEST_TIME 90 9208
TEST_TIME 91 9196
TEST_TIME 92 9187
TEST_TIME 93 9217
TEST_TIME 94 9213
TEST_TIME 95 9212
TEST_TIME 96 9244
TEST_TIME 97 9298
TEST_TIME 98 9289
TEST_TIME 99 9301
TEST_TIME 100 9276
TEST_TIME 101 9349
TEST_TIME 102 9388
TEST_TIME 103 9409
TEST_TIME 104 9413
TEST_TIME 105 9390
TEST_TIME 106 9340
TEST_TIME 107 9279
TEST_TIME 108 9242
TEST_TIME 109 9202
TEST_TIME 110 9180
TEST_TIME 111 9226
TEST_TIME 112 9207
TEST_TIME 113 9196
TEST_TIME 114 9218
TEST_TIME 115 9198
TEST_TIME 116 9234
TEST_TIME 117 9281
TEST_TIME 118 9256
TEST_TIME 119 9281
TEST_TIME 120 9307

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-01-2023 19:39:28 to 03-01-2023 19:39:31.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1549360 1549360 0 0.00 0 30527 6 0 0
doc_list_biud 1549358 1549358 0 0.00 0 35703 24 0 0
sp_add_invoice_to_stock 5259 5132 127 2.41 37 186731 16887 0 127
sp_cancel_adding_invoice 5720 5720 0 0.00 3 86984 805 0 0
sp_cancel_client_order 1814 1814 0 0.00 4 13723 347 0 0
sp_cancel_customer_reserve 561585 561585 0 0.00 0 35704 51 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 3 4029 69 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 4223 80 0 0
sp_cancel_supplier_invoice 6359 6347 12 0.18 1 16461 707 0 12
sp_cancel_supplier_order 400 400 0 0.00 8 989379 90378 0 0
sp_cancel_write_off 10799 10799 0 0.00 1 23044 104 0 0
sp_client_order 21390 21390 0 0.00 3 29233 379 0 0
sp_customer_reserve 465904 465904 0 0.00 0 119749 170 0 0
sp_fill_shopping_cart 32741 32741 0 0.00 1 16925 35 0 0
sp_fill_shopping_cart_clo_res 465902 465902 0 0.00 0 20971 13 0 0
sp_get_clo_for_invoice 5130 5130 0 0.00 0 26088 304 0 0
sp_kill_cost_storno 17682 17682 0 0.00 0 13977 50 0 0
sp_kill_qstorno_ret_qs2qd 1140716 1140716 0 0.00 0 35683 28 0 0
sp_kill_qty_storno 602165 602165 0 0.00 0 35703 61 0 0
sp_lock_selected_doc 21589 21589 0 0.00 0 7891 7 0 0
sp_make_cost_storno 28648 28648 0 0.00 0 22123 58 0 0
sp_multiply_rows_for_pdistr 28647 28647 0 0.00 0 12487 27 0 0
sp_multiply_rows_for_qdistr 21390 21390 0 0.00 0 15102 170 0 0
sp_pay_from_customer 7251 7251 0 0.00 4 31962 113 0 0
sp_pay_to_supplier 5897 5897 0 0.00 5 34173 163 0 0
sp_payment_common 13147 13147 0 0.00 1 20798 36 0 0
sp_qd_handle_on_cancel_clo 1814 1814 0 0.00 0 13699 311 0 0
sp_qd_handle_on_invoice_upd_sts 10850 10850 0 0.00 0 24342 318 0 0
sp_qd_handle_on_reserve_upd_sts 10370 10370 0 0.00 0 22220 61 0 0
sp_reserve_write_off 10370 10370 0 0.00 6 22260 182 0 0
sp_supplier_invoice 5717 5717 0 0.00 5 84704 3217 0 0
sp_supplier_order 5635 5635 0 0.00 5 45943 1299 0 0
srv_aggregate_perf_data 104 104 0 0.00 2573 433016 19820 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1102 140849 47331 0 0
srv_find_qd_qs_mism 1068608 1068608 0 0.00 0 23679 6 0 0
srv_make_invnt_saldo 558 558 0 0.00 36 111861 1354 0 0
srv_make_money_saldo 861 861 0 0.00 1 768 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 120037 389504 249372 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5132 5132 0 0.00 0 7086 146 0 0
t$perf-norm:sp_cancel_adding_invoice 460 460 0 0.00 0 3961 104 0 0
t$perf-norm:sp_cancel_client_order 1814 1814 0 0.00 0 1858 11 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 2452 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 1389 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 2089 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 465 465 0 0.00 0 11878 48 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 27704 1552 0 0
t$perf-norm:sp_cancel_write_off 1076 1076 0 0.00 0 12088 14 0 0
t$perf-norm:sp_client_order 21390 21390 0 0.00 0 16812 17 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 6987 13 0 0
t$perf-norm:sp_pay_from_customer 7251 7251 0 0.00 0 12107 11 0 0
t$perf-norm:sp_pay_to_supplier 5897 5897 0 0.00 0 11173 12 0 0
t$perf-norm:sp_reserve_write_off 10370 10370 0 0.00 0 5440 10 0 0
t$perf-norm:sp_supplier_invoice 5717 5717 0 0.00 0 4127 16 0 0
t$perf-norm:sp_supplier_order 5635 5635 0 0.00 0 6615 14 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 892 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1028 17 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 1153 22 0 0
t$perf-norm:srv_make_money_saldo 861 861 0 0.00 0 1189 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 13 6 0 0
v_add_invoice_to_stock 10514 10260 254 2.41 0 6646 16 0 254
v_all_customers 38546 38546 0 0.00 0 19 0 0 0
v_all_suppliers 31110 31110 0 0.00 0 20 1 0 0
v_all_wares 42780 42780 0 0.00 0 43 1 0 0
v_cancel_adding_invoice 920 920 0 0.00 0 302 6 0 0
v_cancel_client_order 3628 3628 0 0.00 0 1829 3 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 4020 9 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 1871 6 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 407 4 0 0
v_cancel_supplier_invoice 954 930 24 2.51 0 3961 18 0 24
v_cancel_supplier_order 800 800 0 0.00 0 2138 11 0 0
v_cancel_write_off 2152 2152 0 0.00 0 1560 11 0 0
v_max_id_clo_ord 5635 5635 0 0.00 0 721 2 0 0
v_max_id_clo_res 976 976 0 0.00 0 1858 12 0 0
v_max_id_ord_sup 5716 5716 0 0.00 0 2842 3 0 0
v_max_non_paid_invoice 1692 1692 0 0.00 0 639 3 0 0
v_max_non_paid_realizn 3914 3914 0 0.00 0 1581 4 0 0
v_min_id_clo_ord 5635 5635 0 0.00 0 3422 10 0 0
v_min_id_clo_res 976 976 0 0.00 0 3708 12 0 0
v_min_id_ord_sup 5716 5716 0 0.00 0 6123 5 0 0
v_min_non_paid_invoice 5896 5896 0 0.00 0 12672 25 0 0
v_min_non_paid_realizn 7251 7251 0 0.00 0 12755 25 0 0
v_our_firm 10908 10908 0 0.00 0 18 1 0 0
v_reserve_write_off 20740 20740 0 0.00 0 4235 5 0 0
x_lock_depdocs_on_canc_invoice 11602 11602 0 0.00 0 14166 105 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 4631 340 0 0
x_make_qstorno_1000_1200 5635 5635 0 0.00 1 45923 1223 0 0
x_make_qstorno_1200_2000 5717 5717 0 0.00 2 84691 3095 0 0
x_make_qstorno_2100_3300 465904 465904 0 0.00 0 119748 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2023 19:39:32 to 03-01-2023 19:39:36.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:36 39735 8192000000 9902421472 10209968128 972806784 10.614 100 88 176 0 1002556032 5901632 23847616
17:11:22 21143 8192000000 9929297712 10248101888 1011852304 10.993 100 90 180 0 1043487376 7731280 23903792
17:12:47 14800 8192000000 9983561248 10317574144 1067339712 11.527 100 80 160 0 1096322848 9284816 19698320
17:14:09 8583 8192000000 10038342816 10389385216 1127155088 12.095 100 73 146 1 1155672576 11269184 17248304
17:15:19 4567 8192000000 10080987904 10439815168 1169322400 12.490 100 81 160 0 1200545232 12836736 18386096
17:16:26 2772 8192000000 10123251728 10490425344 1212940800 12.896 100 84 167 0 1245701312 14072288 18688224
17:17:33 3028 8192000000 10159461744 10539393024 1249874592 13.237 100 76 152 1 1279006192 13055072 16076528
17:18:37 1531 8192000000 10191182528 10578128896 1279819360 13.511 100 81 161 1 1314192464 17026256 17346848
17:19:39 1087 8192000000 10215918496 10598768640 1297706272 13.674 100 92 180 1 1331943904 13374480 20863152
17:20:42 7585 8192000000 10204420416 10604580864 1301553696 13.709 100 41 81 0 1319152448 8393120 9205632
17:21:53 9277 8192000000 10204489600 10617876480 1316443056 13.844 100 18 38 0 1326271824 5597584 4231184
17:23:03 14044 8192000000 10210178112 10628702208 1325364336 13.925 100 18 36 0 1335350112 6089360 3896416
17:24:22 62347 8192000000 10228693952 10645987328 1331627840 13.982 100 31 62 0 1343967792 4608864 7731088
17:26:24 4474 8192000000 10227698112 10652467200 1338010816 14.039 100 22 42 0 1349060544 6084336 4965392
17:27:29 9081 8192000000 10224723776 10644553728 1331660608 13.982 100 11 22 0 1339540320 5170656 2709056
17:28:39 11158 8192000000 10189752176 10602221568 1268968016 13.412 97 11 22 0 1276019664 4925792 2125856
17:29:53 40691 8192000000 10168371360 10577977344 1201483904 12.790 92 21 42 0 1211219984 4684272 5051808
17:31:35 6130 8192000000 10132126768 10531770368 1213433200 12.901 96 31 61 1 1226778992 5891168 7454624
17:32:43 5346 8192000000 10167600368 10564206592 1258883872 13.320 99 45 90 0 1275911072 6266928 10760272
17:33:50 8246 8192000000 10161026480 10570375168 1272157120 13.441 100 25 50 3 1282721776 5370320 5194336
17:34:59 16061 8192000000 10172228224 10577498112 1277414288 13.489 100 37 74 0 1292027072 6264368 8348416
17:36:19 7826 8192000000 10187395424 10600677376 1298237376 13.679 100 26 50 0 1310319888 5931808 6150704
17:37:31 9821 8192000000 10199001696 10614362112 1305856736 13.748 100 26 52 1 1317464432 5690720 5916976
17:38:48 12481 8192000000 10230995264 10641125376 1331222736 13.978 100 26 51 0 1341711744 4977424 5511584
17:40:10 68153 8192000000 10247484544 10667188224 1331972976 13.985 99 38 77 1 1348192688 7034416 9185296
17:42:21 69270 8192000000 10258316928 10692374528 1358718720 14.226 100 21 40 1 1377728432 14330320 4679392
17:44:35 12400 8192000000 10289081104 10711146496 1378850576 14.406 100 48 96 0 1404653360 15245456 10557328
17:45:52 10243 8192000000 10315416960 10738024448 1389462672 14.501 100 51 99 0 1423202144 22406640 11332832
17:47:06 19980 8192000000 10324078400 10745704448 1382258928 14.437 99 55 110 0 1425736720 29737152 13740640
17:48:36 10628 8192000000 10316099536 10742075392 1389301552 14.500 100 38 76 0 1428450784 29663344 9485888
17:49:53 4420 8192000000 10326106864 10742628352 1386306480 14.473 99 61 121 2 1432317216 31942128 14068608
17:51:03 3459 8192000000 10332825680 10747842560 1386327488 14.473 99 71 140 0 1438761120 34871088 17562544
17:52:13 1098 8192000000 10332951184 10743775232 1379753200 14.414 99 78 153 1 1433195984 33083040 20359744
17:53:20 1545 8192000000 10328255376 10730921984 1370473856 14.331 99 84 167 0 1430848432 40158976 20215600
17:54:26 2318 8192000000 10345966608 10748809216 1373688592 14.360 98 74 144 1 1434301120 42852320 17760208
17:55:36 5003 8192000000 10334207664 10748243968 1357025648 14.211 98 55 110 1 1419652688 48578704 14048336
17:56:45 8414 8192000000 10338084640 10752143360 1381235072 14.428 100 61 123 0 1443906768 46787456 15884240
17:58:02 10275 8192000000 10333675680 10752937984 1370362656 14.330 99 56 110 0 1428544480 44571104 13610720
17:59:19 5659 8192000000 10325488448 10738778112 1368248896 14.311 100 57 113 1 1428883344 46450464 14183984
18:00:28 5872 8192000000 10324568528 10744705024 1374525776 14.368 100 37 74 0 1430225984 45609968 10090240
18:01:38 14988 8192000000 10305449072 10737803264 1346641776 14.117 98 26 52 0 1395929392 41731184 7556432
18:02:56 23080 8192000000 10299674160 10736840704 1362986736 14.264 100 26 53 0 1409291280 38391168 7913376
18:04:26 69514 8192000000 10260076480 10703994880 1323941440 13.912 98 10 20 0 1356387824 29706752 2739632
18:07:22 30287 8192000000 10278611104 10703822848 1333487920 13.999 98 51 102 1 1356911808 11536448 11887440
18:08:57 98165 8192000000 10290752384 10729463808 1375552096 14.377 100 22 44 0 1392765712 12510608 4703008
18:11:37 73130 8192000000 10297749856 10758860800 1383981200 14.452 100 16 32 0 1403692336 16692960 3018176
18:13:53 79843 8192000000 10287674848 10728886272 1324296528 13.916 95 17 32 0 1340749856 12200800 4252528
18:16:36 35439 8192000000 10307737936 10748956672 1372360112 14.348 98 41 82 1 1398877280 15021280 11495888
18:18:23 37102 8192000000 10294371984 10743234560 1390182528 14.507 100 34 68 0 1418779472 20599024 7997920
18:20:07 140849 8192000000 10294703840 10752557056 1391393920 14.518 100 10 20 0 1407278880 13568112 2316848
18:23:47 44149 8192000000 10297729728 10757496832 1394186688 14.543 100 16 30 1 1409167104 11727536 3252880
18:27:31 43435 8192000000 10280859744 10728054784 1346780528 14.119 97 27 52 1 1361190688 8477680 5932480
18:29:16 49500 8192000000 10306392000 10757791744 1398068528 14.578 100 17 34 0 1412834768 10860448 3905792
18:31:12 36019 8192000000 10312155376 10767134720 1401951616 14.612 100 13 26 0 1422133664 17590992 2591056
18:32:51 66513 8192000000 10291741600 10748559360 1340343680 14.061 96 8 16 0 1357771600 15750144 1677776
18:34:59 59210 8192000000 10278645392 10724511744 1285765616 13.566 93 20 40 1 1301187376 10671440 4750320
18:36:59 22893 8192000000 10261932016 10712449024 1354951472 14.192 100 25 49 0 1370304848 10247552 5105824
18:38:27 26319 8192000000 10262215456 10706661376 1330377232 13.971 98 39 78 0 1349453760 10826160 8250368
18:39:54 117655 8192000000 10263952992 10703409152 1326821760 13.938 97 24 48 0 1347583232 14679696 6081776
18:42:52 29061 8192000000 10262830704 10708578304 1338069248 14.040 98 31 62 0 1357420608 12311648 7039712
18:44:32 51380 8192000000 10248520240 10707628032 1347252528 14.123 99 6 10 1 1355417984 6760496 1404960
18:46:25 68943 8192000000 10288669392 10741104640 1368255536 14.311 99 16 31 0 1383019424 11182912 3580976
18:48:39 88882 8192000000 10267915856 10735153152 1300879616 13.703 94 15 30 0 1319315616 14714688 3721312
18:51:08 40780 8192000000 10273754016 10721988608 1354715440 14.190 99 24 48 0 1367602720 7814064 5073216
18:52:50 46060 8192000000 10259592944 10702770176 1303893984 13.731 95 21 42 0 1317125120 8357136 4874000
18:54:37 58619 8192000000 10279199904 10716643328 1334425248 14.007 98 21 41 0 1356639056 17449568 4764240
18:56:38 63611 8192000000 10285899056 10728865792 1341796224 14.074 98 23 46 0 1362252688 15479104 4977360
18:58:43 69320 8192000000 10268374320 10715553792 1343353424 14.088 98 14 28 0 1358071504 11719312 2998768
19:00:55 80392 8192000000 10285494672 10744700928 1381088560 14.426 100 30 60 0 1403856432 15570400 7197472
19:03:16 19223 8192000000 10272985920 10728132608 1366331440 14.294 99 30 60 0 1384843536 10910976 7601120
19:04:37 52820 8192000000 10275337120 10729750528 1382284704 14.437 100 12 22 1 1392473728 7321232 2867792
19:06:30 53232 8192000000 10270115056 10721607680 1363115168 14.265 99 14 28 0 1378974960 12013536 3846256
19:09:27 65726 8192000000 10294737504 10748235776 1379854976 14.415 99 17 35 0 1389009904 5363248 3791680
19:11:34 87101 8192000000 10285124320 10739507200 1340046352 14.058 96 22 44 0 1357107600 11980368 5080880
19:14:06 41428 8192000000 10309177824 10756763648 1392496272 14.528 100 19 38 0 1405714960 8827104 4391584
19:16:06 68662 8192000000 10285666816 10736988160 1335863904 14.020 96 22 44 0 1348936992 8469264 4603824
19:18:22 36962 8192000000 10297424688 10734927872 1355088704 14.193 98 46 92 0 1380793296 15579808 10124784
19:20:02 122933 8192000000 10273594448 10725064704 1278329280 13.498 92 10 20 0 1291153840 10728816 2095744
19:23:10 114219 8192000000 10280281360 10730708992 1361564272 14.251 99 18 37 0 1375107504 9619056 3924176
19:26:06 83073 8192000000 10271034704 10730745856 1357321072 14.213 99 8 16 0 1374422384 15369088 1732224
19:28:30 51987 8192000000 10289636208 10750803968 1378001136 14.399 100 17 34 0 1407273744 25227840 4044768
19:30:29 66200 8192000000 10288551728 10747006976 1362871792 14.263 98 21 42 0 1383026080 14822960 5331328
19:33:11 59697 8192000000 10278826928 10729291776 1343042752 14.085 97 12 24 0 1362628608 16649344 2936512
19:35:37 44331 8192000000 10262181376 10707296256 1285013920 13.559 93 16 33 0 1297960096 9174064 3772112
19:37:26 50382 8192000000 10255651632 10717519872 1307538896 13.764 96 13 26 0 1331643632 21033424 3071312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-01-2023 19:39:38 to 03-01-2023 19:39:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-01-2023 19:39:54 to 03-01-2023 19:39:56.

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          244636
MON$OLDEST_ACTIVE               245517
MON$OLDEST_SNAPSHOT             245517
MON$NEXT_TRANSACTION            245517
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                       2023744
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1605: 
:1606: mscs-test-161.budzdorov.ru    Tue Jan  3 19:38:32 2023
:1607:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1608: 
:1609: 
:1610: mscs-test-161.budzdorov.ru    Tue Jan  3 19:38:33 2023
:1611:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1612: 
:1613: 
:1614: mscs-test-161.budzdorov.ru    Tue Jan  3 19:38:37 2023
:1615:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1616: 
--- end of diff output ---

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

03.01.23 19:39:57. 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-03 19:40:00 No severe PSQL-related problems occured
03.01.23 19:40:01. End of report.