Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.01.2023 22: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 15127912 kB
MemAvailable 30422540 kB
Buffers 0 kB
Cached 16561740 kB
SwapCached 2708 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-18 22:38:32
DTS_END                         2023-01-18 22:38:32.8210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53024
TRN_ID                          245703
ATT_ID                          677
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: 9374.45 21106 1124934
customer order: creation 180.08 330 21610
customer order: refuse 14.85 317 1782
order to supplier: creation 47.84 1252 5741
order to supplier: removal 3.43 103368 411
invoice (draft): creation 48.33 3240 5800
invoice (draft): removal 52.23 835 6267
invoice accept: apply 43.91 17808 5269
invoice accept: cancel 46.98 860 5637
customer reserve: creation 4024.28 177 482914
customer reserve: removal 4606.47 60 552776
realization accept: apply 88.22 180 10586
realization accept: cancel 84.33 106 10120
payment from customer: creation 60.58 119 7270
payment from customer: removal 5.87 82 704
payment to supplier: creation 50.50 149 6060
payment to supplier: removal 3.88 72 466
service: total inventory turnovers 4.93 1099 591
service: total monetary turnovers 6.90 31 828
service: aggregate perf. data 0.82 27114 98
service: refresh index statistics 0.03 264927 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-01-2023 22:39:21 to 18-01-2023 22:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4099
TEST_TIME 2 5536
TEST_TIME 3 4482
TEST_TIME 4 3995
TEST_TIME 5 5053
TEST_TIME 6 5312
TEST_TIME 7 5706
TEST_TIME 8 5487
TEST_TIME 9 5620
TEST_TIME 10 6084
TEST_TIME 11 5903
TEST_TIME 12 5866
TEST_TIME 13 6014
TEST_TIME 14 6028
TEST_TIME 15 6448
TEST_TIME 16 6834
TEST_TIME 17 7472
TEST_TIME 18 7521
TEST_TIME 19 7844
TEST_TIME 20 7760
TEST_TIME 21 8383
TEST_TIME 22 8179
TEST_TIME 23 8350
TEST_TIME 24 8568
TEST_TIME 25 8481
TEST_TIME 26 8979
TEST_TIME 27 8849
TEST_TIME 28 8973
TEST_TIME 29 9140
TEST_TIME 30 9848
TEST_TIME 31 10338
TEST_TIME 32 10668
TEST_TIME 33 10506
TEST_TIME 34 10490
TEST_TIME 35 10767
TEST_TIME 36 10720
TEST_TIME 37 10544
TEST_TIME 38 10348
TEST_TIME 39 10219
TEST_TIME 40 10092
TEST_TIME 41 10260
TEST_TIME 42 10112
TEST_TIME 43 9981
TEST_TIME 44 9875
TEST_TIME 45 9754
TEST_TIME 46 9667
TEST_TIME 47 9730
TEST_TIME 48 9765
TEST_TIME 49 9691
TEST_TIME 50 9551
TEST_TIME 51 9427
TEST_TIME 52 9414
TEST_TIME 53 9386
TEST_TIME 54 9430
TEST_TIME 55 9372
TEST_TIME 56 9383
TEST_TIME 57 9301
TEST_TIME 58 9293
TEST_TIME 59 9272
TEST_TIME 60 9258
TEST_TIME 61 9288
TEST_TIME 62 9249
TEST_TIME 63 9328
TEST_TIME 64 9323
TEST_TIME 65 9370
TEST_TIME 66 9328
TEST_TIME 67 9335
TEST_TIME 68 9422
TEST_TIME 69 9422
TEST_TIME 70 9436
TEST_TIME 71 9518
TEST_TIME 72 9508
TEST_TIME 73 9564
TEST_TIME 74 9601
TEST_TIME 75 9588
TEST_TIME 76 9476
TEST_TIME 77 9497
TEST_TIME 78 9423
TEST_TIME 79 9427
TEST_TIME 80 9414
TEST_TIME 81 9373
TEST_TIME 82 9313
TEST_TIME 83 9267
TEST_TIME 84 9320
TEST_TIME 85 9273
TEST_TIME 86 9277
TEST_TIME 87 9245
TEST_TIME 88 9329
TEST_TIME 89 9372
TEST_TIME 90 9364
TEST_TIME 91 9397
TEST_TIME 92 9428
TEST_TIME 93 9400
TEST_TIME 94 9516
TEST_TIME 95 9477
TEST_TIME 96 9524
TEST_TIME 97 9567
TEST_TIME 98 9553
TEST_TIME 99 9469
TEST_TIME 100 9421
TEST_TIME 101 9396
TEST_TIME 102 9367
TEST_TIME 103 9330
TEST_TIME 104 9268
TEST_TIME 105 9258
TEST_TIME 106 9223
TEST_TIME 107 9237
TEST_TIME 108 9252
TEST_TIME 109 9285
TEST_TIME 110 9249
TEST_TIME 111 9218
TEST_TIME 112 9256
TEST_TIME 113 9315
TEST_TIME 114 9307
TEST_TIME 115 9342
TEST_TIME 116 9304
TEST_TIME 117 9306
TEST_TIME 118 9314
TEST_TIME 119 9325
TEST_TIME 120 9375

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-01-2023 22:39:24 to 18-01-2023 22:39:28.

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 1572499 1572499 0 0.00 0 30567 6 0 0
doc_list_biud 1572499 1572499 0 0.00 0 36841 27 0 0
sp_add_invoice_to_stock 5337 5269 68 1.27 17 183559 17809 0 68
sp_cancel_adding_invoice 5637 5637 0 0.00 4 72207 861 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 8622 317 0 0
sp_cancel_customer_reserve 552776 552776 0 0.00 0 36844 60 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 2 8115 82 0 0
sp_cancel_pay_to_supplier 466 466 0 0.00 2 1839 73 0 0
sp_cancel_supplier_invoice 6272 6267 5 0.07 2 24312 835 0 5
sp_cancel_supplier_order 411 411 0 0.00 5 1067008 103369 0 0
sp_cancel_write_off 10120 10120 0 0.00 1 7574 107 0 0
sp_client_order 21610 21610 0 0.00 3 37389 330 0 0
sp_customer_reserve 482914 482914 0 0.00 0 52656 178 0 0
sp_fill_shopping_cart 33142 33142 0 0.00 1 43073 43 0 0
sp_fill_shopping_cart_clo_res 482912 482912 0 0.00 0 14166 13 0 0
sp_get_clo_for_invoice 5267 5267 0 0.00 0 23778 294 0 0
sp_kill_cost_storno 16927 16927 0 0.00 0 7841 56 0 0
sp_kill_qstorno_ret_qs2qd 1122350 1122350 0 0.00 0 36832 33 0 0
sp_kill_qty_storno 592846 592846 0 0.00 0 36841 71 0 0
sp_lock_selected_doc 21848 21848 0 0.00 0 8879 4 0 0
sp_make_cost_storno 29183 29183 0 0.00 0 30566 60 0 0
sp_multiply_rows_for_pdistr 29183 29183 0 0.00 0 10775 28 0 0
sp_multiply_rows_for_qdistr 21610 21610 0 0.00 0 11767 146 0 0
sp_pay_from_customer 7270 7270 0 0.00 4 14219 120 0 0
sp_pay_to_supplier 6060 6060 0 0.00 5 35357 149 0 0
sp_payment_common 13330 13330 0 0.00 1 14172 31 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 8611 288 0 0
sp_qd_handle_on_invoice_upd_sts 10904 10904 0 0.00 0 18924 338 0 0
sp_qd_handle_on_reserve_upd_sts 10586 10586 0 0.00 0 17304 54 0 0
sp_reserve_write_off 10586 10586 0 0.00 6 17390 181 0 0
sp_supplier_invoice 5800 5800 0 0.00 6 92817 3240 0 0
sp_supplier_order 5741 5741 0 0.00 5 33853 1252 0 0
srv_aggregate_perf_data 98 98 0 0.00 2325 429267 27114 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1674 120608 41243 0 0
srv_find_qd_qs_mism 1075428 1075428 0 0.00 0 20891 5 0 0
srv_make_invnt_saldo 591 591 0 0.00 38 37299 1099 0 0
srv_make_money_saldo 828 828 0 0.00 1 687 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 93030 462706 264927 0 0
t$perf-abend:sp_add_invoice_to_stock 68 68 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5269 5269 0 0.00 0 8226 173 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 3241 120 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 2722 14 0 0
t$perf-norm:sp_cancel_customer_reserve 739 739 0 0.00 0 675 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 368 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 466 466 0 0.00 0 2822 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 882 15 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 20221 1608 0 0
t$perf-norm:sp_cancel_write_off 1054 1054 0 0.00 0 2035 6 0 0
t$perf-norm:sp_client_order 21610 21610 0 0.00 0 19936 17 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 540 3 0 0
t$perf-norm:sp_pay_from_customer 7270 7270 0 0.00 0 6368 10 0 0
t$perf-norm:sp_pay_to_supplier 6060 6060 0 0.00 0 3385 9 0 0
t$perf-norm:sp_reserve_write_off 10586 10586 0 0.00 0 7550 13 0 0
t$perf-norm:sp_supplier_invoice 5800 5800 0 0.00 0 3079 16 0 0
t$perf-norm:sp_supplier_order 5741 5741 0 0.00 0 5375 16 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 345 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 119 3 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 2046 30 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 2583 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 11 5 0 0
v_add_invoice_to_stock 10670 10534 136 1.27 0 33693 22 0 136
v_all_customers 38850 38850 0 0.00 0 22 1 0 0
v_all_suppliers 31292 31292 0 0.00 0 30 1 0 0
v_all_wares 43220 43220 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 2013 10 0 0
v_cancel_client_order 3564 3564 0 0.00 0 4777 7 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 2670 8 0 0
v_cancel_customer_reserve 1478 1478 0 0.00 0 5244 14 0 0
v_cancel_payment_to_supplier 932 932 0 0.00 0 1709 9 0 0
v_cancel_supplier_invoice 844 834 10 1.18 0 5698 35 0 10
v_cancel_supplier_order 822 822 0 0.00 0 2554 8 0 0
v_cancel_write_off 2108 2108 0 0.00 0 3250 16 0 0
v_max_id_clo_ord 5739 5739 0 0.00 0 2691 2 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 2884 12 0 0
v_max_id_ord_sup 5793 5793 0 0.00 0 6750 10 0 0
v_max_non_paid_invoice 1946 1946 0 0.00 0 220 2 0 0
v_max_non_paid_realizn 4029 4029 0 0.00 0 3519 6 0 0
v_min_id_clo_ord 5739 5739 0 0.00 0 3751 7 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 2639 5 0 0
v_min_id_ord_sup 5793 5793 0 0.00 0 4718 6 0 0
v_min_non_paid_invoice 6060 6060 0 0.00 0 12855 20 0 0
v_min_non_paid_realizn 7270 7270 0 0.00 0 8472 25 0 0
v_our_firm 10852 10852 0 0.00 0 19 1 0 0
v_reserve_write_off 21172 21172 0 0.00 0 5478 8 0 0
x_lock_depdocs_on_canc_invoice 11487 11487 0 0.00 0 17323 129 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 6319 323 0 0
x_make_qstorno_1000_1200 5741 5741 0 0.00 1 33848 1175 0 0
x_make_qstorno_1200_2000 5800 5800 0 0.00 3 92807 3072 0 0
x_make_qstorno_2100_3300 482914 482914 0 0.00 0 52653 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-01-2023 22:39:29 to 18-01-2023 22: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
20:08:36 7 8192000000 8801751456 8813903872 7908416 0.096 2 2 3 0 8049504 66144 74944
20:09:37 25027 8192000000 9896870704 10204905472 981881456 10.703 100 87 172 0 1010171328 5928704 22361168
20:11:07 18811 8192000000 9936775104 10256941056 1019309984 11.065 100 85 170 1 1049546000 8443632 21792384
20:12:33 14166 8192000000 9971865280 10300239872 1053815184 11.397 100 84 168 0 1083923664 9555344 20553136
20:14:00 8662 8192000000 10011545792 10353303552 1098152080 11.820 100 83 164 1 1129012432 11184384 19675968
20:15:11 5937 8192000000 10055596464 10407505920 1141876768 12.233 100 87 174 0 1175549888 13649072 20024048
20:16:21 3513 8192000000 10100182704 10464444416 1189142976 12.675 100 77 154 1 1218713280 12134016 17436288
20:17:27 2540 8192000000 10139177136 10508312576 1230916448 13.063 100 79 154 0 1260246064 12167008 17162608
20:18:30 2035 8192000000 10184064416 10563485696 1268782384 13.410 100 82 164 0 1299991824 13404816 17804624
20:19:34 1666 8192000000 10212181264 10595303424 1298763648 13.684 100 86 168 1 1330565472 12878608 18923216
20:20:37 13776 8192000000 10209439456 10607919104 1308527360 13.773 100 56 111 1 1328576080 7520944 12527776
20:21:52 6817 8192000000 10196486496 10611310592 1310411712 13.790 100 27 54 0 1324116640 7689312 6015616
20:22:59 8986 8192000000 10203645008 10622603264 1319069328 13.868 100 14 28 0 1328657632 6590512 2997792
20:24:08 9950 8192000000 10214445648 10632200192 1329284064 13.961 100 16 33 1 1338558000 5706768 3567168
20:25:22 51933 8192000000 10237550320 10647408640 1337407440 14.034 100 45 89 2 1352874656 5261168 10206048
20:27:14 3559 8192000000 10225862800 10647543808 1337339536 14.033 100 31 62 0 1351229696 6577328 7312832
20:28:19 8313 8192000000 10217349664 10635177984 1283365616 13.544 96 20 41 0 1298359440 10198144 4795680
20:29:30 8647 8192000000 10198679184 10612809728 1286151904 13.569 98 8 16 0 1293272688 5445296 1675488
20:30:39 16484 8192000000 10168056464 10583015424 1255945728 13.293 96 12 22 0 1263258608 5106624 2206256
20:31:55 8812 8192000000 10123163744 10510213120 1169829184 12.495 93 23 45 0 1179977488 5285728 4862576
20:33:08 9131 8192000000 10147837296 10541490176 1235902544 13.108 99 27 52 0 1247812320 5866544 6043232
20:34:21 18081 8192000000 10166012256 10565300224 1257807712 13.310 98 40 79 0 1272931024 5729744 9393568
20:35:40 3452 8192000000 10173790208 10579673088 1284563792 13.555 100 56 111 0 1305486816 7682912 13240112
20:36:43 8883 8192000000 10196803936 10603565056 1298127968 13.678 100 31 62 0 1311062416 5708592 7225856
20:37:53 10063 8192000000 10204150960 10613075968 1306286496 13.752 100 23 46 1 1316583136 4768624 5528016
20:39:11 3180 8192000000 10243149472 10650484736 1336648416 14.027 100 48 93 1 1352716224 5594048 10473760
20:40:18 37295 8192000000 10259740400 10675843072 1349103280 14.139 100 36 70 0 1364053664 6857360 8093024
20:42:01 23539 8192000000 10270562960 10691092480 1364789424 14.280 100 40 80 0 1382876720 9298496 8788800
20:43:30 18367 8192000000 10285650992 10708918272 1376154256 14.382 100 38 74 0 1395688720 10725600 8808864
20:44:55 6051 8192000000 10294106192 10722881536 1386160912 14.472 100 42 83 0 1411267744 15401568 9705264
20:46:09 15098 8192000000 10297358992 10726297600 1391923472 14.523 100 46 92 1 1415218032 12503536 10791024
20:47:29 6281 8192000000 10322461552 10744520704 1398909808 14.585 100 56 113 0 1431112832 18621968 13581056
20:48:44 7911 8192000000 10307819488 10742153216 1389953024 14.505 99 37 72 0 1418281520 19890544 8437952
20:50:06 6270 8192000000 10321289264 10743042048 1391476928 14.519 99 56 113 0 1428927312 24098336 13352048
20:51:20 11732 8192000000 10328900272 10759876608 1402273936 14.615 100 44 86 1 1440657776 28031680 10352160
20:52:37 4895 8192000000 10340863488 10759045120 1406455728 14.652 100 61 121 0 1453174704 31564000 15154976
20:53:47 2798 8192000000 10327045104 10731298816 1364655328 14.279 98 91 182 2 1420673856 33121232 22897296
20:54:55 1698 8192000000 10343029040 10752802816 1391580768 14.520 100 86 170 0 1446595008 34408656 20605584
20:56:03 9843 8192000000 10331904464 10752286720 1394682528 14.548 100 66 129 0 1441402464 30761664 15958272
20:57:20 5508 8192000000 10329302880 10747248640 1387995616 14.488 100 56 112 1 1439414448 37879680 13539152
20:58:32 3756 8192000000 10339658432 10747789312 1376479584 14.385 100 79 158 2 1439386208 43247680 19658944
20:59:40 6832 8192000000 10313316464 10726117376 1362110624 14.256 99 49 95 0 1409282096 35113744 12057728
21:00:57 1668 8192000000 10334203808 10737979392 1375161168 14.373 100 76 151 0 1437899664 43837120 18901376
21:02:07 12482 8192000000 10340205200 10759094272 1390692368 14.512 100 46 90 1 1442922464 40195024 12035072
21:03:27 9508 8192000000 10360589616 10772664320 1384819840 14.460 99 67 135 1 1450489040 48108080 17561120
21:04:40 9083 8192000000 10350976480 10778116096 1396386944 14.563 100 53 106 0 1465528880 54533328 14608608
21:05:57 17523 8192000000 10346199984 10785189888 1393314384 14.535 100 29 58 0 1452826544 51001616 8510544
21:07:41 25518 8192000000 10349647296 10786643968 1386803280 14.477 100 29 58 0 1454547072 59178528 8565264
21:09:10 48902 8192000000 10300158608 10766503936 1357258192 14.213 98 16 32 1 1404672912 43095408 4319312
21:11:15 38314 8192000000 10327071136 10754142208 1388149280 14.489 100 60 116 0 1410447504 9294944 13003280
21:12:55 109854 8192000000 10262414304 10701783040 1281845440 13.530 93 9 18 0 1302776432 19135120 1795872
21:15:45 44385 8192000000 10294643088 10738139136 1381372736 14.429 100 41 83 0 1403268272 12453424 9442112
21:17:33 9860 8192000000 10312864672 10750455808 1389425232 14.501 100 55 107 0 1410571296 8714128 12431936
21:18:48 23156 8192000000 10316559104 10766905344 1396409408 14.563 100 30 58 0 1423411072 19908512 7093152
21:20:16 76533 8192000000 10301208256 10772385792 1367076576 14.301 98 4 9 0 1390202576 22162256 963744
21:22:34 33056 8192000000 10301498736 10761900032 1399185808 14.588 100 18 37 0 1414237200 10955840 4095552
21:24:12 56947 8192000000 10312527744 10767605760 1401262512 14.606 100 25 49 1 1422850816 15729232 5859072
21:26:22 96654 8192000000 10282316032 10745708544 1375217872 14.374 99 16 31 0 1392569232 14019776 3331584
21:29:10 65814 8192000000 10294743616 10754555904 1380407840 14.420 99 20 39 1 1391018544 5999392 4611312
21:31:17 67454 8192000000 10294158560 10763268096 1387053328 14.480 99 18 36 0 1404062576 12902512 4106736
21:33:28 5885 8192000000 10308016528 10762600448 1400728784 14.601 100 25 50 0 1418376880 12013184 5634912
21:34:34 55659 8192000000 10297197232 10759839744 1394996816 14.550 99 11 22 0 1409268880 11827152 2444912
21:37:07 69197 8192000000 10292142128 10755084288 1357800640 14.218 97 15 29 1 1372618704 11837104 2980960
21:39:41 110321 8192000000 10291050560 10762559488 1356141856 14.203 97 12 24 0 1379123344 20221440 2760048
21:42:34 66510 8192000000 10272963648 10729467904 1311663952 13.801 94 19 38 0 1324639616 9189456 3786208
21:44:40 27922 8192000000 10277027968 10723868672 1342879408 14.083 97 37 73 0 1360428096 9141328 8407360
21:46:09 30798 8192000000 10262283072 10719469568 1337710944 14.037 98 28 56 0 1361513696 17526544 6276208
21:47:41 107233 8192000000 10255873632 10708807680 1265228944 13.378 92 10 20 0 1279860736 12492096 2139696
21:50:48 120608 8192000000 10283047488 10740563968 1329090176 13.959 97 16 32 0 1350364976 17545744 3729056
21:53:52 86545 8192000000 10278077792 10721685504 1371485184 14.340 100 44 88 1 1390171232 9417744 9268304
21:56:32 5537 8192000000 10280504272 10715906048 1366131776 14.292 100 42 82 0 1383472448 8143536 9197136
21:58:25 34953 8192000000 10247798608 10701131776 1352341696 14.169 100 19 38 0 1363701888 7287232 4072960
22:00:50 65196 8192000000 10256070544 10707763200 1359607280 14.234 100 28 56 0 1376700048 10020464 7072304
22:03:49 44849 8192000000 10259561632 10709577728 1324179504 13.915 97 18 36 0 1343875968 15114672 4581792
22:05:35 48412 8192000000 10279627296 10727686144 1360933280 14.246 99 33 66 0 1384266384 15697808 7635296
22:07:24 49334 8192000000 10258339216 10703630336 1310702464 13.792 96 23 46 0 1326818608 10566736 5549408
22:09:15 95781 8192000000 10276956832 10733056000 1341707184 14.073 98 13 26 0 1356218080 11922960 2587936
22:11:56 55990 8192000000 10268117376 10728071168 1349950032 14.147 99 22 44 0 1367983472 13246464 4786976
22:13:53 55093 8192000000 10297362432 10743472128 1367905776 14.308 99 16 33 1 1385446272 14082192 3458304
22:16:01 104284 8192000000 10288590576 10748887040 1372523280 14.350 99 18 36 0 1392616384 15542064 4551040
22:19:06 47635 8192000000 10275945136 10736721920 1379825808 14.415 100 16 32 0 1392019616 8702768 3491040
22:20:54 45951 8192000000 10274271072 10733420544 1354124320 14.185 97 13 26 0 1363490304 6574912 2791072
22:22:40 59096 8192000000 10276277152 10737496064 1360303152 14.240 98 9 18 0 1374953824 12343168 2307504
22:24:40 101965 8192000000 10275499408 10744717312 1350753072 14.154 98 12 24 0 1366016992 12843488 2420432
22:27:22 53659 8192000000 10270236720 10724249600 1318521520 13.863 95 14 28 0 1333271232 11793152 2956560
22:29:30 54873 8192000000 10280519392 10734743552 1332248160 13.987 96 16 33 1 1350280224 14259856 3772208
22:31:30 43269 8192000000 10287119904 10734944256 1353441488 14.178 98 22 40 0 1366729456 8989072 4298896
22:33:38 54503 8192000000 10272227904 10714513408 1309884832 13.785 95 18 36 0 1322828080 8666704 4276544
22:35:37 18038 8192000000 10286655952 10724954112 1365959248 14.291 100 44 88 0 1387366112 11970784 9436080
22:36:57 43895 8192000000 10292245024 10729947136 1345671616 14.109 98 35 70 0 1370978208 17469760 7836832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 68 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-01-2023 22:39:51 to 18-01-2023 22: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          245123
MON$OLDEST_ACTIVE               245813
MON$OLDEST_SNAPSHOT             245813
MON$NEXT_TRANSACTION            245813
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                       2058656
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.33654 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33654 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33654 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33654 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 ---
:100: 
:101: mscs-test-161.budzdorov.ru    Wed Jan 18 22:38:49 2023
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Wed Jan 18 22:38:49 2023
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:107: 
:108: 
:109: mscs-test-161.budzdorov.ru    Wed Jan 18 22:38:49 2023
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:111: 
--- end of diff output ---

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

18.01.23 22:39:55. 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-18 22:39:57 No severe PSQL-related problems occured
18.01.23 22:39:58. End of report.