Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.2022 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 15138792 kB
MemAvailable 30118428 kB
Buffers 0 kB
Cached 16579208 kB
SwapCached 3392 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 2022-12-09 19:38:31
DTS_END                         2022-12-09 19:38:31.8600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33518
TRN_ID                          245810
ATT_ID                          718
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: 9202.17 19254 1104261
customer order: creation 176.38 328 21165
customer order: refuse 15.58 350 1869
order to supplier: creation 48.08 1152 5769
order to supplier: removal 3.37 82741 404
invoice (draft): creation 47.41 3527 5689
invoice (draft): removal 50.36 672 6043
invoice accept: apply 43.10 16183 5172
invoice accept: cancel 45.85 782 5502
customer reserve: creation 3927.73 161 471327
customer reserve: removal 4548.46 48 545815
realization accept: apply 87.36 171 10483
realization accept: cancel 74.96 104 8995
payment from customer: creation 60.89 108 7307
payment from customer: removal 5.91 66 709
payment to supplier: creation 51.10 132 6132
payment to supplier: removal 3.51 68 421
service: total inventory turnovers 4.85 1001 582
service: total monetary turnovers 6.28 23 754
service: aggregate perf. data 0.99 13045 119
service: refresh index statistics 0.03 264410 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2022 19:39:54 to 09-12-2022 19:39:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5373
TEST_TIME 2 6016
TEST_TIME 3 4429
TEST_TIME 4 4820
TEST_TIME 5 5559
TEST_TIME 6 5487
TEST_TIME 7 5606
TEST_TIME 8 5640
TEST_TIME 9 5701
TEST_TIME 10 5729
TEST_TIME 11 5490
TEST_TIME 12 5449
TEST_TIME 13 5683
TEST_TIME 14 6147
TEST_TIME 15 6144
TEST_TIME 16 6131
TEST_TIME 17 6704
TEST_TIME 18 7062
TEST_TIME 19 7652
TEST_TIME 20 8049
TEST_TIME 21 8101
TEST_TIME 22 8335
TEST_TIME 23 8745
TEST_TIME 24 9151
TEST_TIME 25 9589
TEST_TIME 26 9683
TEST_TIME 27 9872
TEST_TIME 28 9783
TEST_TIME 29 10117
TEST_TIME 30 10043
TEST_TIME 31 10107
TEST_TIME 32 10382
TEST_TIME 33 10362
TEST_TIME 34 10220
TEST_TIME 35 9948
TEST_TIME 36 9717
TEST_TIME 37 9616
TEST_TIME 38 9529
TEST_TIME 39 9723
TEST_TIME 40 9786
TEST_TIME 41 9750
TEST_TIME 42 9634
TEST_TIME 43 9547
TEST_TIME 44 9689
TEST_TIME 45 9545
TEST_TIME 46 9583
TEST_TIME 47 9475
TEST_TIME 48 9426
TEST_TIME 49 9384
TEST_TIME 50 9407
TEST_TIME 51 9334
TEST_TIME 52 9400
TEST_TIME 53 9365
TEST_TIME 54 9323
TEST_TIME 55 9423
TEST_TIME 56 9453
TEST_TIME 57 9446
TEST_TIME 58 9520
TEST_TIME 59 9592
TEST_TIME 60 9622
TEST_TIME 61 9638
TEST_TIME 62 9659
TEST_TIME 63 9780
TEST_TIME 64 9656
TEST_TIME 65 9569
TEST_TIME 66 9542
TEST_TIME 67 9484
TEST_TIME 68 9434
TEST_TIME 69 9358
TEST_TIME 70 9278
TEST_TIME 71 9262
TEST_TIME 72 9186
TEST_TIME 73 9203
TEST_TIME 74 9184
TEST_TIME 75 9160
TEST_TIME 76 9176
TEST_TIME 77 9170
TEST_TIME 78 9196
TEST_TIME 79 9265
TEST_TIME 80 9235
TEST_TIME 81 9304
TEST_TIME 82 9281
TEST_TIME 83 9329
TEST_TIME 84 9352
TEST_TIME 85 9356
TEST_TIME 86 9391
TEST_TIME 87 9385
TEST_TIME 88 9311
TEST_TIME 89 9244
TEST_TIME 90 9214
TEST_TIME 91 9170
TEST_TIME 92 9173
TEST_TIME 93 9193
TEST_TIME 94 9189
TEST_TIME 95 9232
TEST_TIME 96 9239
TEST_TIME 97 9212
TEST_TIME 98 9215
TEST_TIME 99 9282
TEST_TIME 100 9304
TEST_TIME 101 9324
TEST_TIME 102 9335
TEST_TIME 103 9359
TEST_TIME 104 9353
TEST_TIME 105 9429
TEST_TIME 106 9484
TEST_TIME 107 9464
TEST_TIME 108 9422
TEST_TIME 109 9362
TEST_TIME 110 9341
TEST_TIME 111 9324
TEST_TIME 112 9363
TEST_TIME 113 9345
TEST_TIME 114 9328
TEST_TIME 115 9309
TEST_TIME 116 9278
TEST_TIME 117 9221
TEST_TIME 118 9186
TEST_TIME 119 9260
TEST_TIME 120 9203

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-12-2022 19:39:58 to 09-12-2022 19:40:01.

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 1540402 1540402 0 0.00 0 24049 6 0 0
doc_list_biud 1540401 1540401 0 0.00 0 25542 22 0 0
sp_add_invoice_to_stock 5345 5172 173 3.23 39 148178 16183 0 173
sp_cancel_adding_invoice 5502 5502 0 0.00 3 69411 783 0 0
sp_cancel_client_order 1869 1869 0 0.00 4 12412 350 0 0
sp_cancel_customer_reserve 545815 545815 0 0.00 0 25546 49 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 3 2800 67 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 3473 68 0 0
sp_cancel_supplier_invoice 6050 6043 7 0.11 1 17271 672 0 7
sp_cancel_supplier_order 404 404 0 0.00 7 905354 82741 0 0
sp_cancel_write_off 8995 8995 0 0.00 1 9316 104 0 0
sp_client_order 21165 21165 0 0.00 3 22648 329 0 0
sp_customer_reserve 471327 471327 0 0.00 0 108024 162 0 0
sp_fill_shopping_cart 32603 32603 0 0.00 1 11928 40 0 0
sp_fill_shopping_cart_clo_res 471323 471323 0 0.00 0 12907 15 0 0
sp_get_clo_for_invoice 5168 5168 0 0.00 0 26259 306 0 0
sp_kill_cost_storno 15627 15627 0 0.00 0 17008 52 0 0
sp_kill_qstorno_ret_qs2qd 1107072 1107072 0 0.00 0 25539 26 0 0
sp_kill_qty_storno 584278 584278 0 0.00 0 25542 58 0 0
sp_lock_selected_doc 21584 21584 0 0.00 0 7233 6 0 0
sp_make_cost_storno 29090 29090 0 0.00 0 17956 55 0 0
sp_multiply_rows_for_pdistr 29089 29089 0 0.00 0 11452 28 0 0
sp_multiply_rows_for_qdistr 21165 21165 0 0.00 0 13641 142 0 0
sp_pay_from_customer 7307 7307 0 0.00 4 12120 108 0 0
sp_pay_to_supplier 6132 6132 0 0.00 4 13036 132 0 0
sp_payment_common 13439 13439 0 0.00 1 12098 28 0 0
sp_qd_handle_on_cancel_clo 1869 1869 0 0.00 0 12403 324 0 0
sp_qd_handle_on_invoice_upd_sts 10669 10669 0 0.00 0 23275 294 0 0
sp_qd_handle_on_reserve_upd_sts 10483 10483 0 0.00 0 16472 58 0 0
sp_reserve_write_off 10483 10483 0 0.00 6 16571 172 0 0
sp_supplier_invoice 5689 5689 0 0.00 6 85304 3528 0 0
sp_supplier_order 5769 5769 0 0.00 5 34889 1153 0 0
srv_aggregate_perf_data 119 119 0 0.00 2684 128846 13045 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1796 142308 45799 0 0
srv_find_qd_qs_mism 1055065 1055065 0 0.00 0 24048 5 0 0
srv_make_invnt_saldo 582 582 0 0.00 31 66547 1001 0 0
srv_make_money_saldo 754 754 0 0.00 1 901 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 74714 353999 264411 0 0
t$perf-abend:sp_add_invoice_to_stock 173 173 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5172 5172 0 0.00 0 25961 161 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 6744 114 0 0
t$perf-norm:sp_cancel_client_order 1869 1869 0 0.00 0 5956 12 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 3122 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 553 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 1994 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 613 14 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 19494 1395 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 3443 10 0 0
t$perf-norm:sp_client_order 21165 21165 0 0.00 0 8395 15 0 0
t$perf-norm:sp_customer_reserve 1054 1054 0 0.00 0 4945 16 0 0
t$perf-norm:sp_pay_from_customer 7307 7307 0 0.00 0 5966 8 0 0
t$perf-norm:sp_pay_to_supplier 6132 6132 0 0.00 0 8727 10 0 0
t$perf-norm:sp_reserve_write_off 10483 10483 0 0.00 0 7390 12 0 0
t$perf-norm:sp_supplier_invoice 5689 5689 0 0.00 0 3251 14 0 0
t$perf-norm:sp_supplier_order 5769 5769 0 0.00 0 4407 12 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 470 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 994 21 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 3200 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2024 509 0 0
v_add_invoice_to_stock 10678 10332 346 3.24 0 6024 23 0 346
v_all_customers 37926 37926 0 0.00 0 77 0 0 0
v_all_suppliers 31566 31566 0 0.00 0 24 1 0 0
v_all_wares 42328 42328 0 0.00 0 80 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 535 7 0 0
v_cancel_client_order 3738 3738 0 0.00 0 4076 7 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 2778 8 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 2580 6 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 820 6 0 0
v_cancel_supplier_invoice 860 846 14 1.62 0 1729 19 0 14
v_cancel_supplier_order 808 808 0 0.00 0 1904 8 0 0
v_cancel_write_off 2000 2000 0 0.00 0 4938 15 0 0
v_max_id_clo_ord 5768 5768 0 0.00 0 3754 3 0 0
v_max_id_clo_res 1054 1054 0 0.00 0 2635 15 0 0
v_max_id_ord_sup 5670 5670 0 0.00 0 6033 6 0 0
v_max_non_paid_invoice 1787 1787 0 0.00 0 3157 5 0 0
v_max_non_paid_realizn 4126 4126 0 0.00 0 2385 5 0 0
v_min_id_clo_ord 5768 5768 0 0.00 0 2087 6 0 0
v_min_id_clo_res 1054 1054 0 0.00 0 5608 14 0 0
v_min_id_ord_sup 5670 5670 0 0.00 0 5740 6 0 0
v_min_non_paid_invoice 6132 6132 0 0.00 0 9189 17 0 0
v_min_non_paid_realizn 7307 7307 0 0.00 0 12091 23 0 0
v_our_firm 10762 10762 0 0.00 0 94 1 0 0
v_reserve_write_off 20966 20966 0 0.00 0 5299 7 0 0
x_lock_depdocs_on_canc_invoice 11122 11122 0 0.00 0 14343 115 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 2827 293 0 0
x_make_qstorno_1000_1200 5769 5769 0 0.00 1 34876 1083 0 0
x_make_qstorno_1200_2000 5689 5689 0 0.00 2 83407 3375 0 0
x_make_qstorno_2100_3300 471327 471327 0 0.00 0 107989 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2022 19:40:02 to 09-12-2022 19:40:06.

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 19570 8192000000 9883184064 10188820480 969304624 10.580 100 92 182 0 998893776 5584352 24004800
17:11:12 10499 8192000000 9934011008 10253332480 1019158560 11.064 100 90 180 0 1051085664 8400864 23526240
17:12:26 8272 8192000000 9960641968 10289737728 1049570352 11.357 100 80 160 0 1079373280 9365792 20437136
17:13:39 9040 8192000000 10023491888 10367741952 1105931232 11.894 100 82 164 0 1137179008 10541088 20706688
17:14:52 6289 8192000000 10065284656 10421276672 1153825008 12.345 100 71 141 1 1181938576 12063120 16050448
17:16:02 3672 8192000000 10107868832 10473967616 1199230752 12.769 100 78 156 0 1229926320 13638560 17057008
17:17:07 1625 8192000000 10149044432 10517962752 1235137936 13.101 100 88 173 3 1267381248 13650816 18592496
17:18:11 2014 8192000000 10175139104 10554781696 1262742784 13.355 100 80 155 0 1291372080 12419232 16210064
17:19:15 1007 8192000000 10203718064 10585391104 1287934048 13.585 100 90 175 1 1320426880 12460784 20032048
17:20:17 2609 8192000000 10195042624 10593132544 1297179456 13.670 100 55 107 0 1319758608 10854688 11724464
17:21:21 51577 8192000000 10209690880 10613809152 1309490144 13.781 100 47 93 1 1327145712 6650512 11005056
17:23:14 5146 8192000000 10212421760 10625679360 1319854224 13.875 100 27 53 0 1332150688 6490672 5805792
17:24:20 9685 8192000000 10210837296 10630758400 1323575808 13.909 100 22 44 1 1334951488 6566496 4809184
17:25:30 25477 8192000000 10211526384 10637537280 1332934144 13.994 100 16 32 0 1342077392 4882016 4261232
17:27:17 6199 8192000000 10226241360 10646851584 1335930880 14.021 100 30 58 1 1349010608 6352784 6726944
17:28:26 6395 8192000000 10196101744 10612576256 1296871072 13.667 98 23 46 0 1308031008 5774928 5385008
17:29:33 9507 8192000000 10176037456 10589843456 1280574592 13.518 99 16 32 1 1290147216 5697328 3875296
17:30:44 49157 8192000000 10107478576 10507444224 1091500256 11.757 86 14 28 0 1099119664 4621680 2997728
17:32:37 5874 8192000000 10125735616 10524712960 1218289584 12.946 98 23 46 0 1228887056 5063584 5533888
17:33:43 6837 8192000000 10144339344 10548375552 1255289792 13.287 100 26 50 3 1266607520 5247728 6070000
17:34:50 15189 8192000000 10167827168 10573320192 1273121216 13.450 100 23 46 1 1283345168 5354048 4869904
17:36:09 22591 8192000000 10173310176 10583257088 1286074128 13.568 100 20 39 1 1295563616 5498640 3990848
17:37:31 5351 8192000000 10192639968 10604470272 1301168992 13.706 100 25 50 0 1312902784 5855280 5878512
17:38:38 11240 8192000000 10236906208 10637103104 1327234496 13.942 100 48 95 1 1343676432 4568896 11873040
17:39:59 18235 8192000000 10255996464 10669662208 1346546784 14.116 100 38 74 1 1367691712 12783328 8361600
17:41:18 54534 8192000000 10253022944 10678063104 1355356576 14.196 100 35 68 0 1371576832 8637984 7582272
17:44:02 28964 8192000000 10278159168 10703167488 1373054176 14.354 100 48 94 2 1393446352 10041168 10351008
17:45:42 27902 8192000000 10285889184 10712686592 1371032944 14.336 99 36 73 0 1392219872 12632176 8554752
17:47:21 39142 8192000000 10318119824 10747412480 1404793952 14.638 100 49 98 1 1433842752 17285040 11763760
17:50:06 7604 8192000000 10323386896 10749030400 1406560992 14.653 100 51 102 2 1437954656 19218608 12175056
17:51:22 2686 8192000000 10344444080 10761342976 1412012752 14.702 100 71 141 0 1452780016 23845472 16921792
17:52:33 2314 8192000000 10338767200 10760876032 1411197248 14.695 100 67 133 1 1449396752 21779344 16420160
17:53:42 7488 8192000000 10342894336 10765086720 1402353584 14.616 99 57 114 0 1445863472 29251712 14258176
17:54:57 1791 8192000000 10337079456 10740936704 1379936768 14.416 99 84 169 0 1439863744 38226608 21700368
17:56:03 5814 8192000000 10303003088 10715643904 1364331184 14.276 100 56 111 0 1414863856 36675440 13857232
17:57:15 2067 8192000000 10338187744 10740695040 1379464272 14.412 100 83 167 1 1441691792 40308912 21918608
17:58:22 6345 8192000000 10303009504 10714714112 1365168640 14.284 100 39 76 0 1405336160 30258224 9909296
17:59:32 3588 8192000000 10283273072 10692190208 1332827232 13.993 99 46 91 0 1372663728 27720176 12116320
18:00:39 5480 8192000000 10286282016 10703585280 1352158944 14.167 100 38 75 1 1395172896 32837088 10176864
18:01:48 32936 8192000000 10260649952 10686025728 1322893408 13.903 98 25 50 0 1364457248 35180336 6383504
18:03:24 8177 8192000000 10257884640 10677424128 1304057904 13.732 97 47 95 0 1340154704 24350720 11746080
18:04:34 26704 8192000000 10247919904 10676322304 1295270304 13.652 97 22 44 0 1321891376 20877376 5743696
18:06:02 46388 8192000000 10264701488 10695041024 1348526128 14.134 100 19 37 0 1367169120 13969776 4673216
18:07:53 53296 8192000000 10265622016 10700267520 1337301216 14.033 99 16 31 1 1357447136 16556768 3589152
18:09:50 34007 8192000000 10266544064 10709090304 1335469328 14.017 98 17 35 0 1352309680 12841600 3998752
18:11:30 77593 8192000000 10267041280 10711552000 1354062528 14.184 99 17 34 0 1368495728 10321712 4111488
18:14:09 63017 8192000000 10257461856 10698444800 1333497904 13.999 98 31 59 2 1345971296 5973872 6499520
18:16:14 34518 8192000000 10313067280 10747293696 1382455648 14.438 100 56 112 0 1417218848 22230944 12532256
18:17:56 40274 8192000000 10288274384 10767044608 1383344368 14.446 100 11 22 0 1407567408 22018624 2204416
18:19:56 69758 8192000000 10305794528 10763132928 1404834848 14.638 100 19 38 0 1419898080 10924464 4138768
18:22:27 63874 8192000000 10291260880 10749784064 1376708048 14.387 98 18 34 1 1394058064 13377120 3972896
18:24:31 69701 8192000000 10305812304 10756747264 1396815024 14.567 100 21 42 1 1417040096 15400464 4824608
18:26:49 73598 8192000000 10293852160 10764869632 1390991104 14.515 99 25 50 1 1408668560 11951232 5726224
18:29:03 37451 8192000000 10299681456 10762276864 1403222672 14.624 100 10 17 0 1414230720 9234208 1773840
18:30:44 46157 8192000000 10311526288 10776207360 1404945104 14.639 100 13 26 1 1423673792 16036480 2692208
18:33:16 62679 8192000000 10310328864 10772770816 1391825776 14.522 99 15 28 0 1408369664 13625456 2918432
18:35:19 68209 8192000000 10315940224 10769797120 1355679040 14.199 96 20 40 1 1370950320 10784624 4486656
18:37:44 76613 8192000000 10301444416 10762645504 1350891920 14.156 96 20 38 0 1375080896 19940544 4248432
18:40:05 33217 8192000000 10309735456 10763911168 1382388256 14.438 99 22 44 0 1408509648 21316592 4804800
18:41:39 94109 8192000000 10279170272 10722013184 1284587776 13.555 92 27 52 0 1302138672 11839072 5711824
18:45:10 67397 8192000000 10227257184 10680209408 1302949936 13.722 94 6 12 0 1311470128 6874496 1645696
18:47:21 58923 8192000000 10251044096 10713620480 1331814800 13.984 97 8 16 0 1340364496 6727120 1822576
18:49:20 45021 8192000000 10239436144 10690084864 1320150752 13.878 97 14 28 0 1332089664 8542368 3396544
18:51:06 52724 8192000000 10230788944 10691698688 1302102176 13.714 96 11 22 0 1314513408 10047296 2363936
18:53:10 29026 8192000000 10252033984 10695278592 1328084832 13.950 98 35 70 1 1347537008 11551552 7900624
18:54:40 14247 8192000000 10251726160 10684600320 1332878512 13.993 99 38 76 0 1349527200 8479536 8169152
18:55:56 77157 8192000000 10279524544 10730921984 1357389536 14.214 99 19 38 1 1378491840 16209504 4892800
18:58:16 37776 8192000000 10285533248 10716880896 1327990576 13.949 97 46 92 0 1351868864 13232064 10646224
18:59:57 46105 8192000000 10273453152 10720477184 1363724448 14.271 100 31 63 1 1382021936 11522064 6775424
19:01:48 99621 8192000000 10293956256 10753716224 1380102704 14.417 100 10 20 0 1395641488 13154768 2384016
19:04:28 82538 8192000000 10265565312 10720997376 1344538800 14.098 97 17 34 0 1357700992 9168144 3994048
19:07:30 26588 8192000000 10285310400 10731798528 1380265040 14.419 100 32 64 1 1395202816 7818256 7119520
19:08:57 73943 8192000000 10288158720 10750844928 1394163200 14.543 100 13 26 0 1405594032 8511888 2918944
19:11:15 39648 8192000000 10282701392 10750750720 1389738480 14.504 100 8 15 0 1399186752 8063232 1385040
19:12:55 57529 8192000000 10309194240 10776285184 1405707456 14.646 100 14 28 1 1421188864 12001488 3479920
19:15:09 36713 8192000000 10315041296 10768637952 1400236352 14.597 100 22 44 0 1417680240 12395808 5048080
19:16:46 83820 8192000000 10307114480 10758983680 1377798016 14.397 98 16 32 0 1392968384 10999104 4171264
19:19:40 142274 8192000000 10301019232 10753515520 1327741600 13.947 95 13 26 0 1341445120 10995056 2708464
19:23:06 89548 8192000000 10299883888 10767581184 1356112096 14.202 98 12 24 0 1374525456 16101120 2312240
19:25:37 27136 8192000000 10295010528 10742546432 1386008192 14.470 100 19 38 0 1398165728 7999040 4158496
19:27:04 76100 8192000000 10286452224 10739757056 1367547056 14.305 99 34 68 0 1383188096 7554784 8086256
19:29:39 41522 8192000000 10256810640 10714718208 1349024976 14.139 99 21 42 0 1369116512 15770864 4320672
19:31:25 41928 8192000000 10238811600 10692452352 1330046752 13.968 98 15 30 0 1340448752 6704080 3697920
19:33:14 84115 8192000000 10255873872 10708230144 1291578080 13.619 94 13 26 0 1300944464 6277280 3089104
19:35:38 60732 8192000000 10267751920 10728763392 1369086496 14.319 100 14 28 0 1387473248 14841792 3544960
19:37:40 55696 8192000000 9228654160 9498689536 363573584 4.249 27 2 4 0 366227216 2400416 253216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-12-2022 19:40:08 to 09-12-2022 19:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 173 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-12-2022 19:40:24 to 09-12-2022 19:40:26.

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

09.12.22 19:40:28. 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
2022-12-09 19:40:30 No severe PSQL-related problems occured
09.12.22 19:40:31. End of report.