Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.02.2023 16:38
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 15344344 kB
MemAvailable 30402440 kB
Buffers 0 kB
Cached 16339040 kB
SwapCached 5068 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-10 16:38:37
DTS_END                         2023-02-10 16:38:37.0140
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/34604
TRN_ID                          237310
ATT_ID                          710
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: 9308.66 21143 1117039
customer order: creation 168.46 403 20215
customer order: refuse 14.26 338 1711
order to supplier: creation 45.95 1344 5514
order to supplier: removal 3.52 108574 422
invoice (draft): creation 44.85 3743 5382
invoice (draft): removal 52.63 847 6316
invoice accept: apply 41.73 19010 5008
invoice accept: cancel 47.84 794 5741
customer reserve: creation 3893.07 188 467168
customer reserve: removal 4703.33 63 564399
realization accept: apply 82.36 195 9883
realization accept: cancel 84.27 112 10112
payment from customer: creation 57.87 132 6944
payment from customer: removal 5.98 79 718
payment to supplier: creation 47.57 130 5708
payment to supplier: removal 3.34 107 401
service: total inventory turnovers 4.21 1224 505
service: total monetary turnovers 6.58 27 789
service: aggregate perf. data 0.83 22273 99
service: refresh index statistics 0.03 263285 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-02-2023 16:39:17 to 10-02-2023 16:39:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2973
TEST_TIME 2 3240
TEST_TIME 3 3730
TEST_TIME 4 4067
TEST_TIME 5 4312
TEST_TIME 6 4467
TEST_TIME 7 5040
TEST_TIME 8 5239
TEST_TIME 9 5625
TEST_TIME 10 5953
TEST_TIME 11 6323
TEST_TIME 12 6448
TEST_TIME 13 6694
TEST_TIME 14 6784
TEST_TIME 15 7187
TEST_TIME 16 7446
TEST_TIME 17 7408
TEST_TIME 18 7487
TEST_TIME 19 7832
TEST_TIME 20 7967
TEST_TIME 21 8027
TEST_TIME 22 7963
TEST_TIME 23 8232
TEST_TIME 24 8556
TEST_TIME 25 8495
TEST_TIME 26 8592
TEST_TIME 27 9159
TEST_TIME 28 9173
TEST_TIME 29 9914
TEST_TIME 30 9977
TEST_TIME 31 10684
TEST_TIME 32 10622
TEST_TIME 33 10434
TEST_TIME 34 10211
TEST_TIME 35 10013
TEST_TIME 36 9924
TEST_TIME 37 9994
TEST_TIME 38 9797
TEST_TIME 39 9590
TEST_TIME 40 9671
TEST_TIME 41 9574
TEST_TIME 42 9507
TEST_TIME 43 9439
TEST_TIME 44 9405
TEST_TIME 45 9591
TEST_TIME 46 9455
TEST_TIME 47 9454
TEST_TIME 48 9314
TEST_TIME 49 9139
TEST_TIME 50 9096
TEST_TIME 51 9229
TEST_TIME 52 9095
TEST_TIME 53 9175
TEST_TIME 54 9102
TEST_TIME 55 9049
TEST_TIME 56 9022
TEST_TIME 57 8939
TEST_TIME 58 8884
TEST_TIME 59 8958
TEST_TIME 60 9037
TEST_TIME 61 9021
TEST_TIME 62 9012
TEST_TIME 63 9022
TEST_TIME 64 9135
TEST_TIME 65 9111
TEST_TIME 66 9199
TEST_TIME 67 9295
TEST_TIME 68 9224
TEST_TIME 69 9242
TEST_TIME 70 9267
TEST_TIME 71 9272
TEST_TIME 72 9290
TEST_TIME 73 9411
TEST_TIME 74 9442
TEST_TIME 75 9350
TEST_TIME 76 9278
TEST_TIME 77 9311
TEST_TIME 78 9273
TEST_TIME 79 9250
TEST_TIME 80 9219
TEST_TIME 81 9224
TEST_TIME 82 9193
TEST_TIME 83 9196
TEST_TIME 84 9141
TEST_TIME 85 9154
TEST_TIME 86 9168
TEST_TIME 87 9177
TEST_TIME 88 9224
TEST_TIME 89 9245
TEST_TIME 90 9227
TEST_TIME 91 9264
TEST_TIME 92 9217
TEST_TIME 93 9309
TEST_TIME 94 9276
TEST_TIME 95 9344
TEST_TIME 96 9312
TEST_TIME 97 9329
TEST_TIME 98 9340
TEST_TIME 99 9375
TEST_TIME 100 9301
TEST_TIME 101 9283
TEST_TIME 102 9266
TEST_TIME 103 9225
TEST_TIME 104 9245
TEST_TIME 105 9212
TEST_TIME 106 9169
TEST_TIME 107 9126
TEST_TIME 108 9137
TEST_TIME 109 9139
TEST_TIME 110 9135
TEST_TIME 111 9111
TEST_TIME 112 9151
TEST_TIME 113 9152
TEST_TIME 114 9175
TEST_TIME 115 9291
TEST_TIME 116 9270
TEST_TIME 117 9256
TEST_TIME 118 9287
TEST_TIME 119 9336
TEST_TIME 120 9309

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-02-2023 16:39:21 to 10-02-2023 16:39:24.

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 1549152 1549152 0 0.00 0 25223 6 0 0
doc_list_biud 1549152 1549152 0 0.00 0 27936 29 0 0
sp_add_invoice_to_stock 5170 5008 162 3.13 26 180685 19010 0 162
sp_cancel_adding_invoice 5741 5741 0 0.00 4 53065 794 0 0
sp_cancel_client_order 1711 1711 0 0.00 5 6973 338 0 0
sp_cancel_customer_reserve 564399 564399 0 0.00 0 26108 64 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 2 5413 80 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 6143 108 0 0
sp_cancel_supplier_invoice 6326 6316 10 0.15 1 27810 847 0 10
sp_cancel_supplier_order 422 422 0 0.00 5 980248 108575 0 0
sp_cancel_write_off 10112 10112 0 0.00 1 8036 113 0 0
sp_client_order 20215 20215 0 0.00 3 32615 403 0 0
sp_customer_reserve 467168 467168 0 0.00 0 78656 188 0 0
sp_fill_shopping_cart 31098 31098 0 0.00 1 17054 42 0 0
sp_fill_shopping_cart_clo_res 467167 467167 0 0.00 0 13754 16 0 0
sp_get_clo_for_invoice 5007 5007 0 0.00 0 37377 337 0 0
sp_kill_cost_storno 16972 16972 0 0.00 0 9623 56 0 0
sp_kill_qstorno_ret_qs2qd 1145648 1145648 0 0.00 0 27806 35 0 0
sp_kill_qty_storno 603591 603591 0 0.00 0 27936 74 0 0
sp_lock_selected_doc 20697 20697 0 0.00 0 6633 4 0 0
sp_make_cost_storno 27542 27542 0 0.00 0 19539 57 0 0
sp_multiply_rows_for_pdistr 27542 27542 0 0.00 0 25217 30 0 0
sp_multiply_rows_for_qdistr 20215 20215 0 0.00 0 32592 174 0 0
sp_pay_from_customer 6944 6944 0 0.00 4 25227 133 0 0
sp_pay_to_supplier 5708 5708 0 0.00 5 12681 130 0 0
sp_payment_common 12652 12652 0 0.00 2 10357 37 0 0
sp_qd_handle_on_cancel_clo 1711 1711 0 0.00 0 6954 312 0 0
sp_qd_handle_on_invoice_upd_sts 10748 10748 0 0.00 0 27935 345 0 0
sp_qd_handle_on_reserve_upd_sts 9883 9883 0 0.00 0 15529 70 0 0
sp_reserve_write_off 9883 9883 0 0.00 6 15568 195 0 0
sp_supplier_invoice 5382 5382 0 0.00 5 75647 3744 0 0
sp_supplier_order 5514 5514 0 0.00 5 41459 1344 0 0
srv_aggregate_perf_data 99 99 0 0.00 2708 513653 22274 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1028 98271 40395 0 0
srv_find_qd_qs_mism 1069493 1069493 0 0.00 0 23723 5 0 0
srv_make_invnt_saldo 505 505 0 0.00 41 42402 1224 0 0
srv_make_money_saldo 789 789 0 0.00 1 1370 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 138192 402673 263286 0 0
t$perf-abend:sp_add_invoice_to_stock 162 162 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5008 5008 0 0.00 0 17950 158 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 4026 93 0 0
t$perf-norm:sp_cancel_client_order 1711 1711 0 0.00 0 957 8 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 2041 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 4071 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 3052 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 716 13 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 39060 1970 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 2218 6 0 0
t$perf-norm:sp_client_order 20215 20215 0 0.00 0 12160 17 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 3458 11 0 0
t$perf-norm:sp_pay_from_customer 6944 6944 0 0.00 0 21566 16 0 0
t$perf-norm:sp_pay_to_supplier 5708 5708 0 0.00 0 5665 11 0 0
t$perf-norm:sp_reserve_write_off 9883 9883 0 0.00 0 14796 15 0 0
t$perf-norm:sp_supplier_invoice 5382 5382 0 0.00 0 3911 17 0 0
t$perf-norm:sp_supplier_order 5514 5514 0 0.00 0 14392 17 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 161 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 523 17 0 0
t$perf-norm:srv_make_invnt_saldo 505 505 0 0.00 0 1728 28 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 5831 34 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10338 10014 324 3.13 0 4189 15 0 324
v_all_customers 36722 36722 0 0.00 0 15 1 0 0
v_all_suppliers 30018 30018 0 0.00 0 26 1 0 0
v_all_wares 40426 40426 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 826 8 0 0
v_cancel_client_order 3422 3422 0 0.00 0 3497 5 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 5390 9 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 1242 5 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 2882 7 0 0
v_cancel_supplier_invoice 820 800 20 2.43 0 507 15 0 20
v_cancel_supplier_order 844 844 0 0.00 0 2883 8 0 0
v_cancel_write_off 2016 2016 0 0.00 0 5399 18 0 0
v_max_id_clo_ord 5514 5514 0 0.00 0 4795 4 0 0
v_max_id_clo_res 959 959 0 0.00 0 2890 14 0 0
v_max_id_ord_sup 5371 5371 0 0.00 0 8053 4 0 0
v_max_non_paid_invoice 1584 1584 0 0.00 0 6925 9 0 0
v_max_non_paid_realizn 3681 3681 0 0.00 0 1608 4 0 0
v_min_id_clo_ord 5514 5514 0 0.00 0 5323 8 0 0
v_min_id_clo_res 959 959 0 0.00 0 9582 17 0 0
v_min_id_ord_sup 5371 5371 0 0.00 0 5897 7 0 0
v_min_non_paid_invoice 5708 5708 0 0.00 0 10351 23 0 0
v_min_non_paid_realizn 6944 6944 0 0.00 0 8666 31 0 0
v_our_firm 10230 10230 0 0.00 0 17 1 0 0
v_reserve_write_off 19766 19766 0 0.00 0 5476 6 0 0
x_lock_depdocs_on_canc_invoice 11657 11657 0 0.00 0 15612 126 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 5907 352 0 0
x_make_qstorno_1000_1200 5514 5514 0 0.00 1 41408 1265 0 0
x_make_qstorno_1200_2000 5382 5382 0 0.00 3 75639 3586 0 0
x_make_qstorno_2100_3300 467168 467168 0 0.00 0 78655 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-02-2023 16:39:25 to 10-02-2023 16:39:29.

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:40 3 8192000000 8806550224 8820658176 11536224 0.140 5 3 2 0 11644192 65184 42784
14:09:41 23558 8192000000 9889050112 10195582976 971268512 10.599 100 94 184 0 1001735344 5744016 24722816
14:11:10 20897 8192000000 9942748112 10261024768 1021729408 11.089 100 88 176 0 1052487600 7841088 22917104
14:12:32 15742 8192000000 9976737984 10307272704 1058484800 11.442 100 80 158 1 1086817376 8548688 19783888
14:13:59 8729 8192000000 10028205664 10374971392 1117635088 12.005 100 79 156 0 1150248096 14252944 18360064
14:15:10 6927 8192000000 10082362048 10441469952 1170128560 12.498 100 70 140 1 1197622160 11492112 16001488
14:16:20 4554 8192000000 10117270688 10487218176 1210585632 12.875 100 78 156 1 1240285456 12255856 17443968
14:17:26 2411 8192000000 10151343232 10528079872 1241700272 13.162 100 79 159 0 1272466128 13467568 17298288
14:18:29 1752 8192000000 10179793232 10562420736 1268252816 13.406 100 87 170 0 1300901056 12992416 19655824
14:19:32 992 8192000000 10208578480 10592075776 1294648400 13.647 100 92 184 2 1328266560 13261600 20356560
14:20:35 26343 8192000000 10196785296 10598965248 1299454528 13.690 100 53 106 0 1320436144 9275680 11705936
14:22:03 3319 8192000000 10205198528 10611716096 1306120512 13.751 100 33 66 0 1323475024 10481056 6873456
14:23:09 10089 8192000000 10197606384 10617118720 1316976848 13.849 100 23 46 0 1327931792 6261296 4693648
14:24:22 5768 8192000000 10208274560 10632282112 1329546448 13.963 100 18 36 0 1339612400 6164608 3901344
14:25:30 40663 8192000000 10231063984 10649071616 1338424800 14.043 100 33 67 0 1351425952 4775504 8225648
14:27:35 6008 8192000000 10227902240 10653335552 1331204128 13.978 99 28 54 2 1343408400 6261312 5942960
14:28:43 10453 8192000000 10218091984 10639048704 1311457008 13.799 99 21 42 0 1330428720 14531008 4440704
14:29:57 3861 8192000000 10178100704 10586427392 1289049504 13.596 100 26 52 1 1301499776 6551648 5898624
14:31:08 23141 8192000000 10152789024 10556592128 1204340080 12.817 93 55 109 2 1223611536 7537728 11733728
14:32:32 4152 8192000000 10124231232 10516029440 1195073472 12.731 96 30 59 0 1213340144 11081728 7184944
14:33:37 13185 8192000000 10144971664 10543206400 1228879600 13.044 98 20 38 1 1239331520 5964144 4487776
14:34:53 9550 8192000000 10176694352 10589339648 1289114240 13.596 100 21 42 0 1299406976 5085312 5207424
14:36:04 32049 8192000000 10173517680 10591830016 1291051744 13.614 100 23 46 0 1301732544 5144400 5536400
14:37:37 12516 8192000000 10200078416 10610450432 1303457248 13.727 100 20 40 0 1313241344 5276784 4507312
14:38:56 38657 8192000000 10241755120 10654515200 1333069072 13.995 100 29 58 1 1349546928 10234144 6243712
14:40:40 18605 8192000000 10275755152 10688729088 1359530416 14.233 100 47 92 1 1384664176 14079088 11054672
14:42:00 39106 8192000000 10285742336 10701643776 1369099632 14.319 100 50 100 2 1396834464 15938096 11796736
14:43:44 19775 8192000000 10287744880 10716209152 1382446752 14.438 100 31 61 0 1406066560 16216432 7403376
14:45:21 20708 8192000000 10308083920 10735972352 1391256368 14.517 100 35 68 0 1421106560 21362848 8487344
14:46:48 4203 8192000000 10329390368 10753191936 1404891232 14.639 100 56 112 1 1439941600 21247680 13802688
14:47:56 2304 8192000000 10326720032 10752106496 1404145296 14.632 100 53 106 0 1440126784 22728416 13253072
14:49:04 4418 8192000000 10345937408 10772332544 1418061040 14.756 100 57 113 1 1457834912 25928096 13845776
14:50:12 4568 8192000000 10334682000 10759708672 1407178192 14.659 99 51 98 1 1447323760 27930400 12215168
14:51:21 2563 8192000000 10354462624 10774544384 1406864480 14.656 99 79 157 0 1459126512 33408016 18854016
14:52:27 1205 8192000000 10362579360 10778402816 1418921760 14.763 100 84 166 0 1469793504 30945360 19926384
14:53:37 10189 8192000000 10352017728 10775257088 1402082032 14.614 100 51 102 0 1456163104 40747136 13333936
14:54:52 1708 8192000000 10351264480 10769227776 1406050560 14.649 100 77 153 1 1459321968 34269616 19001792
14:55:59 7902 8192000000 10341527360 10768154624 1380807632 14.424 98 51 102 1 1432298288 38729312 12761344
14:57:17 1755 8192000000 10330761712 10744266752 1378736832 14.405 100 68 135 1 1436149040 39354688 18057520
14:58:28 6690 8192000000 10319269248 10742988800 1383674480 14.449 100 48 96 0 1434675632 38933008 12068144
14:59:39 23772 8192000000 10334724256 10750791680 1368878800 14.317 99 44 86 1 1427798256 47489984 11429472
15:01:22 1020 8192000000 10364749376 10765762560 1381429008 14.429 100 87 170 2 1461491712 58584496 21478208
15:02:32 6038 8192000000 10337875056 10759180288 1370143232 14.328 99 47 93 0 1434137232 51939520 12054480
15:03:39 14829 8192000000 10331905520 10755887104 1374268048 14.365 100 36 71 0 1436076240 51386528 10421664
15:04:56 8919 8192000000 10332839808 10759983104 1366118560 14.292 100 35 70 1 1439340064 63182432 10039072
15:06:06 23941 8192000000 10316018912 10749050880 1346069536 14.112 99 28 55 1 1411076048 57082176 7924336
15:07:33 33251 8192000000 10295234400 10736951296 1336019696 14.022 99 20 39 2 1398943696 56822144 6101856
15:09:19 22690 8192000000 10262624192 10702942208 1350428304 14.151 99 30 59 1 1366232368 9142928 6661136
15:10:42 36491 8192000000 10249639392 10693373952 1333009024 13.994 98 24 48 0 1351138400 12317440 5811936
15:12:22 98271 8192000000 10273710544 10713186304 1342731184 14.082 98 23 46 0 1361321808 13049120 5541504
15:15:01 47038 8192000000 10258464128 10712592384 1340865296 14.065 98 27 54 0 1359597376 12517024 6215056
15:17:35 3926 8192000000 10286133696 10715664384 1364538544 14.278 100 57 113 1 1391495328 12903440 14053344
15:19:01 56264 8192000000 10281869152 10721042432 1370675984 14.333 100 29 58 0 1385417632 7871680 6869968
15:21:01 34553 8192000000 10299750688 10738405376 1384424768 14.456 100 56 112 2 1412259680 14580592 13254320
15:22:51 74602 8192000000 10279455408 10746613760 1376358272 14.384 100 10 20 0 1399804032 21118960 2326800
15:25:06 41374 8192000000 10284354512 10744889344 1383301680 14.446 100 11 22 1 1400210336 14840272 2068384
15:26:49 30252 8192000000 10287080976 10742493184 1380395056 14.420 99 11 22 2 1394566880 11358848 2812976
15:28:46 64638 8192000000 10291779728 10751041536 1364328432 14.276 98 40 80 0 1388555152 14537696 9689024
15:30:52 39100 8192000000 10283369120 10744606720 1388254464 14.490 100 26 52 0 1408404160 14137152 6012544
15:33:22 60741 8192000000 10315466320 10771922944 1405965808 14.648 100 20 40 1 1426030384 15571600 4492976
15:35:24 70632 8192000000 10320985856 10782167040 1418705344 14.761 100 38 76 0 1450719296 23346320 8667632
15:40:07 46559 8192000000 10300773408 10760269824 1376829600 14.388 98 15 30 0 1387985488 8048144 3107744
15:41:55 80562 8192000000 10307903200 10755952640 1377274128 14.392 98 17 33 1 1395336288 14920144 3142016
15:44:16 30673 8192000000 10305823264 10757668864 1374826032 14.370 98 41 82 0 1397525424 13657104 9042288
15:45:47 63568 8192000000 10294948368 10724773888 1306801952 13.757 94 25 50 1 1320998896 9027600 5169344
15:47:51 18711 8192000000 10284091216 10726670336 1372202880 14.347 100 34 68 0 1392353104 12704864 7445360
15:49:12 44789 8192000000 10259267152 10698432512 1293710112 13.638 96 31 63 0 1312661360 12236752 6714496
15:50:57 85290 8192000000 10240598960 10685263872 1271445584 13.435 94 14 28 0 1287800560 13060160 3294816
15:53:27 77901 8192000000 10248398912 10681372672 1329431664 13.962 99 41 80 1 1347046880 8411424 9203792
15:55:47 55556 8192000000 10233424384 10675539968 1329569584 13.963 99 20 40 0 1343714672 9570688 4574400
15:57:44 67543 8192000000 10246631584 10695794688 1339503184 14.053 99 11 22 0 1350379600 8537344 2339072
15:59:52 41627 8192000000 10230984400 10676609024 1303552480 13.728 97 10 19 0 1312882688 7625280 1704928
16:01:35 59124 8192000000 10235514688 10693005312 1344318480 14.096 100 16 32 1 1359835648 11635216 3881952
16:03:44 51904 8192000000 10255112016 10708803584 1341215136 14.068 99 12 24 0 1364041120 20003568 2822416
16:05:38 77450 8192000000 10277634080 10736320512 1368876032 14.317 100 14 28 0 1391751024 19841504 3033488
16:08:00 28753 8192000000 10278079920 10718859264 1353268240 14.177 99 31 62 0 1367698992 8274288 6156464
16:09:34 51398 8192000000 10273890464 10711748608 1287636928 13.583 94 15 31 0 1297628608 6959600 3032080
16:11:25 53598 8192000000 10280022656 10724024320 1368793712 14.316 100 7 13 0 1383346320 13422304 1130304
16:13:25 70605 8192000000 10277999376 10724167680 1343693824 14.091 97 23 46 0 1364503408 15735920 5073664
16:15:46 36304 8192000000 10311256768 10752663552 1389610592 14.502 100 32 63 0 1407907472 11504384 6792496
16:17:23 88631 8192000000 10296164688 10747117568 1379185920 14.409 99 14 28 1 1391333728 8705376 3442432
16:21:05 22798 8192000000 10297014704 10747043840 1394414304 14.545 100 27 53 0 1410167136 9974512 5778320
16:22:29 69023 8192000000 10275094416 10739929088 1372882288 14.353 98 16 32 0 1385051488 8497104 3672096
16:24:38 74670 8192000000 10303573920 10759135232 1371253968 14.338 97 16 32 0 1383688944 8876368 3558608
16:26:53 58122 8192000000 10301442560 10765107200 1392409264 14.527 99 19 36 0 1408905904 12186528 4310112
16:28:53 47397 8192000000 10286818432 10742583296 1376103568 14.382 98 14 28 0 1390648368 11516080 3028720
16:30:43 57281 8192000000 10292759408 10737381376 1336564768 14.026 95 23 46 0 1353425648 11580256 5280624
16:32:47 92529 8192000000 10279777936 10742517760 1328809488 13.956 96 13 26 0 1345470944 13929952 2731504
16:35:23 55163 8192000000 10301137488 10741972992 1369365488 14.321 99 34 68 0 1396662512 19428848 7868176
16:37:22 81716 8192000000 9036634976 9240236032 202638208 2.413 15 2 4 0 204645968 1721488 286272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-02-2023 16:39:31 to 10-02-2023 16:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 162 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-02-2023 16:39:48 to 10-02-2023 16:39: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          237620
MON$OLDEST_ACTIVE               237621
MON$OLDEST_SNAPSHOT             237621
MON$NEXT_TRANSACTION            237621
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                       2026912
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.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 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'

10.02.23 16:39: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-02-10 16:39:53 No severe PSQL-related problems occured
10.02.23 16:39:55. End of report.