Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.01.2023 19: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 15262280 kB
MemAvailable 30372524 kB
Buffers 0 kB
Cached 16430332 kB
SwapCached 4544 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-04 19:38:34
DTS_END                         2023-01-04 19:38:34.3160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34082
TRN_ID                          240202
ATT_ID                          675
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: 9144.28 18294 1097313
customer order: creation 177.65 354 21318
customer order: refuse 15.31 350 1837
order to supplier: creation 45.83 1333 5499
order to supplier: removal 3.55 104101 426
invoice (draft): creation 47.67 3327 5720
invoice (draft): removal 51.44 864 6173
invoice accept: apply 41.95 16607 5034
invoice accept: cancel 46.28 836 5554
customer reserve: creation 3884.08 163 466090
customer reserve: removal 4539.83 65 544779
realization accept: apply 85.08 178 10209
realization accept: cancel 73.39 117 8807
payment from customer: creation 61.00 129 7320
payment from customer: removal 5.36 90 643
payment to supplier: creation 50.56 145 6067
payment to supplier: removal 3.47 61 416
service: total inventory turnovers 4.64 1280 557
service: total monetary turnovers 6.40 62 768
service: aggregate perf. data 0.77 22942 92
service: refresh index statistics 0.03 212880 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-01-2023 19:39:20 to 04-01-2023 19:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3611
TEST_TIME 2 6011
TEST_TIME 3 5321
TEST_TIME 4 5460
TEST_TIME 5 5828
TEST_TIME 6 5368
TEST_TIME 7 5686
TEST_TIME 8 5527
TEST_TIME 9 5703
TEST_TIME 10 5974
TEST_TIME 11 6130
TEST_TIME 12 6355
TEST_TIME 13 6608
TEST_TIME 14 6877
TEST_TIME 15 7485
TEST_TIME 16 7388
TEST_TIME 17 7410
TEST_TIME 18 7762
TEST_TIME 19 8064
TEST_TIME 20 8009
TEST_TIME 21 8289
TEST_TIME 22 8152
TEST_TIME 23 8294
TEST_TIME 24 8394
TEST_TIME 25 8478
TEST_TIME 26 9029
TEST_TIME 27 9018
TEST_TIME 28 9193
TEST_TIME 29 10235
TEST_TIME 30 11170
TEST_TIME 31 11042
TEST_TIME 32 10725
TEST_TIME 33 10698
TEST_TIME 34 10665
TEST_TIME 35 10633
TEST_TIME 36 10439
TEST_TIME 37 10268
TEST_TIME 38 10133
TEST_TIME 39 10135
TEST_TIME 40 10038
TEST_TIME 41 9930
TEST_TIME 42 9757
TEST_TIME 43 9701
TEST_TIME 44 9783
TEST_TIME 45 9654
TEST_TIME 46 9647
TEST_TIME 47 9614
TEST_TIME 48 9595
TEST_TIME 49 9571
TEST_TIME 50 9481
TEST_TIME 51 9451
TEST_TIME 52 9464
TEST_TIME 53 9481
TEST_TIME 54 9430
TEST_TIME 55 9358
TEST_TIME 56 9243
TEST_TIME 57 9205
TEST_TIME 58 9123
TEST_TIME 59 9163
TEST_TIME 60 9124
TEST_TIME 61 9152
TEST_TIME 62 9209
TEST_TIME 63 9202
TEST_TIME 64 9282
TEST_TIME 65 9275
TEST_TIME 66 9303
TEST_TIME 67 9328
TEST_TIME 68 9337
TEST_TIME 69 9304
TEST_TIME 70 9315
TEST_TIME 71 9375
TEST_TIME 72 9361
TEST_TIME 73 9368
TEST_TIME 74 9376
TEST_TIME 75 9344
TEST_TIME 76 9247
TEST_TIME 77 9272
TEST_TIME 78 9190
TEST_TIME 79 9192
TEST_TIME 80 9190
TEST_TIME 81 9156
TEST_TIME 82 9131
TEST_TIME 83 9143
TEST_TIME 84 9060
TEST_TIME 85 9022
TEST_TIME 86 9108
TEST_TIME 87 9103
TEST_TIME 88 9129
TEST_TIME 89 9115
TEST_TIME 90 9119
TEST_TIME 91 9146
TEST_TIME 92 9122
TEST_TIME 93 9224
TEST_TIME 94 9209
TEST_TIME 95 9192
TEST_TIME 96 9221
TEST_TIME 97 9234
TEST_TIME 98 9240
TEST_TIME 99 9246
TEST_TIME 100 9291
TEST_TIME 101 9245
TEST_TIME 102 9192
TEST_TIME 103 9141
TEST_TIME 104 9144
TEST_TIME 105 9127
TEST_TIME 106 9065
TEST_TIME 107 9041
TEST_TIME 108 9076
TEST_TIME 109 9064
TEST_TIME 110 9093
TEST_TIME 111 9114
TEST_TIME 112 9110
TEST_TIME 113 9105
TEST_TIME 114 9124
TEST_TIME 115 9105
TEST_TIME 116 9180
TEST_TIME 117 9211
TEST_TIME 118 9217
TEST_TIME 119 9210
TEST_TIME 120 9155

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-01-2023 19:39:24 to 04-01-2023 19:39:27.

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 1529238 1529238 0 0.00 0 34895 6 0 0
doc_list_biud 1529238 1529238 0 0.00 0 80810 29 0 0
sp_add_invoice_to_stock 5145 5034 111 2.15 32 152789 16608 0 111
sp_cancel_adding_invoice 5554 5554 0 0.00 4 77826 837 0 0
sp_cancel_client_order 1837 1837 0 0.00 4 9026 350 0 0
sp_cancel_customer_reserve 544779 544779 0 0.00 0 38505 65 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 7996 91 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 4427 61 0 0
sp_cancel_supplier_invoice 6184 6173 11 0.17 2 80839 865 0 11
sp_cancel_supplier_order 426 426 0 0.00 6 913778 104102 0 0
sp_cancel_write_off 8807 8807 0 0.00 1 7383 117 0 0
sp_client_order 21318 21318 0 0.00 3 32645 354 0 0
sp_customer_reserve 466090 466090 0 0.00 0 40196 164 0 0
sp_fill_shopping_cart 32531 32531 0 0.00 1 10112 41 0 0
sp_fill_shopping_cart_clo_res 466082 466082 0 0.00 0 17846 14 0 0
sp_get_clo_for_invoice 5023 5023 0 0.00 0 20667 303 0 0
sp_kill_cost_storno 15420 15420 0 0.00 0 10125 59 0 0
sp_kill_qstorno_ret_qs2qd 1104964 1104964 0 0.00 0 80795 35 0 0
sp_kill_qty_storno 582808 582808 0 0.00 0 80803 75 0 0
sp_lock_selected_doc 21114 21114 0 0.00 0 7834 6 0 0
sp_make_cost_storno 28619 28619 0 0.00 0 34871 54 0 0
sp_multiply_rows_for_pdistr 28619 28619 0 0.00 0 13933 25 0 0
sp_multiply_rows_for_qdistr 21318 21318 0 0.00 0 32631 148 0 0
sp_pay_from_customer 7320 7320 0 0.00 4 35014 129 0 0
sp_pay_to_supplier 6067 6067 0 0.00 4 35024 145 0 0
sp_payment_common 13387 13387 0 0.00 1 34936 46 0 0
sp_qd_handle_on_cancel_clo 1837 1837 0 0.00 0 9019 329 0 0
sp_qd_handle_on_invoice_upd_sts 10577 10577 0 0.00 0 30549 315 0 0
sp_qd_handle_on_reserve_upd_sts 10209 10209 0 0.00 0 28825 63 0 0
sp_reserve_write_off 10209 10209 0 0.00 6 28957 178 0 0
sp_supplier_invoice 5720 5720 0 0.00 5 94342 3327 0 0
sp_supplier_order 5499 5499 0 0.00 4 61569 1334 0 0
srv_aggregate_perf_data 92 92 0 0.00 3020 489357 22942 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1894 123530 44807 0 0
srv_find_qd_qs_mism 1048805 1048805 0 0.00 0 34613 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 47 129683 1281 0 0
srv_make_money_saldo 768 768 0 0.00 1 29939 63 0 0
srv_recalc_idx_stat 4 4 0 0.00 134524 316570 212881 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5036 5036 0 0.00 0 8059 151 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 5893 101 0 0
t$perf-norm:sp_cancel_client_order 1837 1837 0 0.00 0 4738 17 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 1104 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 1341 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 1757 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 4190 19 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 26527 1753 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 1543 9 0 0
t$perf-norm:sp_client_order 21319 21319 0 0.00 0 4509 16 0 0
t$perf-norm:sp_customer_reserve 1018 1018 0 0.00 0 3818 13 0 0
t$perf-norm:sp_pay_from_customer 7320 7320 0 0.00 0 5480 11 0 0
t$perf-norm:sp_pay_to_supplier 6067 6067 0 0.00 0 8667 9 0 0
t$perf-norm:sp_reserve_write_off 10209 10209 0 0.00 0 8652 12 0 0
t$perf-norm:sp_supplier_invoice 5720 5720 0 0.00 0 4603 19 0 0
t$perf-norm:sp_supplier_order 5499 5499 0 0.00 0 7519 18 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 305 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 4837 31 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 1379 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10266 10044 222 2.16 0 8952 17 0 222
v_all_customers 39088 39088 0 0.00 0 27 0 0 0
v_all_suppliers 31550 31550 0 0.00 0 21 1 0 0
v_all_wares 42636 42636 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 512 6 0 0
v_cancel_client_order 3674 3674 0 0.00 0 2396 4 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 5364 10 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 2536 6 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 1245 5 0 0
v_cancel_supplier_invoice 822 800 22 2.67 0 2368 21 0 22
v_cancel_supplier_order 852 852 0 0.00 0 1012 5 0 0
v_cancel_write_off 2040 2040 0 0.00 0 6298 18 0 0
v_max_id_clo_ord 5496 5496 0 0.00 0 7617 3 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 3411 18 0 0
v_max_id_ord_sup 5717 5717 0 0.00 0 3235 3 0 0
v_max_non_paid_invoice 1505 1505 0 0.00 0 186 2 0 0
v_max_non_paid_realizn 3757 3757 0 0.00 0 3882 6 0 0
v_min_id_clo_ord 5496 5496 0 0.00 0 4895 7 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 5677 12 0 0
v_min_id_ord_sup 5717 5717 0 0.00 0 4692 10 0 0
v_min_non_paid_invoice 6067 6067 0 0.00 0 34932 34 0 0
v_min_non_paid_realizn 7319 7319 0 0.00 0 26219 42 0 0
v_our_firm 10674 10674 0 0.00 0 15 1 0 0
v_reserve_write_off 20418 20418 0 0.00 0 6250 6 0 0
x_lock_depdocs_on_canc_invoice 11327 11327 0 0.00 0 25093 122 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 7187 316 0 0
x_make_qstorno_1000_1200 5499 5499 0 0.00 1 61475 1258 0 0
x_make_qstorno_1200_2000 5720 5720 0 0.00 2 89590 3173 0 0
x_make_qstorno_2100_3300 466090 466090 0 0.00 0 40194 147 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 04-01-2023 19:39:28 to 04-01-2023 19:39:33.

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:38 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:38 23756 8192000000 9903765488 10213425152 977474832 10.660 100 80 158 0 1004590688 5963856 21152000
17:11:05 14335 8192000000 9943498736 10264920064 1028062160 11.150 100 83 164 1 1057715888 7987744 21665984
17:12:27 15589 8192000000 9985753376 10314375168 1066990816 11.523 100 86 170 0 1097463856 9405552 21067488
17:13:46 12385 8192000000 10019859712 10366660608 1111637936 11.948 100 72 142 0 1137747872 9461936 16648000
17:15:02 6738 8192000000 10083396480 10443198464 1172483168 12.520 100 79 156 1 1202627360 11884896 18259296
17:16:13 4355 8192000000 10120812640 10487582720 1206297984 12.835 100 78 154 1 1237077040 13266720 17512336
17:17:21 3219 8192000000 10152559280 10528722944 1239298448 13.140 100 73 145 0 1267013632 11564640 16150544
17:18:26 1887 8192000000 10180491008 10561978368 1266388544 13.389 100 83 164 0 1297915872 12965808 18561520
17:19:31 730 8192000000 10200283248 10583683072 1290527120 13.609 100 90 178 0 1324128640 13690880 19910640
17:20:35 17435 8192000000 10199046144 10599374848 1297417056 13.672 100 59 115 0 1320364592 9316960 13630576
17:21:54 2853 8192000000 10215252384 10612051968 1311957760 13.804 100 57 113 1 1333472064 8423408 13090896
17:22:59 1500 8192000000 10216747904 10620043264 1316686736 13.847 100 65 127 0 1340063488 8297872 15078880
17:24:02 4606 8192000000 10219582096 10630733824 1324257616 13.915 100 39 78 1 1340375600 7250720 8867264
17:25:07 15660 8192000000 10215538448 10634809344 1331231376 13.978 100 21 42 0 1342461264 6223264 5006624
17:26:24 10872 8192000000 10217682432 10645901312 1334451136 14.007 100 11 22 1 1346440976 9907600 2082240
17:27:36 1967 8192000000 10230821792 10645889024 1334636128 14.009 100 50 101 1 1352624672 6645504 11343040
17:28:40 11308 8192000000 10213722400 10631512064 1303879536 13.731 98 24 47 1 1314443024 5062288 5501200
17:29:52 9914 8192000000 10166639344 10570784768 1211250608 12.881 93 14 28 2 1223727728 9710832 2766288
17:31:03 64921 8192000000 10108910288 10507100160 1161344384 12.416 92 35 70 2 1174544928 5363520 7837024
17:33:13 3392 8192000000 10137605824 10530668544 1236631056 13.115 99 30 58 0 1249031728 5666400 6734272
17:34:18 5340 8192000000 10154048800 10555981824 1252682912 13.263 99 23 46 1 1264407312 6271200 5453200
17:35:25 20498 8192000000 10171087792 10580062208 1283843024 13.548 100 20 41 1 1293380352 5085168 4452160
17:36:48 36973 8192000000 10176569760 10588291072 1289564832 13.600 100 26 50 3 1300370144 5718672 5086640
17:38:26 4422 8192000000 10207958864 10619981824 1311940032 13.804 100 23 46 1 1322310160 5092752 5277376
17:39:44 30204 8192000000 10238411248 10656833536 1338581840 14.045 100 33 66 0 1356999568 10718864 7698864
17:41:18 20496 8192000000 10267282128 10686771200 1358160480 14.221 100 48 96 0 1380823552 11572544 11090528
17:42:54 23043 8192000000 10282101744 10712178688 1380096384 14.417 100 39 77 0 1406336528 16904960 9335184
17:44:56 18489 8192000000 10312190976 10738569216 1391683120 14.521 100 39 78 0 1424144032 23502336 8958576
17:46:21 12267 8192000000 10327360784 10750111744 1403180752 14.623 100 61 119 0 1438792416 21463968 14147696
17:47:43 9607 8192000000 10343747600 10767753216 1413062624 14.711 100 59 118 0 1450057472 22314240 14680608
17:48:59 9785 8192000000 10340354112 10767622144 1415105184 14.729 100 50 100 0 1450256848 22827136 12324528
17:50:13 4941 8192000000 10364245632 10790559744 1427832064 14.842 100 66 126 0 1470413520 27653968 14927488
17:51:24 5250 8192000000 10369405712 10791714816 1427662464 14.841 100 63 126 0 1471759600 28596160 15500976
17:52:34 1891 8192000000 10373548704 10793889792 1420163552 14.774 100 82 164 2 1478333024 38376528 19792944
17:53:42 3805 8192000000 10349686672 10773241856 1401876272 14.612 100 65 129 0 1458009696 40116096 16017328
17:54:52 18726 8192000000 10373534432 10799845376 1417131456 14.747 100 47 95 1 1473497968 43960752 12405760
17:56:19 18445 8192000000 10365722304 10788614144 1401297184 14.607 99 47 94 0 1457898048 44027984 12572880
17:57:44 11398 8192000000 10321239264 10741063680 1349334144 14.141 97 34 68 0 1400745968 41885376 9526448
17:59:02 7410 8192000000 10315988128 10734120960 1356833696 14.209 98 49 97 4 1415375664 45410432 13131536
18:00:17 6640 8192000000 10313761152 10736721920 1370235168 14.329 100 35 70 0 1422114528 42110016 9769344
18:01:33 11769 8192000000 10350495472 10777579520 1396370160 14.563 100 33 66 0 1458925888 53167488 9388240
18:02:53 17780 8192000000 10353742720 10777358336 1378835440 14.406 99 36 72 0 1449020480 59743504 10441536
18:04:16 12095 8192000000 10367617904 10800070656 1397901024 14.576 100 32 64 0 1466718480 59572384 9245072
18:05:48 23669 8192000000 10338698080 10782908416 1371895696 14.344 100 26 52 1 1448782432 69119664 7767072
18:07:13 25073 8192000000 10325576064 10774945792 1348129408 14.131 99 24 48 0 1436046208 80244704 7672096
18:08:41 49547 8192000000 10275927856 10715725824 1325631664 13.928 97 16 30 2 1341764992 13335792 2797536
18:10:32 69702 8192000000 10275349440 10710630400 1340121408 14.059 98 42 85 0 1365974480 15425088 10427984
18:12:49 48396 8192000000 10272294512 10720296960 1370792240 14.334 100 33 64 0 1391114752 13063936 7258576
18:14:42 123505 8192000000 10285245696 10736410624 1351399280 14.160 98 14 29 0 1366786656 12137616 3249760
18:17:46 37322 8192000000 10289699216 10728890368 1367601040 14.306 99 50 94 2 1389517616 11059904 10856672
18:19:34 101646 8192000000 10281767312 10730483712 1352924368 14.174 98 24 49 0 1373124976 14213056 5987552
18:22:59 49177 8192000000 10287536368 10723414016 1369336160 14.321 100 51 100 0 1387941216 6217712 12387344
18:24:49 59237 8192000000 10295863616 10745221120 1361381872 14.250 99 22 44 0 1380706304 14137664 5186768
18:28:57 33141 8192000000 10299152160 10750406656 1382326656 14.437 99 33 64 0 1403132592 13162576 7643360
18:31:34 47431 8192000000 10281581232 10744659968 1392148128 14.525 100 12 24 0 1401410848 6734896 2527824
18:33:32 40969 8192000000 10288592960 10758692864 1398251408 14.579 100 4 8 0 1406463152 7162320 1049424
18:35:14 89785 8192000000 10282343312 10756997120 1382946368 14.443 99 7 15 0 1393169904 8175392 2048144
18:37:44 93568 8192000000 10293482912 10771189760 1386588224 14.475 99 8 16 0 1399449360 11147984 1713152
18:40:20 53663 8192000000 10304715504 10763706368 1392939968 14.532 99 13 26 1 1413154304 17437856 2776480
18:42:14 33044 8192000000 10286235264 10747109376 1371399680 14.340 98 7 14 0 1382823296 9744912 1678704
18:44:11 42467 8192000000 10295508768 10734895104 1370278144 14.330 99 28 54 0 1386698944 10236048 6184752
18:45:53 67636 8192000000 10265279552 10716221440 1309358112 13.780 95 14 28 0 1324321264 12168640 2794512
18:48:01 49366 8192000000 10281923520 10731032576 1350637072 14.153 98 26 52 0 1369731264 13451360 5642832
18:49:51 46195 8192000000 10253261168 10693468160 1290005520 13.604 94 26 54 0 1303108800 7692640 5410640
18:51:43 58614 8192000000 10270469552 10714693632 1324536768 13.918 97 15 30 0 1337765696 9698592 3530336
18:53:42 79793 8192000000 10274773728 10709504000 1343916800 14.093 99 41 80 0 1362508016 9667344 8923872
18:56:02 53943 8192000000 10238101712 10677723136 1283143984 13.542 95 35 70 1 1300142336 9366784 7631568
18:58:05 64063 8192000000 10220561536 10669654016 1279913136 13.512 96 15 31 1 1291948800 8718336 3317328
19:00:10 83871 8192000000 10225147600 10677506048 1311339408 13.798 98 13 26 0 1323664432 9024032 3300992
19:02:40 54185 8192000000 10238794816 10688421888 1314480752 13.827 98 17 34 1 1335084576 16662416 3941408
19:04:34 52099 8192000000 10271959952 10708103168 1355718256 14.199 100 30 60 0 1377792848 15378608 6695984
19:06:28 75541 8192000000 10277729552 10723880960 1345900272 14.111 98 16 32 0 1359411376 9632384 3878720
19:08:43 58423 8192000000 10265588320 10719895552 1341612992 14.072 98 27 53 0 1357403104 10408896 5381216
19:10:48 71081 8192000000 10287919984 10749235200 1381753472 14.432 100 13 26 0 1401745984 16945616 3046896
19:13:05 28899 8192000000 10299339232 10736898048 1369461824 14.322 99 33 66 0 1385974608 9768112 6744672
19:14:43 69010 8192000000 10289672960 10737139712 1331629328 13.982 96 20 40 0 1352112160 15853856 4628976
19:16:55 42784 8192000000 10287985392 10730524672 1361233648 14.248 98 22 44 0 1372667536 6946304 4487584
19:18:41 90897 8192000000 10268426464 10718420992 1322238272 13.897 96 24 48 0 1336263296 8722736 5302288
19:21:27 58306 8192000000 10284314080 10736201728 1368217616 14.311 99 23 46 0 1382822736 9682928 4922192
19:23:25 67996 8192000000 10266349120 10725445632 1363511952 14.269 99 11 22 1 1376161616 10178528 2471136
19:25:59 33348 8192000000 10284341632 10741694464 1376512464 14.385 100 28 57 0 1399353168 15421136 7419568
19:27:34 56521 8192000000 10288734000 10748145664 1370167120 14.329 99 10 19 0 1386244048 13805792 2271136
19:29:33 65323 8192000000 10300373920 10744262656 1361914160 14.255 98 30 59 1 1380397376 11914928 6568288
19:31:42 53921 8192000000 10278274080 10732249088 1345865888 14.110 98 26 52 0 1362180640 10315216 5999536
19:33:39 25023 8192000000 10291230320 10729177088 1358928784 14.228 99 40 81 0 1380905440 12800608 9176048
19:35:10 105935 8192000000 10268635328 10727276544 1280615408 13.519 93 15 30 0 1301233616 17487136 3131072
19:37:57 44590 8192000000 9063510192 9286967296 222055264 2.639 17 3 6 0 226862112 4114096 692752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-01-2023 19:39:35 to 04-01-2023 19:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-01-2023 19:39:51 to 04-01-2023 19:39:53.

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          240482
MON$OLDEST_ACTIVE               240483
MON$OLDEST_SNAPSHOT             240483
MON$NEXT_TRANSACTION            240483
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                       2034336
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 ---
:1665: 
:1666: mscs-test-161.budzdorov.ru    Wed Jan  4 19:38:47 2023
:1667:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1668: 
--- end of diff output ---

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

04.01.23 19:39:54. 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-04 19:39:56 No severe PSQL-related problems occured
04.01.23 19:39:58. End of report.