Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.12.2022 01: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 15065072 kB
MemAvailable 30057028 kB
Buffers 0 kB
Cached 16617632 kB
SwapCached 3300 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-13 01:38:32
DTS_END                         2022-12-13 01:38:32.6310
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/54878
TRN_ID                          238817
ATT_ID                          701
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: 9527.61 18609 1143313
customer order: creation 173.61 331 20833
customer order: refuse 14.35 357 1722
order to supplier: creation 45.62 1241 5474
order to supplier: removal 3.68 91209 441
invoice (draft): creation 46.91 2915 5629
invoice (draft): removal 56.08 748 6729
invoice accept: apply 42.93 18031 5152
invoice accept: cancel 50.84 683 6101
customer reserve: creation 3922.54 182 470705
customer reserve: removal 4866.83 53 584020
realization accept: apply 84.28 173 10113
realization accept: cancel 89.33 99 10719
payment from customer: creation 59.93 96 7191
payment from customer: removal 5.91 89 709
payment to supplier: creation 48.93 123 5872
payment to supplier: removal 3.56 64 427
service: total inventory turnovers 4.72 1085 567
service: total monetary turnovers 6.73 33 807
service: aggregate perf. data 0.82 22309 98
service: refresh index statistics 0.03 232359 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-12-2022 01:39:18 to 13-12-2022 01:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5097
TEST_TIME 2 5452
TEST_TIME 3 5646
TEST_TIME 4 5705
TEST_TIME 5 5761
TEST_TIME 6 6033
TEST_TIME 7 6299
TEST_TIME 8 6630
TEST_TIME 9 7101
TEST_TIME 10 7035
TEST_TIME 11 7287
TEST_TIME 12 7614
TEST_TIME 13 7755
TEST_TIME 14 7778
TEST_TIME 15 7838
TEST_TIME 16 7808
TEST_TIME 17 7752
TEST_TIME 18 8252
TEST_TIME 19 8368
TEST_TIME 20 8714
TEST_TIME 21 8579
TEST_TIME 22 8653
TEST_TIME 23 8660
TEST_TIME 24 8725
TEST_TIME 25 8676
TEST_TIME 26 8878
TEST_TIME 27 9316
TEST_TIME 28 9452
TEST_TIME 29 9840
TEST_TIME 30 10318
TEST_TIME 31 10525
TEST_TIME 32 10528
TEST_TIME 33 10468
TEST_TIME 34 10515
TEST_TIME 35 10276
TEST_TIME 36 10068
TEST_TIME 37 9973
TEST_TIME 38 9924
TEST_TIME 39 9995
TEST_TIME 40 9868
TEST_TIME 41 9944
TEST_TIME 42 9841
TEST_TIME 43 9940
TEST_TIME 44 9827
TEST_TIME 45 9708
TEST_TIME 46 9607
TEST_TIME 47 9690
TEST_TIME 48 9792
TEST_TIME 49 9698
TEST_TIME 50 9655
TEST_TIME 51 9540
TEST_TIME 52 9519
TEST_TIME 53 9449
TEST_TIME 54 9421
TEST_TIME 55 9469
TEST_TIME 56 9393
TEST_TIME 57 9425
TEST_TIME 58 9510
TEST_TIME 59 9448
TEST_TIME 60 9579
TEST_TIME 61 9538
TEST_TIME 62 9574
TEST_TIME 63 9706
TEST_TIME 64 9703
TEST_TIME 65 9861
TEST_TIME 66 9898
TEST_TIME 67 9871
TEST_TIME 68 9938
TEST_TIME 69 9809
TEST_TIME 70 9875
TEST_TIME 71 9779
TEST_TIME 72 9779
TEST_TIME 73 9722
TEST_TIME 74 9640
TEST_TIME 75 9693
TEST_TIME 76 9646
TEST_TIME 77 9627
TEST_TIME 78 9530
TEST_TIME 79 9543
TEST_TIME 80 9507
TEST_TIME 81 9460
TEST_TIME 82 9406
TEST_TIME 83 9372
TEST_TIME 84 9371
TEST_TIME 85 9389
TEST_TIME 86 9345
TEST_TIME 87 9361
TEST_TIME 88 9361
TEST_TIME 89 9396
TEST_TIME 90 9486
TEST_TIME 91 9473
TEST_TIME 92 9500
TEST_TIME 93 9543
TEST_TIME 94 9607
TEST_TIME 95 9593
TEST_TIME 96 9598
TEST_TIME 97 9609
TEST_TIME 98 9590
TEST_TIME 99 9523
TEST_TIME 100 9575
TEST_TIME 101 9512
TEST_TIME 102 9518
TEST_TIME 103 9453
TEST_TIME 104 9439
TEST_TIME 105 9396
TEST_TIME 106 9425
TEST_TIME 107 9436
TEST_TIME 108 9410
TEST_TIME 109 9415
TEST_TIME 110 9409
TEST_TIME 111 9456
TEST_TIME 112 9453
TEST_TIME 113 9503
TEST_TIME 114 9507
TEST_TIME 115 9554
TEST_TIME 116 9574
TEST_TIME 117 9555
TEST_TIME 118 9521
TEST_TIME 119 9534
TEST_TIME 120 9534

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-12-2022 01:39:22 to 13-12-2022 01:39:26.

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 1579094 1579094 0 0.00 0 15268 6 0 0
doc_list_biud 1579094 1579094 0 0.00 0 43711 25 0 0
sp_add_invoice_to_stock 5277 5152 125 2.36 22 207682 18031 0 125
sp_cancel_adding_invoice 6101 6101 0 0.00 3 54579 684 0 0
sp_cancel_client_order 1722 1722 0 0.00 4 11259 357 0 0
sp_cancel_customer_reserve 584020 584020 0 0.00 0 43713 53 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 3 5120 89 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 2 5751 65 0 0
sp_cancel_supplier_invoice 6734 6729 5 0.07 1 22289 749 0 5
sp_cancel_supplier_order 441 441 0 0.00 11 908178 91210 0 0
sp_cancel_write_off 10719 10719 0 0.00 1 22326 100 0 0
sp_client_order 20833 20833 0 0.00 3 42891 331 0 0
sp_customer_reserve 470705 470705 0 0.00 0 119406 182 0 0
sp_fill_shopping_cart 31936 31936 0 0.00 1 15839 37 0 0
sp_fill_shopping_cart_clo_res 470682 470682 0 0.00 0 12068 12 0 0
sp_get_clo_for_invoice 5129 5129 0 0.00 0 20314 261 0 0
sp_kill_cost_storno 17956 17956 0 0.00 0 15266 50 0 0
sp_kill_qstorno_ret_qs2qd 1185929 1185929 0 0.00 0 43708 29 0 0
sp_kill_qty_storno 624974 624974 0 0.00 0 43711 63 0 0
sp_lock_selected_doc 21096 21096 0 0.00 0 6466 5 0 0
sp_make_cost_storno 28305 28305 0 0.00 0 14280 46 0 0
sp_multiply_rows_for_pdistr 28305 28305 0 0.00 0 7734 26 0 0
sp_multiply_rows_for_qdistr 20833 20833 0 0.00 0 42883 152 0 0
sp_pay_from_customer 7191 7191 0 0.00 3 12342 96 0 0
sp_pay_to_supplier 5872 5872 0 0.00 4 14290 123 0 0
sp_payment_common 13063 13063 0 0.00 1 9145 25 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 11253 320 0 0
sp_qd_handle_on_invoice_upd_sts 11230 11230 0 0.00 0 32252 310 0 0
sp_qd_handle_on_reserve_upd_sts 10113 10113 0 0.00 0 13996 65 0 0
sp_reserve_write_off 10113 10113 0 0.00 6 14067 173 0 0
sp_supplier_invoice 5629 5629 0 0.00 7 87489 2915 0 0
sp_supplier_order 5474 5474 0 0.00 4 50017 1242 0 0
srv_aggregate_perf_data 98 98 0 0.00 2372 297163 22310 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1682 130320 42764 0 0
srv_find_qd_qs_mism 1094940 1094940 0 0.00 0 13025 5 0 0
srv_make_invnt_saldo 567 567 0 0.00 48 36101 1086 0 0
srv_make_money_saldo 807 807 0 0.00 1 4081 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 80723 418929 232360 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 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 5152 5152 0 0.00 0 12412 189 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 3046 63 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 12504 20 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 3911 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 2518 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 2801 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1345 15 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 38687 1627 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 2788 9 0 0
t$perf-norm:sp_client_order 20833 20833 0 0.00 0 40364 22 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 4389 10 0 0
t$perf-norm:sp_pay_from_customer 7191 7191 0 0.00 0 39994 14 0 0
t$perf-norm:sp_pay_to_supplier 5872 5872 0 0.00 0 4445 12 0 0
t$perf-norm:sp_reserve_write_off 10113 10113 0 0.00 0 40453 17 0 0
t$perf-norm:sp_supplier_invoice 5629 5629 0 0.00 0 13367 23 0 0
t$perf-norm:sp_supplier_order 5474 5474 0 0.00 0 14514 20 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 184 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 196 5 0 0
t$perf-norm:srv_make_invnt_saldo 567 567 0 0.00 0 832 17 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 5263 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10504 10254 250 2.38 0 7337 18 0 250
v_all_customers 38246 38246 0 0.00 0 99 1 0 0
v_all_suppliers 30550 30550 0 0.00 0 30 1 0 0
v_all_wares 41666 41666 0 0.00 0 95 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 780 8 0 0
v_cancel_client_order 3444 3444 0 0.00 0 3161 6 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 2330 5 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 0 4251 7 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 1413 6 0 0
v_cancel_supplier_invoice 794 784 10 1.25 0 4294 21 0 10
v_cancel_supplier_order 882 882 0 0.00 0 4734 10 0 0
v_cancel_write_off 2064 2064 0 0.00 0 4155 16 0 0
v_max_id_clo_ord 5474 5474 0 0.00 0 444 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 2810 22 0 0
v_max_id_ord_sup 5629 5629 0 0.00 0 5610 6 0 0
v_max_non_paid_invoice 1700 1700 0 0.00 0 3187 5 0 0
v_max_non_paid_realizn 3731 3731 0 0.00 0 5219 7 0 0
v_min_id_clo_ord 5474 5474 0 0.00 0 4408 6 0 0
v_min_id_clo_res 983 983 0 0.00 0 4552 10 0 0
v_min_id_ord_sup 5629 5629 0 0.00 0 3761 4 0 0
v_min_non_paid_invoice 5872 5872 0 0.00 0 7715 19 0 0
v_min_non_paid_realizn 7191 7191 0 0.00 0 9142 17 0 0
v_our_firm 10340 10340 0 0.00 0 28 1 0 0
v_reserve_write_off 20226 20226 0 0.00 0 6887 8 0 0
x_lock_depdocs_on_canc_invoice 12438 12438 0 0.00 0 27294 115 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 11520 358 0 0
x_make_qstorno_1000_1200 5474 5474 0 0.00 1 49994 1162 0 0
x_make_qstorno_1200_2000 5629 5629 0 0.00 3 87350 2786 0 0
x_make_qstorno_2100_3300 470705 470705 0 0.00 0 119404 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-12-2022 01:39:27 to 13-12-2022 01:39:31.

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
23:08:36 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:37 26092 8192000000 9898566480 10207514624 981499312 10.699 100 87 174 0 1010593680 6265440 22828928
23:11:14 17434 8192000000 9956348576 10278068224 1035827328 11.225 100 90 180 0 1068062240 8972832 23262080
23:12:36 23931 8192000000 9990782448 10327486464 1075488240 11.604 100 87 174 0 1107813984 10616176 21709568
23:14:04 9784 8192000000 10035093632 10382528512 1117519520 12.004 100 82 164 0 1149143264 11615248 20008496
23:15:17 5238 8192000000 10076151440 10431868928 1158325888 12.388 100 82 164 0 1190682960 13362384 18994688
23:16:27 4316 8192000000 10104374848 10475814912 1200432464 12.780 100 69 136 0 1227594880 11934944 15227472
23:17:35 2397 8192000000 10154019104 10530820096 1242846880 13.172 100 82 163 0 1272717872 11278064 18592928
23:18:40 1727 8192000000 10177896464 10559295488 1268341504 13.406 100 73 146 0 1297609216 13155216 16112496
23:19:43 1033 8192000000 10211950064 10597679104 1301320224 13.707 100 90 179 1 1334799984 13318144 20161616
23:20:45 2813 8192000000 10208391392 10608087040 1308299216 13.771 100 54 108 0 1331262896 11135216 11828464
23:21:50 12865 8192000000 10197752432 10610991104 1311391088 13.799 100 16 33 1 1320328640 5445344 3492208
23:23:08 49662 8192000000 10206616800 10626830336 1317029920 13.850 100 15 30 0 1325960736 5416896 3513920
23:24:59 9588 8192000000 10214464656 10638200832 1326638448 13.937 100 25 49 1 1338823904 6204832 5980624
23:26:11 10603 8192000000 10228582080 10652155904 1335295344 14.015 100 21 42 1 1346335264 6338064 4701856
23:27:27 56792 8192000000 10231667328 10655854592 1330845424 13.975 99 38 74 2 1343960608 4691264 8423920
23:29:26 6844 8192000000 10215151648 10631749632 1304265888 13.734 98 24 49 0 1315959184 5859824 5833472
23:30:34 5548 8192000000 10177045440 10586955776 1261724384 13.346 98 31 61 0 1283851568 14493504 7633680
23:31:40 16819 8192000000 10153424032 10554724352 1229150720 13.046 97 29 58 0 1242109152 6082368 6876064
23:32:57 19490 8192000000 10116250880 10510749696 1109423264 11.927 89 20 41 0 1123899680 9547920 4928496
23:34:20 6672 8192000000 10162010544 10566434816 1257660816 13.309 99 26 50 0 1269350096 6291424 5397856
23:35:27 9839 8192000000 10175799392 10587832320 1286678400 13.574 100 24 47 1 1301635952 9614544 5343008
23:36:38 65599 8192000000 10198041616 10606063616 1302015616 13.714 100 37 74 0 1315699872 4893776 8790480
23:38:46 18140 8192000000 10228618784 10636013568 1326030288 13.931 100 47 94 0 1344974208 8195776 10748144
23:40:13 19383 8192000000 10253515488 10665111552 1342096864 14.076 100 47 92 1 1366017968 13962080 9959024
23:41:39 23428 8192000000 10265645072 10684538880 1360057200 14.238 100 43 87 0 1381797328 10955728 10784400
23:43:06 45921 8192000000 10275905440 10702307328 1372016928 14.345 100 26 52 0 1392902288 13996000 6889360
23:44:55 9014 8192000000 10290617168 10717650944 1381921040 14.434 100 49 97 0 1409549712 15054048 12574624
23:46:07 18345 8192000000 10299717328 10726621184 1388500160 14.492 100 36 72 0 1413540224 16761104 8278960
23:47:28 29723 8192000000 10300927232 10731196416 1377814256 14.397 99 39 78 0 1404014528 17654480 8545792
23:49:06 2401 8192000000 10316621568 10737496064 1394984400 14.550 100 69 133 2 1429024800 19011904 15028496
23:50:13 3896 8192000000 10331644048 10752798720 1404448272 14.635 100 81 157 0 1443644048 21102880 18092896
23:51:26 12085 8192000000 10324870000 10748690432 1397706672 14.575 100 59 118 0 1436156016 24667136 13782208
23:52:45 4203 8192000000 10309548272 10720710656 1373751728 14.361 99 63 124 0 1414274000 25541472 14980800
23:53:53 2744 8192000000 10330500128 10737209344 1382632832 14.440 100 76 150 1 1433816864 32839440 18344592
23:55:04 2311 8192000000 10321939776 10727821312 1368053920 14.310 100 79 156 0 1425798784 37493712 20251152
23:56:10 17012 8192000000 10311992080 10720292864 1360911744 14.246 98 55 109 2 1411580432 36355744 14312944
23:57:33 1681 8192000000 10338610688 10740297728 1376447776 14.385 100 78 158 0 1439269472 42952144 19869552
23:58:38 10391 8192000000 10340992976 10756358144 1393123584 14.534 100 55 110 3 1443958192 36799680 14034928
23:59:52 14622 8192000000 10347423280 10771755008 1398604720 14.583 100 38 74 0 1454417392 45165648 10647024
00:01:15 13225 8192000000 10339965920 10767929344 1389904576 14.505 100 38 76 1 1443578464 43745488 9928400
00:02:35 13756 8192000000 10320482672 10746572800 1374436768 14.367 100 37 74 0 1431898192 47185600 10275824
00:04:01 13754 8192000000 10308743632 10730827776 1345204176 14.104 98 33 66 2 1402941376 48043424 9693776
00:05:16 28336 8192000000 10311221264 10748633088 1355827696 14.200 100 27 54 1 1423307360 59589824 7889840
00:06:53 28939 8192000000 10293025760 10724605952 1324695984 13.919 98 19 38 0 1379026176 48880368 5449824
00:08:33 29745 8192000000 10254927008 10681266176 1313609536 13.819 98 30 62 1 1336043152 16068592 6365024
00:10:03 60650 8192000000 10272216144 10715013120 1363062544 14.265 100 10 20 1 1373990960 9009040 1919376
00:12:05 84361 8192000000 10283214624 10737135616 1360434352 14.241 99 16 31 0 1377812144 13953776 3424016
00:15:23 54391 8192000000 10303128064 10755862528 1381303392 14.428 100 23 46 2 1405017888 17941808 5772688
00:18:00 29365 8192000000 10303652848 10744201216 1369678160 14.324 99 44 86 0 1389520352 10276640 9565552
00:19:31 35130 8192000000 10297345136 10752831488 1379897440 14.416 99 37 70 0 1400719216 12612592 8209184
00:21:12 38648 8192000000 10297547088 10765565952 1393273664 14.535 100 12 24 0 1412092864 15610672 3208528
00:22:51 42813 8192000000 10313512128 10771390464 1404497248 14.635 100 10 20 0 1418720480 11805856 2417376
00:24:35 96219 8192000000 10308842496 10773573632 1400853184 14.603 100 14 28 0 1420704720 16541504 3310032
00:27:11 34453 8192000000 10307312368 10770653184 1400250336 14.597 100 26 52 1 1415066640 9502016 5314288
00:28:47 43217 8192000000 10304257136 10771943424 1408555008 14.671 100 13 26 0 1420334464 8996112 2783344
00:30:31 55804 8192000000 10296192176 10776494080 1405621456 14.645 100 9 18 0 1417677120 10114640 1941024
00:32:27 27403 8192000000 10283584528 10747039744 1374941824 14.371 99 20 40 0 1391206736 12026016 4238896
00:33:59 36405 8192000000 10272706592 10722852864 1328654752 13.955 96 28 57 1 1343136224 7641232 6840240
00:35:40 50970 8192000000 10273797360 10723938304 1353293728 14.177 99 16 32 0 1367452240 10178048 3980464
00:37:40 27302 8192000000 10282115872 10730336256 1358892096 14.227 99 42 84 0 1380708816 12536448 9280272
00:39:14 40093 8192000000 10286102800 10725400576 1334454336 14.007 97 32 64 0 1351126352 9925248 6746768
00:40:59 63402 8192000000 10270644128 10725494784 1289354944 13.598 94 11 22 0 1311952224 20016256 2581024
00:43:11 130319 8192000000 10273682112 10730622976 1263032592 13.358 92 13 26 0 1282345088 16051840 3260656
00:46:23 91327 8192000000 10292287152 10732584960 1361681408 14.252 99 35 68 0 1385586672 16053840 7851424
00:49:09 18243 8192000000 10289715456 10741665792 1370824384 14.334 100 44 86 1 1397347296 17238416 9284496
00:50:28 10226 8192000000 10263914592 10716606464 1363886832 14.272 100 36 66 0 1378777776 7341216 7549728
00:51:38 47391 8192000000 10263380640 10723188736 1365808672 14.289 100 11 22 1 1379017232 10563184 2645376
00:53:26 51371 8192000000 10262166304 10720239616 1355474640 14.197 99 8 16 0 1364206224 7074448 1657136
00:55:25 54845 8192000000 10240246192 10699587584 1345853632 14.110 99 8 16 0 1358988800 11492912 1642256
00:57:22 66870 8192000000 10237774896 10705166336 1322489216 13.899 98 16 32 0 1339245712 13120816 3635680
00:59:45 96016 8192000000 10242693920 10701565952 1335772880 14.019 99 10 20 0 1348924416 10888016 2263520
01:03:01 64171 8192000000 10251536512 10713190400 1316121248 13.842 97 8 16 0 1330452144 12761536 1569360
01:05:06 35218 8192000000 10277100640 10720366592 1354599504 14.189 99 34 68 0 1377564336 15122192 7842640
01:06:44 61737 8192000000 10283073712 10735095808 1370556560 14.332 100 12 24 0 1393552816 19909616 3086640
01:08:46 62335 8192000000 10295874688 10740350976 1355881696 14.200 98 34 68 1 1374235824 10528000 7826128
01:10:48 42173 8192000000 10300116480 10749624320 1378850512 14.406 100 22 44 0 1392620976 9662352 4108112
01:12:37 90913 8192000000 10315197920 10767765504 1375587888 14.377 99 24 48 0 1394816208 13249120 5979200
01:15:09 69202 8192000000 10297443296 10754445312 1348990560 14.138 97 10 20 0 1364641040 13745872 1904608
01:17:24 104162 8192000000 10285129472 10756710400 1360629264 14.243 98 9 18 0 1376622080 14063680 1929136
01:20:59 89407 8192000000 10299389968 10765312000 1398800272 14.584 100 14 28 0 1411854128 9726640 3327216
01:23:28 64096 8192000000 10302185312 10768187392 1404888640 14.639 100 16 32 0 1422570656 13054528 4627488
01:26:56 64349 8192000000 10295054368 10750726144 1334461808 14.007 95 20 40 0 1345796656 7076160 4258688
01:29:07 75699 8192000000 10279227664 10733694976 1341802720 14.074 97 21 42 0 1363094896 16259136 5033040
01:31:28 43439 8192000000 10284736992 10740711424 1355144832 14.194 98 28 57 0 1373997936 12541824 6311280
01:33:20 124839 8192000000 10274321056 10728202240 1270397952 13.425 92 16 32 1 1289142320 15095328 3649040
01:36:25 20581 8192000000 10289336016 10728779776 1335361216 14.016 97 34 68 0 1351334016 8752304 7220496
01:37:50 16944 8192000000 10283216880 10722922496 1346891440 14.119 98 43 85 1 1367273568 11324864 9057264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-12-2022 01:39:33 to 13-12-2022 01:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 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 13-12-2022 01:39:49 to 13-12-2022 01:39:51.

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          234245
MON$OLDEST_ACTIVE               238945
MON$OLDEST_SNAPSHOT             238945
MON$NEXT_TRANSACTION            238945
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                       2024928
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.33647 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33647 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33647 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33647 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 ---
:205: 
:206: mscs-test-161.budzdorov.ru    Tue Dec 13 01:38:34 2022
:207:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:208: 
:209: 
:210: mscs-test-161.budzdorov.ru    Tue Dec 13 01:38:35 2022
:211:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:212: 
:213: 
:214: mscs-test-161.budzdorov.ru    Tue Dec 13 01:38:42 2022
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:216: 
:217: 
:218: mscs-test-161.budzdorov.ru    Tue Dec 13 01:38:44 2022
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:220: 
--- end of diff output ---

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

13.12.22 01:39:52. 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-13 01:39:55 No severe PSQL-related problems occured
13.12.22 01:39:56. End of report.