Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.07.2022 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16724364 kB
MemAvailable 30994344 kB
Buffers 0 kB
Cached 14968316 kB
SwapCached 2512 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-07-28 19:38:26
DTS_END                         2022-07-28 19:38:26.7890
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/48050
TRN_ID                          250160
ATT_ID                          721
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: 9657.56 20969 1158907
customer order: creation 179.70 347 21564
customer order: refuse 15.58 347 1869
order to supplier: creation 47.47 1283 5696
order to supplier: removal 3.69 98770 443
invoice (draft): creation 47.48 3429 5698
invoice (draft): removal 54.78 903 6574
invoice accept: apply 43.60 17448 5232
invoice accept: cancel 49.38 795 5925
customer reserve: creation 4053.04 172 486365
customer reserve: removal 4863.79 57 583655
realization accept: apply 87.43 172 10492
realization accept: cancel 79.83 116 9580
payment from customer: creation 60.84 109 7301
payment from customer: removal 5.73 86 688
payment to supplier: creation 49.29 126 5915
payment to supplier: removal 3.79 87 455
service: total inventory turnovers 4.53 1119 543
service: total monetary turnovers 6.74 36 809
service: aggregate perf. data 0.83 20796 99
service: refresh index statistics 0.03 273174 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-07-2022 19:39:28 to 28-07-2022 19:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4120
TEST_TIME 2 3454
TEST_TIME 3 2558
TEST_TIME 4 2601
TEST_TIME 5 2721
TEST_TIME 6 2955
TEST_TIME 7 3549
TEST_TIME 8 3498
TEST_TIME 9 4034
TEST_TIME 10 4326
TEST_TIME 11 4554
TEST_TIME 12 4646
TEST_TIME 13 5736
TEST_TIME 14 5737
TEST_TIME 15 6010
TEST_TIME 16 6623
TEST_TIME 17 6682
TEST_TIME 18 7201
TEST_TIME 19 7521
TEST_TIME 20 7896
TEST_TIME 21 7988
TEST_TIME 22 8545
TEST_TIME 23 9412
TEST_TIME 24 10155
TEST_TIME 25 10733
TEST_TIME 26 10846
TEST_TIME 27 10692
TEST_TIME 28 10568
TEST_TIME 29 10575
TEST_TIME 30 10470
TEST_TIME 31 10222
TEST_TIME 32 10264
TEST_TIME 33 10179
TEST_TIME 34 10138
TEST_TIME 35 10074
TEST_TIME 36 9919
TEST_TIME 37 10128
TEST_TIME 38 10193
TEST_TIME 39 10088
TEST_TIME 40 10038
TEST_TIME 41 9991
TEST_TIME 42 9859
TEST_TIME 43 9892
TEST_TIME 44 10016
TEST_TIME 45 9945
TEST_TIME 46 9937
TEST_TIME 47 9997
TEST_TIME 48 10015
TEST_TIME 49 10048
TEST_TIME 50 10018
TEST_TIME 51 9939
TEST_TIME 52 10064
TEST_TIME 53 10163
TEST_TIME 54 10199
TEST_TIME 55 10245
TEST_TIME 56 10156
TEST_TIME 57 10148
TEST_TIME 58 10017
TEST_TIME 59 10005
TEST_TIME 60 9935
TEST_TIME 61 9943
TEST_TIME 62 9915
TEST_TIME 63 9809
TEST_TIME 64 9796
TEST_TIME 65 9717
TEST_TIME 66 9680
TEST_TIME 67 9800
TEST_TIME 68 9778
TEST_TIME 69 9771
TEST_TIME 70 9850
TEST_TIME 71 9823
TEST_TIME 72 9891
TEST_TIME 73 9851
TEST_TIME 74 9845
TEST_TIME 75 10013
TEST_TIME 76 10022
TEST_TIME 77 9978
TEST_TIME 78 10047
TEST_TIME 79 9937
TEST_TIME 80 9928
TEST_TIME 81 9853
TEST_TIME 82 9851
TEST_TIME 83 9831
TEST_TIME 84 9797
TEST_TIME 85 9787
TEST_TIME 86 9772
TEST_TIME 87 9749
TEST_TIME 88 9697
TEST_TIME 89 9695
TEST_TIME 90 9677
TEST_TIME 91 9699
TEST_TIME 92 9693
TEST_TIME 93 9720
TEST_TIME 94 9704
TEST_TIME 95 9735
TEST_TIME 96 9766
TEST_TIME 97 9769
TEST_TIME 98 9798
TEST_TIME 99 9825
TEST_TIME 100 9855
TEST_TIME 101 9872
TEST_TIME 102 9850
TEST_TIME 103 9866
TEST_TIME 104 9820
TEST_TIME 105 9763
TEST_TIME 106 9734
TEST_TIME 107 9714
TEST_TIME 108 9710
TEST_TIME 109 9659
TEST_TIME 110 9656
TEST_TIME 111 9675
TEST_TIME 112 9689
TEST_TIME 113 9645
TEST_TIME 114 9677
TEST_TIME 115 9652
TEST_TIME 116 9637
TEST_TIME 117 9690
TEST_TIME 118 9671
TEST_TIME 119 9655
TEST_TIME 120 9659

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-07-2022 19:39:32 to 28-07-2022 19:39:35.

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 1607213 1607213 0 0.00 0 33514 6 0 0
doc_list_biud 1607212 1607212 0 0.00 0 40487 27 0 0
sp_add_invoice_to_stock 5335 5232 103 1.93 25 176334 17449 0 103
sp_cancel_adding_invoice 5925 5925 0 0.00 5 70535 796 0 0
sp_cancel_client_order 1869 1869 0 0.00 4 14581 348 0 0
sp_cancel_customer_reserve 583655 583655 0 0.00 0 18733 58 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 2 7795 86 0 0
sp_cancel_pay_to_supplier 455 455 0 0.00 3 5381 88 0 0
sp_cancel_supplier_invoice 6584 6574 10 0.15 1 18612 904 0 10
sp_cancel_supplier_order 443 443 0 0.00 10 1271420 98771 0 0
sp_cancel_write_off 9580 9580 0 0.00 1 10465 116 0 0
sp_client_order 21564 21564 0 0.00 3 24043 348 0 0
sp_customer_reserve 486365 486365 0 0.00 0 79285 172 0 0
sp_fill_shopping_cart 32954 32954 0 0.00 1 22467 40 0 0
sp_fill_shopping_cart_clo_res 486360 486360 0 0.00 0 23994 16 0 0
sp_get_clo_for_invoice 5227 5227 0 0.00 0 24874 338 0 0
sp_kill_cost_storno 16648 16648 0 0.00 0 7849 57 0 0
sp_kill_qstorno_ret_qs2qd 1183907 1183907 0 0.00 0 18729 32 0 0
sp_kill_qty_storno 623765 623765 0 0.00 0 40486 69 0 0
sp_lock_selected_doc 21719 21719 0 0.00 0 9185 6 0 0
sp_make_cost_storno 28935 28935 0 0.00 0 33505 56 0 0
sp_multiply_rows_for_pdistr 28935 28935 0 0.00 0 9974 29 0 0
sp_multiply_rows_for_qdistr 21564 21564 0 0.00 0 16069 171 0 0
sp_pay_from_customer 7301 7301 0 0.00 4 19793 109 0 0
sp_pay_to_supplier 5915 5915 0 0.00 5 12484 127 0 0
sp_payment_common 13216 13216 0 0.00 2 19584 31 0 0
sp_qd_handle_on_cancel_clo 1869 1869 0 0.00 0 14570 311 0 0
sp_qd_handle_on_invoice_upd_sts 11152 11152 0 0.00 0 40481 322 0 0
sp_qd_handle_on_reserve_upd_sts 10492 10492 0 0.00 0 6547 47 0 0
sp_reserve_write_off 10492 10492 0 0.00 6 19515 173 0 0
sp_supplier_invoice 5698 5698 0 0.00 6 95499 3429 0 0
sp_supplier_order 5696 5696 0 0.00 5 94407 1283 0 0
srv_aggregate_perf_data 99 99 0 0.00 2465 544965 20796 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1266 126685 45104 0 0
srv_find_qd_qs_mism 1108313 1108313 0 0.00 0 7814 5 0 0
srv_make_invnt_saldo 543 543 0 0.00 37 64580 1120 0 0
srv_make_money_saldo 809 809 0 0.00 0 9395 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 150460 418181 273175 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5232 5232 0 0.00 0 14239 172 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 7292 97 0 0
t$perf-norm:sp_cancel_client_order 1869 1869 0 0.00 0 5810 13 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 818 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 846 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 455 455 0 0.00 0 734 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 7401 28 0 0
t$perf-norm:sp_cancel_supplier_order 443 443 0 0.00 0 36773 1746 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 4097 12 0 0
t$perf-norm:sp_client_order 21564 21564 0 0.00 0 10602 18 0 0
t$perf-norm:sp_customer_reserve 1045 1045 0 0.00 0 2258 12 0 0
t$perf-norm:sp_pay_from_customer 7301 7301 0 0.00 0 31443 19 0 0
t$perf-norm:sp_pay_to_supplier 5915 5915 0 0.00 0 5380 13 0 0
t$perf-norm:sp_reserve_write_off 10492 10492 0 0.00 0 7498 15 0 0
t$perf-norm:sp_supplier_invoice 5698 5698 0 0.00 0 5523 18 0 0
t$perf-norm:sp_supplier_order 5696 5696 0 0.00 0 3755 19 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 407 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 543 543 0 0.00 0 792 17 0 0
t$perf-norm:srv_make_money_saldo 809 809 0 0.00 0 8403 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10660 10454 206 1.93 0 6966 19 0 206
v_all_customers 38866 38866 0 0.00 0 91 1 0 0
v_all_suppliers 31196 31196 0 0.00 0 24 1 0 0
v_all_wares 43128 43128 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 2897 19 0 0
v_cancel_client_order 3738 3738 0 0.00 0 4807 9 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 2863 9 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 2940 7 0 0
v_cancel_payment_to_supplier 910 910 0 0.00 0 2754 8 0 0
v_cancel_supplier_invoice 826 806 20 2.42 0 3415 18 0 20
v_cancel_supplier_order 886 886 0 0.00 0 998 7 0 0
v_cancel_write_off 2070 2070 0 0.00 0 2788 16 0 0
v_max_id_clo_ord 5695 5695 0 0.00 0 738 2 0 0
v_max_id_clo_res 1045 1045 0 0.00 0 2215 15 0 0
v_max_id_ord_sup 5694 5694 0 0.00 0 6203 4 0 0
v_max_non_paid_invoice 1706 1706 0 0.00 0 1608 4 0 0
v_max_non_paid_realizn 3906 3906 0 0.00 0 682 4 0 0
v_min_id_clo_ord 5695 5695 0 0.00 0 12249 16 0 0
v_min_id_clo_res 1045 1045 0 0.00 0 9793 28 0 0
v_min_id_ord_sup 5694 5694 0 0.00 0 5494 7 0 0
v_min_non_paid_invoice 5915 5915 0 0.00 0 11436 20 0 0
v_min_non_paid_realizn 7301 7301 0 0.00 0 11820 24 0 0
v_our_firm 11052 11052 0 0.00 0 28 1 0 0
v_reserve_write_off 20984 20984 0 0.00 0 4805 7 0 0
x_lock_depdocs_on_canc_invoice 12096 12096 0 0.00 0 27343 119 0 0
x_lock_depdocs_on_canc_sup_ord 443 443 0 0.00 0 10821 339 0 0
x_make_qstorno_1000_1200 5696 5696 0 0.00 1 94390 1203 0 0
x_make_qstorno_1200_2000 5698 5698 0 0.00 3 95489 3282 0 0
x_make_qstorno_2100_3300 486365 486365 0 0.00 0 79237 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-07-2022 19:39:36 to 28-07-2022 19:39:40.

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:30 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:31 19151 8192000000 9891000304 10202787840 976466112 10.650 100 84 166 0 1004273104 5362112 22444880
17:10:59 18560 8192000000 9944184336 10263986176 1027627600 11.146 100 96 192 0 1060504416 7940720 24936096
17:12:39 23373 8192000000 9987063696 10319446016 1067527184 11.528 100 88 176 0 1099243568 9910176 21806208
17:14:06 9746 8192000000 10043990576 10390675456 1126588304 12.089 100 86 172 0 1158824608 11831632 20404672
17:15:19 6763 8192000000 10079399264 10438795264 1168035328 12.478 100 74 148 0 1196907088 12593584 16278176
17:16:29 4190 8192000000 10121807728 10492608512 1219387024 12.956 100 79 158 0 1249785008 13121008 17276976
17:17:34 1381 8192000000 10165227056 10538606592 1248262560 13.222 100 92 184 1 1283498224 14275776 20959888
17:18:37 1039 8192000000 10199245184 10579824640 1284979840 13.558 100 92 183 0 1318271888 13047888 20244160
17:19:39 20438 8192000000 10194721184 10598563840 1303094464 13.723 100 43 85 0 1322925120 9891824 9938832
17:21:00 5460 8192000000 10201676032 10613407744 1312516704 13.809 100 28 57 0 1325073760 5907360 6649696
17:22:08 13528 8192000000 10207787280 10628706304 1325267024 13.924 100 20 41 0 1335687344 5523248 4897072
17:23:22 7320 8192000000 10214599600 10635710464 1329419120 13.962 100 22 44 1 1339775088 5831808 4524160
17:24:29 12638 8192000000 10225752608 10646933504 1336053504 14.022 100 23 47 0 1347257072 5968064 5235504
17:25:57 6020 8192000000 10234016176 10654273536 1339412240 14.052 100 22 43 0 1350196192 6415920 4368032
17:27:03 4171 8192000000 10228203136 10640982016 1315834704 13.839 98 27 55 0 1328494000 6553872 6105424
17:28:10 9260 8192000000 10211791616 10630115328 1299476128 13.690 98 18 36 0 1308729936 5256352 3997456
17:29:20 68692 8192000000 10163900400 10566463488 1201085824 12.786 93 37 75 0 1214841888 4850992 8905072
17:31:30 9539 8192000000 10136122352 10535702528 1203459088 12.808 96 23 46 0 1213761920 5482880 4819952
17:32:42 7170 8192000000 10151970032 10550652928 1244402720 13.187 99 26 52 0 1257051280 6763136 5885424
17:33:50 22028 8192000000 10173713760 10586906624 1280176976 13.515 100 30 58 3 1293013072 5723584 7112512
17:35:43 4938 8192000000 10188897808 10600099840 1292998192 13.632 100 21 42 1 1302760288 5089376 4672720
17:36:51 9808 8192000000 10194969504 10616348672 1310109888 13.787 100 23 46 0 1321744544 5990832 5643824
17:38:02 13128 8192000000 10221181728 10641211392 1328114800 13.950 100 12 25 0 1335639808 4637584 2887424
17:39:18 51049 8192000000 10257230464 10676850688 1352902688 14.174 100 43 84 1 1371630224 8296128 10431408
17:41:37 60701 8192000000 10283518816 10710376448 1373327360 14.357 100 33 65 1 1397467216 15884672 8255184
17:44:36 3150 8192000000 10334176160 10748485632 1401519744 14.609 100 66 127 1 1443471344 26243440 15708160
17:45:44 6325 8192000000 10331967824 10749726720 1401513552 14.608 100 56 111 0 1443155680 28261424 13380704
17:46:58 4575 8192000000 10351143760 10770386944 1410966528 14.693 100 60 116 0 1459217904 33781040 14470336
17:48:08 9159 8192000000 10354621744 10777309184 1416192240 14.739 100 52 104 0 1464980944 35881392 12907312
17:49:19 6984 8192000000 10365779872 10780454912 1414569984 14.725 100 66 128 1 1470015168 39990752 15454432
17:50:32 5847 8192000000 10349704032 10764685312 1388214448 14.490 99 64 126 0 1448769376 45117520 15437408
17:51:45 5527 8192000000 10347158032 10772848640 1399009664 14.586 99 59 116 0 1457173184 43201776 14961744
17:52:56 6068 8192000000 10359067440 10775494656 1402754992 14.620 100 69 138 2 1465991728 45352976 17883760
17:54:15 1499 8192000000 10359956624 10768080896 1397230912 14.570 100 79 157 0 1462132880 45627984 19273984
17:55:25 1649 8192000000 10347931200 10755383296 1382870848 14.442 100 83 168 1 1450752864 46569696 21312320
17:56:32 3612 8192000000 10318771120 10731597824 1351777296 14.163 99 70 140 2 1423922400 53617040 18528064
17:57:51 17492 8192000000 10316669072 10740596736 1360762960 14.244 100 49 98 0 1429236960 55233280 13240720
17:59:13 12094 8192000000 10296804128 10722078720 1339484304 14.053 100 34 70 0 1406321376 56901680 9935392
18:00:29 1264 8192000000 10339973600 10742775808 1349331376 14.141 100 85 168 1 1435531632 64009520 22190736
18:01:31 44465 8192000000 10269502608 10711138304 1303960112 13.731 97 28 56 1 1364611376 51878928 8772336
18:03:23 88368 8192000000 10265642016 10705137664 1317132240 13.851 97 15 26 0 1332933200 12026528 3774432
18:05:53 102506 8192000000 10283109936 10723008512 1356934352 14.210 99 17 33 1 1367336656 7450976 2951328
18:08:39 126685 8192000000 10303023568 10752946176 1366919920 14.299 99 27 54 1 1386775104 13682832 6172352
18:11:46 30869 8192000000 10302810704 10753187840 1394405216 14.545 100 23 45 0 1416282096 16671616 5205264
18:14:00 23614 8192000000 10309467040 10749140992 1388629792 14.494 100 46 90 1 1412775872 13997808 10148272
18:15:26 9584 8192000000 10300564752 10735185920 1374180784 14.364 99 56 108 3 1406873712 19346288 13346640
18:16:37 58273 8192000000 10289480608 10748002304 1386363392 14.473 100 14 28 0 1408790816 18505472 3921952
18:18:36 121736 8192000000 10295948096 10745606144 1363330704 14.267 98 20 40 0 1384029872 16201568 4497600
18:22:00 45775 8192000000 10289015904 10767949824 1394357040 14.545 100 20 38 0 1415130976 16040320 4733616
18:23:47 54343 8192000000 10304672544 10758848512 1377222720 14.392 99 23 46 0 1396890528 14694256 4973552
18:25:43 37932 8192000000 10290103808 10760564736 1384769120 14.459 99 17 34 1 1400978848 12188240 4021488
18:27:25 60690 8192000000 10298073776 10751000576 1364827280 14.281 98 17 34 1 1380281936 11794224 3660432
18:30:09 39772 8192000000 10309793152 10770649088 1368235696 14.311 98 15 30 0 1389849584 18620144 2993744
18:31:54 61568 8192000000 10276249968 10733539328 1314006944 13.822 95 9 18 0 1328734992 12906800 1821248
18:33:57 64758 8192000000 10295451712 10730754048 1336386784 14.025 97 28 57 0 1360447216 17369168 6691264
18:36:03 117866 8192000000 10263509072 10720378880 1332975904 13.994 97 21 42 0 1346712016 8960704 4775408
18:39:01 37167 8192000000 10272175568 10732355584 1375330688 14.375 100 24 48 0 1389600560 9014896 5254976
18:40:43 46886 8192000000 10244234032 10704384000 1326374320 13.934 98 5 10 0 1341872512 14792144 706048
18:42:30 64515 8192000000 10257126688 10711007232 1306626656 13.755 96 15 30 1 1320310480 10578944 3104880
18:44:35 30359 8192000000 10261473584 10712571904 1354133920 14.185 99 33 64 1 1371220832 9937760 7149152
18:46:26 60040 8192000000 10291497344 10731028480 1351358064 14.160 98 32 62 0 1368802336 10476864 6967408
18:48:29 63928 8192000000 10283064288 10739466240 1334090656 14.004 97 13 25 0 1345872496 9048192 2733648
18:51:32 49688 8192000000 10261349456 10703343616 1308711696 13.774 96 30 58 0 1328874272 13505696 6656880
18:53:21 62047 8192000000 10284378368 10743500800 1345377472 14.106 98 15 30 0 1374020352 25153744 3489136
18:55:24 8070 8192000000 10280617920 10711101440 1350697200 14.154 99 44 84 0 1367486240 6866608 9922432
18:56:32 88754 8192000000 10282725984 10731212800 1369829200 14.326 100 16 32 0 1391572592 18424464 3318928
18:59:01 25369 8192000000 10281655296 10723594240 1351627808 14.162 98 42 84 1 1369126960 8144464 9354688
19:00:45 69006 8192000000 10273518064 10732744704 1359029424 14.229 98 33 66 0 1377125568 10733376 7362768
19:02:54 40825 8192000000 10272463440 10729787392 1381030592 14.426 100 35 68 0 1395430928 6599744 7800592
19:04:36 48700 8192000000 10289372880 10741071872 1378118640 14.400 99 10 20 0 1388328368 7441488 2768240
19:06:32 56360 8192000000 10294407552 10749775872 1391185536 14.516 100 22 44 0 1408603216 12401344 5016336
19:08:43 68945 8192000000 10282334224 10737344512 1341859840 14.074 96 6 12 1 1353030240 10053056 1117344
19:10:54 42215 8192000000 10293040144 10746884096 1385761696 14.468 100 33 66 0 1402655392 9870832 7022864
19:12:38 9870 8192000000 10293768352 10725830656 1365605152 14.288 99 60 116 2 1389709648 11235840 12868656
19:13:53 76446 8192000000 10309886048 10757668864 1382804768 14.442 99 30 60 0 1405184672 15712960 6666944
19:16:13 73760 8192000000 10307018816 10771390464 1356099744 14.202 97 27 53 1 1379275632 17575840 5600048
19:18:56 55977 8192000000 10286217888 10744111104 1334770592 14.010 96 10 20 0 1350941536 14110080 2060864
19:20:52 37035 8192000000 10266108784 10709045248 1335762080 14.019 97 25 51 0 1352036720 10712848 5561792
19:22:35 114513 8192000000 10245556704 10689048576 1322466080 13.899 96 28 56 0 1339459248 9749840 7243328
19:25:46 33416 8192000000 10277302576 10727170048 1356994672 14.210 99 42 82 0 1373382720 7010368 9377680
19:28:47 54441 8192000000 10251548624 10697109504 1310693152 13.792 96 18 36 0 1324824848 9614192 4517504
19:30:44 68855 8192000000 10287180720 10730618880 1360626912 14.243 100 14 27 0 1371780704 8197248 2956544
19:32:56 37318 8192000000 10264092944 10705629184 1323343232 13.907 96 24 48 0 1338195600 9365616 5486752
19:34:34 29290 8192000000 10278790448 10725535744 1356913984 14.210 99 50 97 0 1379167200 11743344 10509872
19:36:04 35399 8192000000 10264790736 10710183936 1345329280 14.105 99 17 34 0 1357132176 8228272 3574624
19:37:40 66500 8192000000 9168689664 9426374656 321285696 3.773 24 6 12 0 329944304 7418272 1240336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-07-2022 19:39:42 to 28-07-2022 19:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-07-2022 19:39:58 to 28-07-2022 19:40:00.

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          250420
MON$OLDEST_ACTIVE               250421
MON$OLDEST_SNAPSHOT             250421
MON$NEXT_TRANSACTION            250421
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                       2028896
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.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:391: 
:392: mscs-test-161.budzdorov.ru    Thu Jul 28 19:38:48 2022
:393:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:394: 
:395: 
:396: mscs-test-161.budzdorov.ru    Thu Jul 28 19:38:48 2022
:397:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:398: 
:399: 
:400: mscs-test-161.budzdorov.ru    Thu Jul 28 19:38:48 2022
:401:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:402: 
:403: 
:404: mscs-test-161.budzdorov.ru    Thu Jul 28 19:38:48 2022
:405:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:406: 
--- end of diff output ---

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

28.07.22 19:40:01. 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-07-28 19:40:03 No severe PSQL-related problems occured
28.07.22 19:40:04. End of report.