Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.03.2023 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 15422852 kB
MemAvailable 30397020 kB
Buffers 0 kB
Cached 16267296 kB
SwapCached 3028 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-03-04 19:38:43
DTS_END                         2023-03-04 19:38:43.7340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36400
TRN_ID                          241279
ATT_ID                          726
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: 9446.85 20663 1133622
customer order: creation 173.18 351 20782
customer order: refuse 14.98 349 1798
order to supplier: creation 46.80 1146 5616
order to supplier: removal 3.63 93424 436
invoice (draft): creation 47.23 2867 5667
invoice (draft): removal 55.45 707 6654
invoice accept: apply 40.89 17725 4907
invoice accept: cancel 50.60 749 6072
customer reserve: creation 3779.18 176 453501
customer reserve: removal 4930.64 53 591677
realization accept: apply 84.46 177 10135
realization accept: cancel 88.62 100 10634
payment from customer: creation 60.65 95 7278
payment from customer: removal 5.43 67 651
payment to supplier: creation 49.38 113 5926
payment to supplier: removal 3.32 89 398
service: total inventory turnovers 4.83 1062 580
service: total monetary turnovers 6.77 47 812
service: aggregate perf. data 0.78 24003 94
service: refresh index statistics 0.03 269960 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-03-2023 19:39:35 to 04-03-2023 19:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6405
TEST_TIME 2 5130
TEST_TIME 3 5500
TEST_TIME 4 5661
TEST_TIME 5 5464
TEST_TIME 6 5214
TEST_TIME 7 5046
TEST_TIME 8 4862
TEST_TIME 9 4969
TEST_TIME 10 5491
TEST_TIME 11 5364
TEST_TIME 12 5450
TEST_TIME 13 5522
TEST_TIME 14 5768
TEST_TIME 15 5881
TEST_TIME 16 6483
TEST_TIME 17 6969
TEST_TIME 18 7927
TEST_TIME 19 8165
TEST_TIME 20 8462
TEST_TIME 21 9154
TEST_TIME 22 9988
TEST_TIME 23 10437
TEST_TIME 24 10298
TEST_TIME 25 10129
TEST_TIME 26 10017
TEST_TIME 27 9825
TEST_TIME 28 10026
TEST_TIME 29 9828
TEST_TIME 30 9755
TEST_TIME 31 9570
TEST_TIME 32 9534
TEST_TIME 33 9417
TEST_TIME 34 9373
TEST_TIME 35 9360
TEST_TIME 36 9355
TEST_TIME 37 9587
TEST_TIME 38 9787
TEST_TIME 39 9855
TEST_TIME 40 9812
TEST_TIME 41 9823
TEST_TIME 42 9887
TEST_TIME 43 9882
TEST_TIME 44 9871
TEST_TIME 45 9911
TEST_TIME 46 9882
TEST_TIME 47 10048
TEST_TIME 48 10011
TEST_TIME 49 9991
TEST_TIME 50 9956
TEST_TIME 51 9869
TEST_TIME 52 9902
TEST_TIME 53 9813
TEST_TIME 54 9852
TEST_TIME 55 9747
TEST_TIME 56 9660
TEST_TIME 57 9580
TEST_TIME 58 9495
TEST_TIME 59 9582
TEST_TIME 60 9599
TEST_TIME 61 9719
TEST_TIME 62 9627
TEST_TIME 63 9642
TEST_TIME 64 9768
TEST_TIME 65 9739
TEST_TIME 66 9822
TEST_TIME 67 9846
TEST_TIME 68 9855
TEST_TIME 69 9802
TEST_TIME 70 9750
TEST_TIME 71 9951
TEST_TIME 72 9940
TEST_TIME 73 9970
TEST_TIME 74 9880
TEST_TIME 75 9799
TEST_TIME 76 9751
TEST_TIME 77 9704
TEST_TIME 78 9720
TEST_TIME 79 9732
TEST_TIME 80 9692
TEST_TIME 81 9663
TEST_TIME 82 9638
TEST_TIME 83 9566
TEST_TIME 84 9503
TEST_TIME 85 9472
TEST_TIME 86 9493
TEST_TIME 87 9527
TEST_TIME 88 9552
TEST_TIME 89 9539
TEST_TIME 90 9570
TEST_TIME 91 9557
TEST_TIME 92 9624
TEST_TIME 93 9608
TEST_TIME 94 9660
TEST_TIME 95 9631
TEST_TIME 96 9678
TEST_TIME 97 9704
TEST_TIME 98 9725
TEST_TIME 99 9712
TEST_TIME 100 9765
TEST_TIME 101 9686
TEST_TIME 102 9663
TEST_TIME 103 9608
TEST_TIME 104 9597
TEST_TIME 105 9590
TEST_TIME 106 9560
TEST_TIME 107 9514
TEST_TIME 108 9507
TEST_TIME 109 9518
TEST_TIME 110 9475
TEST_TIME 111 9481
TEST_TIME 112 9438
TEST_TIME 113 9445
TEST_TIME 114 9445
TEST_TIME 115 9429
TEST_TIME 116 9461
TEST_TIME 117 9446
TEST_TIME 118 9408
TEST_TIME 119 9471
TEST_TIME 120 9452

Performance per minute, chart


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

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 1554323 1554323 0 0.00 0 14106 6 0 0
doc_list_biud 1554323 1554323 0 0.00 0 45057 26 0 0
sp_add_invoice_to_stock 5080 4907 173 3.40 31 184177 17725 0 173
sp_cancel_adding_invoice 6072 6072 0 0.00 4 76835 749 0 0
sp_cancel_client_order 1798 1798 0 0.00 4 20287 349 0 0
sp_cancel_customer_reserve 591677 591677 0 0.00 0 45062 54 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 3 9810 67 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 6161 89 0 0
sp_cancel_supplier_invoice 6668 6654 14 0.20 1 18695 707 0 14
sp_cancel_supplier_order 436 436 0 0.00 6 1134269 93424 0 0
sp_cancel_write_off 10634 10634 0 0.00 1 12256 101 0 0
sp_client_order 20782 20782 0 0.00 3 38496 352 0 0
sp_customer_reserve 453501 453501 0 0.00 0 29230 176 0 0
sp_fill_shopping_cart 32055 32055 0 0.00 1 11577 34 0 0
sp_fill_shopping_cart_clo_res 453493 453493 0 0.00 0 10870 12 0 0
sp_get_clo_for_invoice 4898 4898 0 0.00 0 45195 291 0 0
sp_kill_cost_storno 17755 17755 0 0.00 0 7860 43 0 0
sp_kill_qstorno_ret_qs2qd 1201078 1201078 0 0.00 0 45046 29 0 0
sp_kill_qty_storno 632304 632304 0 0.00 0 45056 63 0 0
sp_lock_selected_doc 20921 20921 0 0.00 0 10338 6 0 0
sp_make_cost_storno 28237 28237 0 0.00 0 14100 43 0 0
sp_multiply_rows_for_pdistr 28237 28237 0 0.00 0 7107 25 0 0
sp_multiply_rows_for_qdistr 20782 20782 0 0.00 0 38478 158 0 0
sp_pay_from_customer 7278 7278 0 0.00 3 9922 95 0 0
sp_pay_to_supplier 5926 5926 0 0.00 4 15869 113 0 0
sp_payment_common 13204 13204 0 0.00 1 8105 23 0 0
sp_qd_handle_on_cancel_clo 1798 1798 0 0.00 0 20280 311 0 0
sp_qd_handle_on_invoice_upd_sts 10970 10970 0 0.00 0 27760 306 0 0
sp_qd_handle_on_reserve_upd_sts 10135 10135 0 0.00 0 23148 73 0 0
sp_reserve_write_off 10135 10135 0 0.00 6 23162 177 0 0
sp_supplier_invoice 5667 5667 0 0.00 5 81208 2868 0 0
sp_supplier_order 5616 5616 0 0.00 5 20656 1147 0 0
srv_aggregate_perf_data 94 94 0 0.00 2742 623159 24004 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 856 163218 49090 0 0
srv_find_qd_qs_mism 1086193 1086193 0 0.00 0 12130 5 0 0
srv_make_invnt_saldo 580 580 0 0.00 42 41097 1063 0 0
srv_make_money_saldo 812 812 0 0.00 1 21254 47 0 0
srv_recalc_idx_stat 4 4 0 0.00 122205 458221 269960 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 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4907 4907 0 0.00 0 12348 164 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 2104 59 0 0
t$perf-norm:sp_cancel_client_order 1798 1798 0 0.00 0 7169 26 0 0
t$perf-norm:sp_cancel_customer_reserve 759 759 0 0.00 0 1287 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 4767 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 557 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 4827 24 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 28232 1528 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 7603 12 0 0
t$perf-norm:sp_client_order 20782 20782 0 0.00 0 7995 18 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 6156 12 0 0
t$perf-norm:sp_pay_from_customer 7278 7278 0 0.00 0 6773 10 0 0
t$perf-norm:sp_pay_to_supplier 5926 5926 0 0.00 0 7268 14 0 0
t$perf-norm:sp_reserve_write_off 10135 10135 0 0.00 0 6637 12 0 0
t$perf-norm:sp_supplier_invoice 5667 5667 0 0.00 0 6617 14 0 0
t$perf-norm:sp_supplier_order 5616 5616 0 0.00 0 5743 15 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 1 0 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 132 3 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 2431 22 0 0
t$perf-norm:srv_make_money_saldo 812 812 0 0.00 0 2635 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 112 30 0 0
v_add_invoice_to_stock 10142 9796 346 3.41 0 6390 22 0 346
v_all_customers 38248 38248 0 0.00 0 109 0 0 0
v_all_suppliers 31384 31384 0 0.00 0 19 1 0 0
v_all_wares 41564 41564 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 1107 8 0 0
v_cancel_client_order 3596 3596 0 0.00 0 4676 8 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 1145 5 0 0
v_cancel_customer_reserve 1518 1518 0 0.00 0 4042 6 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 3465 10 0 0
v_cancel_supplier_invoice 836 808 28 3.34 0 1881 17 0 28
v_cancel_supplier_order 872 872 0 0.00 0 2543 9 0 0
v_cancel_write_off 2072 2072 0 0.00 0 2538 16 0 0
v_max_id_clo_ord 5616 5616 0 0.00 0 458 1 0 0
v_max_id_clo_res 996 996 0 0.00 0 2189 13 0 0
v_max_id_ord_sup 5657 5657 0 0.00 0 4151 4 0 0
v_max_non_paid_invoice 1507 1507 0 0.00 0 2151 5 0 0
v_max_non_paid_realizn 3774 3774 0 0.00 0 2800 5 0 0
v_min_id_clo_ord 5616 5616 0 0.00 0 2294 5 0 0
v_min_id_clo_res 996 996 0 0.00 0 10458 49 0 0
v_min_id_ord_sup 5657 5657 0 0.00 0 5582 5 0 0
v_min_non_paid_invoice 5926 5926 0 0.00 0 6654 15 0 0
v_min_non_paid_realizn 7278 7278 0 0.00 0 6710 18 0 0
v_our_firm 10324 10324 0 0.00 0 80 1 0 0
v_reserve_write_off 20270 20270 0 0.00 0 6376 8 0 0
x_lock_depdocs_on_canc_invoice 12322 12322 0 0.00 0 19428 122 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 14296 312 0 0
x_make_qstorno_1000_1200 5616 5616 0 0.00 2 19837 1079 0 0
x_make_qstorno_1200_2000 5667 5667 0 0.00 3 81172 2747 0 0
x_make_qstorno_2100_3300 453501 453501 0 0.00 0 29228 161 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 04-03-2023 19:39:43 to 04-03-2023 19:39:48.

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:47 19 8192000000 8809221968 8824066048 13165424 0.160 4 3 4 0 13350672 78128 107120
17:09:48 12024 8192000000 9896133792 10202890240 982973248 10.713 100 92 184 0 1012990448 6020752 23996448
17:11:02 14888 8192000000 9940599424 10261012480 1020500240 11.077 100 88 176 0 1051537104 8512576 22524288
17:12:29 15492 8192000000 9982732240 10315251712 1069468352 11.547 100 81 162 0 1099476512 10122656 19885504
17:13:54 7863 8192000000 10021626672 10367094784 1108079520 11.914 100 78 153 2 1137146624 10808592 18258512
17:15:11 5010 8192000000 10074320304 10432282624 1157759376 12.382 100 82 164 1 1189660128 13086480 18814272
17:16:20 4974 8192000000 10098178272 10465308672 1195670240 12.736 100 70 140 0 1222921552 11699952 15551360
17:17:29 1870 8192000000 10147472336 10518048768 1232943824 13.081 100 89 177 2 1267549936 15071904 19534208
17:18:33 1095 8192000000 10177114016 10555572224 1263290384 13.360 100 83 164 2 1293714304 11940048 18483872
17:19:35 2081 8192000000 10196685344 10577469440 1293099664 13.632 100 89 175 0 1324544576 12196928 19247984
17:20:39 836 8192000000 10214537280 10599526400 1297196864 13.670 100 86 168 0 1328099920 11881744 19021312
17:21:40 5334 8192000000 10194739472 10601357312 1302612576 13.719 100 27 54 1 1315352880 6879152 5861152
17:22:47 5521 8192000000 10198750304 10611396608 1312747248 13.811 100 25 49 0 1324721392 7004064 4970080
17:23:56 64679 8192000000 10210624320 10627198976 1317262864 13.852 100 27 55 1 1328957952 5445424 6249664
17:26:01 7450 8192000000 10218006720 10636611584 1325340992 13.925 100 21 43 0 1335651520 5659056 4651472
17:27:09 8885 8192000000 10223356320 10640809984 1331663584 13.982 100 24 47 0 1343010560 6520928 4826048
17:28:18 10353 8192000000 10220920512 10631729152 1300923392 13.704 98 20 40 0 1312218304 6207600 5087312
17:29:29 10507 8192000000 10175818992 10590101504 1279725440 13.511 97 11 22 0 1287259136 5340720 2192976
17:30:40 7284 8192000000 10163771056 10570088448 1262494128 13.353 98 23 46 0 1274021104 5821632 5705344
17:31:50 5416 8192000000 10163188336 10571313152 1274519280 13.463 100 23 44 0 1284742240 5214320 5008640
17:32:58 12874 8192000000 10133419616 10528395264 1230787120 13.061 98 29 58 0 1242974320 5046032 7141168
17:34:11 16631 8192000000 10148474688 10549440512 1244611712 13.189 99 24 48 0 1256443648 6473456 5358480
17:35:30 5101 8192000000 10184774784 10585776128 1279923888 13.512 100 27 53 2 1295441456 9468112 6049456
17:36:38 6367 8192000000 10191443968 10599665664 1297276000 13.670 100 29 58 1 1310038848 6001168 6761680
17:37:47 72878 8192000000 10213365008 10626609152 1311455152 13.799 100 36 71 0 1325290544 5158432 8676960
17:40:02 26089 8192000000 10246598544 10658164736 1342913024 14.084 100 44 84 0 1361411008 9100000 9397984
17:41:35 6305 8192000000 10266747584 10678398976 1357198864 14.212 100 55 109 1 1383140768 14117344 11824560
17:42:45 47344 8192000000 10288168688 10711302144 1374988864 14.372 100 40 80 0 1400744352 16313536 9441952
17:44:37 8359 8192000000 10305094880 10722267136 1387825568 14.486 100 58 114 1 1421334880 20661104 12848208
17:46:02 43948 8192000000 10331037200 10749480960 1401183072 14.606 100 45 90 0 1439724336 27234944 11306320
17:47:58 11946 8192000000 10344185824 10767798272 1406291424 14.651 99 49 97 0 1449793456 31456368 12045664
17:49:15 4215 8192000000 10346037648 10764423168 1406142496 14.650 99 60 116 4 1449842640 29955872 13744272
17:50:23 4524 8192000000 10345955040 10760216576 1386167072 14.472 98 57 111 0 1436114912 36169472 13778368
17:51:33 2438 8192000000 10354003024 10766274560 1399232864 14.588 99 67 135 0 1458218016 41155552 17829600
17:52:42 3997 8192000000 10364714256 10771243008 1398582864 14.582 99 82 164 2 1465961008 47110912 20267232
17:53:51 856 8192000000 10367303920 10768752640 1393651600 14.538 100 86 173 0 1471598240 55412656 22533984
17:54:58 6513 8192000000 10341583152 10754949120 1375781856 14.379 100 60 120 0 1447163216 55129280 16252080
17:56:10 1525 8192000000 10344870800 10763100160 1380940448 14.425 99 63 123 1 1446243808 49704256 15599104
17:57:16 10938 8192000000 10326862528 10751680512 1381745632 14.432 100 35 70 1 1433183712 41867872 9570208
17:58:35 37403 8192000000 10293359424 10724548608 1320902512 13.885 97 29 56 3 1382848208 53364032 8581664
18:00:19 54388 8192000000 10260591696 10702884864 1282171920 13.533 95 17 34 0 1321281360 34730832 4378608
18:02:19 18438 8192000000 10247231408 10681221120 1332058672 13.986 99 21 43 0 1344231008 7488800 4683536
18:03:43 95911 8192000000 10256127760 10683355136 1323803408 13.911 98 27 54 3 1351158336 20975440 6379488
18:06:37 41201 8192000000 10264531328 10716471296 1356973952 14.210 100 17 33 0 1376908528 16465216 3469360
18:08:22 10011 8192000000 10277761776 10708168704 1361001184 14.246 100 46 89 2 1382957744 12138880 9817680
18:09:35 7764 8192000000 10303818112 10729177088 1378752448 14.405 100 69 135 1 1413826992 18678528 16396016
18:10:47 79575 8192000000 10292982800 10741026816 1347381040 14.124 97 19 38 0 1378487168 26231440 4874688
18:13:16 25312 8192000000 10288061504 10743873536 1368976080 14.318 99 19 37 0 1404540272 30776384 4787808
18:14:55 43335 8192000000 10300591488 10753556480 1385934688 14.470 100 14 28 0 1410429552 20974480 3520384
18:16:41 85084 8192000000 10294975664 10748977152 1382928960 14.443 100 17 34 0 1401021472 14083360 4009152
18:19:06 38123 8192000000 10292655328 10730905600 1357467200 14.215 98 33 66 0 1375209408 10440592 7301616
18:20:45 73288 8192000000 10297277760 10736304128 1349193872 14.140 97 15 30 0 1362136368 9727392 3215104
18:23:04 78374 8192000000 10318064656 10758762496 1398673104 14.583 100 26 52 1 1416012576 11553440 5786032
18:25:30 106549 8192000000 10313325312 10762612736 1365230848 14.284 97 11 22 0 1385413264 18057264 2125152
18:28:18 103489 8192000000 10322348832 10772008960 1407022768 14.657 100 24 48 1 1429880640 16913312 5944560
18:31:05 31234 8192000000 10300598688 10758389760 1386456480 14.474 99 19 37 0 1403474608 12033216 4984912
18:33:03 35065 8192000000 10297330832 10770075648 1401215472 14.606 100 6 12 0 1415687904 13106752 1365680
18:34:39 69930 8192000000 10291772192 10747367424 1371584112 14.341 98 21 42 0 1391501184 14946112 4970960
18:37:24 68793 8192000000 10284142416 10735357952 1344623392 14.099 97 17 34 0 1365630640 17103840 3903408
18:39:46 62707 8192000000 10287169392 10733977600 1344289984 14.096 97 18 37 1 1361461248 13508480 3662784
18:41:49 77151 8192000000 10280173216 10731220992 1342346256 14.079 98 20 40 0 1367274352 20416576 4511520
18:44:32 43633 8192000000 10273988032 10713128960 1284533312 13.554 94 27 54 0 1306543152 16127168 5882672
18:46:16 91851 8192000000 10260303040 10696749056 1312044192 13.805 97 34 66 0 1329439264 9912224 7482848
18:48:48 22090 8192000000 10279651888 10706853888 1344690016 14.100 100 41 82 0 1374099968 20177056 9232896
18:50:12 72052 8192000000 10275309232 10722856960 1312615968 13.810 97 14 29 0 1337355568 22016544 2723056
18:52:25 98134 8192000000 10255186640 10696003584 1345488912 14.107 99 34 69 1 1361436640 8419936 7527792
18:55:05 22650 8192000000 10280060064 10722996224 1359912720 14.237 99 29 59 0 1379430368 12853104 6664544
18:56:29 78284 8192000000 10254336064 10713104384 1309804016 13.784 96 22 44 0 1329856224 14981104 5071104
18:58:57 18608 8192000000 10267761248 10703052800 1352267728 14.168 100 42 85 0 1369115568 7579216 9268624
19:00:19 62866 8192000000 10269193488 10722615296 1369810560 14.325 100 13 26 0 1382736352 10048560 2877232
19:02:38 63848 8192000000 10260977104 10710421504 1319708096 13.874 96 33 66 1 1337144768 10425216 7011456
19:04:43 63456 8192000000 10275076192 10734366720 1375385136 14.375 100 10 20 0 1389063232 11251856 2426240
19:07:16 44016 8192000000 10297412576 10748776448 1374867296 14.371 99 36 72 1 1391416704 8546384 8003024
19:09:05 69673 8192000000 10306037360 10760196096 1386501024 14.475 99 19 38 0 1400584256 9540080 4543152
19:11:15 15605 8192000000 10320771360 10770280448 1406280272 14.651 100 45 90 0 1426025088 9090960 10653856
19:12:38 73416 8192000000 10330482112 10762121216 1380937312 14.425 98 30 55 0 1395578560 8633632 6007616
19:14:53 163218 8192000000 10312745680 10773471232 1307765792 13.766 93 5 10 0 1321555568 12818608 971168
19:18:37 132107 8192000000 10297208720 10758348800 1392078480 14.524 99 18 36 0 1408435888 12250560 4106848
19:21:50 29432 8192000000 10307115968 10764001280 1391704752 14.521 99 31 59 0 1412290544 14274064 6311728
19:23:27 52912 8192000000 10300535264 10770042880 1391441024 14.519 99 7 14 0 1403693792 10655408 1597360
19:25:20 84835 8192000000 10297968304 10759327744 1373873088 14.362 98 14 28 0 1392280096 14980096 3426912
19:27:45 53698 8192000000 10291700656 10750205952 1393901088 14.541 100 16 32 1 1411459840 13498448 4060304
19:29:40 26085 8192000000 10290963264 10740330496 1385994992 14.470 100 21 41 1 1397198208 7117488 4085728
19:31:10 61715 8192000000 10286208096 10749362176 1384960576 14.461 100 10 20 0 1397710864 10426880 2323408
19:33:16 53164 8192000000 10274131568 10728374272 1320480336 13.881 95 20 38 0 1335411536 10621072 4310128
19:35:15 62195 8192000000 10284534752 10729897984 1347832224 14.128 98 19 38 0 1364260960 12169760 4258976
19:37:21 37997 8192000000 10253131824 10700378112 1318271376 13.861 98 31 62 1 1341390928 16211104 6908448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-03-2023 19:39:49 to 04-03-2023 19:40:05.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-03-2023 19:40:06 to 04-03-2023 19:40:08.

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          237218
MON$OLDEST_ACTIVE               241413
MON$OLDEST_SNAPSHOT             241413
MON$NEXT_TRANSACTION            241413
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                       2009792
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:975: 
:976: mscs-test-161.budzdorov.ru    Sat Mar  4 19:38:51 2023
:977:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:978: 
:979: 
:980: mscs-test-161.budzdorov.ru    Sat Mar  4 19:38:51 2023
:981:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:982: 
:983: 
:984: mscs-test-161.budzdorov.ru    Sat Mar  4 19:38:51 2023
:985:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:986: 
:987: 
:988: mscs-test-161.budzdorov.ru    Sat Mar  4 19:38:51 2023
:989:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:990: 
:991: 
:992: mscs-test-161.budzdorov.ru    Sat Mar  4 19:38:51 2023
:993:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:994: 
--- end of diff output ---

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

04.03.23 19:40:09. 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-03-04 19:40:11 No severe PSQL-related problems occured
04.03.23 19:40:13. End of report.