Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.10.2022 22: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 15561500 kB
MemAvailable 30570808 kB
Buffers 0 kB
Cached 16159920 kB
SwapCached 2080 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-10-02 22:38:28
DTS_END                         2022-10-02 22:38:28.2910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57670
TRN_ID                          238510
ATT_ID                          685
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: 9511.01 21393 1141321
customer order: creation 175.12 335 21014
customer order: refuse 14.73 353 1767
order to supplier: creation 46.43 1271 5571
order to supplier: removal 3.53 100093 423
invoice (draft): creation 45.43 2920 5452
invoice (draft): removal 55.19 776 6623
invoice accept: apply 41.49 17045 4979
invoice accept: cancel 50.28 764 6033
customer reserve: creation 3843.88 169 461266
customer reserve: removal 4935.02 56 592203
realization accept: apply 83.63 163 10035
realization accept: cancel 87.68 106 10522
payment from customer: creation 58.92 94 7070
payment from customer: removal 5.43 92 651
payment to supplier: creation 48.77 124 5852
payment to supplier: removal 3.41 81 409
service: total inventory turnovers 4.58 976 550
service: total monetary turnovers 6.56 38 787
service: aggregate perf. data 0.92 15130 110
service: refresh index statistics 0.03 287271 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-10-2022 22:39:47 to 02-10-2022 22:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3094
TEST_TIME 2 5131
TEST_TIME 3 4958
TEST_TIME 4 4827
TEST_TIME 5 4653
TEST_TIME 6 4476
TEST_TIME 7 4626
TEST_TIME 8 4984
TEST_TIME 9 5045
TEST_TIME 10 5431
TEST_TIME 11 5828
TEST_TIME 12 6273
TEST_TIME 13 6733
TEST_TIME 14 6914
TEST_TIME 15 7585
TEST_TIME 16 8178
TEST_TIME 17 8284
TEST_TIME 18 8545
TEST_TIME 19 8413
TEST_TIME 20 8873
TEST_TIME 21 8979
TEST_TIME 22 9253
TEST_TIME 23 9318
TEST_TIME 24 9656
TEST_TIME 25 10073
TEST_TIME 26 10472
TEST_TIME 27 11132
TEST_TIME 28 10918
TEST_TIME 29 10841
TEST_TIME 30 10742
TEST_TIME 31 10467
TEST_TIME 32 10580
TEST_TIME 33 10660
TEST_TIME 34 10642
TEST_TIME 35 10439
TEST_TIME 36 10422
TEST_TIME 37 10367
TEST_TIME 38 10333
TEST_TIME 39 10248
TEST_TIME 40 10127
TEST_TIME 41 10020
TEST_TIME 42 9940
TEST_TIME 43 9901
TEST_TIME 44 9759
TEST_TIME 45 10095
TEST_TIME 46 9966
TEST_TIME 47 9885
TEST_TIME 48 9962
TEST_TIME 49 10012
TEST_TIME 50 9940
TEST_TIME 51 10008
TEST_TIME 52 9941
TEST_TIME 53 9946
TEST_TIME 54 10054
TEST_TIME 55 10106
TEST_TIME 56 10064
TEST_TIME 57 10061
TEST_TIME 58 10055
TEST_TIME 59 10058
TEST_TIME 60 10102
TEST_TIME 61 10023
TEST_TIME 62 9903
TEST_TIME 63 9938
TEST_TIME 64 9854
TEST_TIME 65 9851
TEST_TIME 66 9787
TEST_TIME 67 9780
TEST_TIME 68 9717
TEST_TIME 69 9626
TEST_TIME 70 9587
TEST_TIME 71 9546
TEST_TIME 72 9677
TEST_TIME 73 9644
TEST_TIME 74 9702
TEST_TIME 75 9732
TEST_TIME 76 9822
TEST_TIME 77 9805
TEST_TIME 78 9810
TEST_TIME 79 9991
TEST_TIME 80 9917
TEST_TIME 81 9956
TEST_TIME 82 10019
TEST_TIME 83 9992
TEST_TIME 84 9948
TEST_TIME 85 9915
TEST_TIME 86 9923
TEST_TIME 87 9856
TEST_TIME 88 9819
TEST_TIME 89 9792
TEST_TIME 90 9741
TEST_TIME 91 9709
TEST_TIME 92 9649
TEST_TIME 93 9664
TEST_TIME 94 9649
TEST_TIME 95 9645
TEST_TIME 96 9634
TEST_TIME 97 9624
TEST_TIME 98 9613
TEST_TIME 99 9696
TEST_TIME 100 9718
TEST_TIME 101 9706
TEST_TIME 102 9712
TEST_TIME 103 9812
TEST_TIME 104 9782
TEST_TIME 105 9755
TEST_TIME 106 9797
TEST_TIME 107 9822
TEST_TIME 108 9746
TEST_TIME 109 9671
TEST_TIME 110 9643
TEST_TIME 111 9661
TEST_TIME 112 9624
TEST_TIME 113 9583
TEST_TIME 114 9545
TEST_TIME 115 9517
TEST_TIME 116 9479
TEST_TIME 117 9490
TEST_TIME 118 9548
TEST_TIME 119 9522
TEST_TIME 120 9514

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-10-2022 22:39:51 to 02-10-2022 22:39:54.

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 1569767 1569767 0 0.00 0 17260 6 0 0
doc_list_biud 1569766 1569766 0 0.00 0 35984 27 0 0
sp_add_invoice_to_stock 5113 4979 134 2.62 23 184446 17045 0 134
sp_cancel_adding_invoice 6033 6033 0 0.00 3 49668 764 0 0
sp_cancel_client_order 1767 1767 0 0.00 4 16216 353 0 0
sp_cancel_customer_reserve 592203 592203 0 0.00 0 27165 57 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 2 5440 93 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 3 5594 81 0 0
sp_cancel_supplier_invoice 6638 6623 15 0.22 1 37407 776 0 15
sp_cancel_supplier_order 423 423 0 0.00 7 938543 100093 0 0
sp_cancel_write_off 10522 10522 0 0.00 1 12259 107 0 0
sp_client_order 21014 21014 0 0.00 3 21295 336 0 0
sp_customer_reserve 461266 461266 0 0.00 0 125190 169 0 0
sp_fill_shopping_cart 32037 32037 0 0.00 1 9928 33 0 0
sp_fill_shopping_cart_clo_res 461253 461253 0 0.00 0 23358 14 0 0
sp_get_clo_for_invoice 4964 4964 0 0.00 0 25966 299 0 0
sp_kill_cost_storno 17615 17615 0 0.00 0 12256 52 0 0
sp_kill_qstorno_ret_qs2qd 1201974 1201974 0 0.00 0 35978 31 0 0
sp_kill_qty_storno 632569 632569 0 0.00 0 35982 66 0 0
sp_lock_selected_doc 20904 20904 0 0.00 0 7213 5 0 0
sp_make_cost_storno 27921 27921 0 0.00 0 17136 50 0 0
sp_multiply_rows_for_pdistr 27921 27921 0 0.00 0 6828 24 0 0
sp_multiply_rows_for_qdistr 21014 21014 0 0.00 0 21261 159 0 0
sp_pay_from_customer 7070 7070 0 0.00 4 9964 94 0 0
sp_pay_to_supplier 5852 5852 0 0.00 5 10772 124 0 0
sp_payment_common 12922 12922 0 0.00 1 7459 25 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 16178 328 0 0
sp_qd_handle_on_invoice_upd_sts 10997 10997 0 0.00 0 17858 290 0 0
sp_qd_handle_on_reserve_upd_sts 10034 10034 0 0.00 0 8845 53 0 0
sp_reserve_write_off 10035 10035 0 0.00 6 17278 163 0 0
sp_supplier_invoice 5452 5452 0 0.00 7 90949 2921 0 0
sp_supplier_order 5571 5571 0 0.00 4 33487 1271 0 0
srv_aggregate_perf_data 110 110 0 0.00 2875 120625 15131 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1866 153776 44213 0 0
srv_find_qd_qs_mism 1094179 1094179 0 0.00 0 14268 5 0 0
srv_make_invnt_saldo 550 550 0 0.00 22 22435 976 0 0
srv_make_money_saldo 787 787 0 0.00 0 11451 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 172286 347186 287271 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4979 4979 0 0.00 0 18353 167 0 0
t$perf-norm:sp_cancel_adding_invoice 443 443 0 0.00 0 2523 91 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 2457 14 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 6388 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 1964 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 9511 44 0 0
t$perf-norm:sp_cancel_supplier_invoice 465 465 0 0.00 0 1922 15 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 32571 1827 0 0
t$perf-norm:sp_cancel_write_off 1064 1064 0 0.00 0 8523 25 0 0
t$perf-norm:sp_client_order 21014 21014 0 0.00 0 17988 20 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 7086 13 0 0
t$perf-norm:sp_pay_from_customer 7070 7070 0 0.00 0 8074 11 0 0
t$perf-norm:sp_pay_to_supplier 5852 5852 0 0.00 0 15060 17 0 0
t$perf-norm:sp_reserve_write_off 10035 10035 0 0.00 0 17974 16 0 0
t$perf-norm:sp_supplier_invoice 5452 5452 0 0.00 0 9463 21 0 0
t$perf-norm:sp_supplier_order 5571 5571 0 0.00 0 17202 19 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 770 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2097 38 0 0
t$perf-norm:srv_make_invnt_saldo 550 550 0 0.00 0 9542 51 0 0
t$perf-norm:srv_make_money_saldo 787 787 0 0.00 0 777 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10184 9916 268 2.63 0 6590 22 0 268
v_all_customers 38238 38238 0 0.00 0 23 1 0 0
v_all_suppliers 30722 30722 0 0.00 0 83 1 0 0
v_all_wares 42028 42028 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 886 886 0 0.00 0 964 8 0 0
v_cancel_client_order 3534 3534 0 0.00 0 4340 7 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 4340 13 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 2604 5 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 5579 14 0 0
v_cancel_supplier_invoice 960 930 30 3.12 0 2695 20 0 30
v_cancel_supplier_order 846 846 0 0.00 0 2475 8 0 0
v_cancel_write_off 2128 2128 0 0.00 0 1105 12 0 0
v_max_id_clo_ord 5571 5571 0 0.00 0 215 1 0 0
v_max_id_clo_res 984 984 0 0.00 0 1169 10 0 0
v_max_id_ord_sup 5452 5452 0 0.00 0 4193 4 0 0
v_max_non_paid_invoice 1514 1514 0 0.00 0 618 4 0 0
v_max_non_paid_realizn 3746 3746 0 0.00 0 2531 5 0 0
v_min_id_clo_ord 5571 5571 0 0.00 0 2906 8 0 0
v_min_id_clo_res 984 984 0 0.00 0 4023 5 0 0
v_min_id_ord_sup 5452 5452 0 0.00 0 9295 11 0 0
v_min_non_paid_invoice 5852 5852 0 0.00 0 4904 19 0 0
v_min_non_paid_realizn 7070 7070 0 0.00 0 5167 17 0 0
v_our_firm 10438 10438 0 0.00 0 14 1 0 0
v_reserve_write_off 20068 20068 0 0.00 0 6161 7 0 0
x_lock_depdocs_on_canc_invoice 12191 12191 0 0.00 0 13963 123 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 5024 303 0 0
x_make_qstorno_1000_1200 5571 5571 0 0.00 2 33476 1198 0 0
x_make_qstorno_1200_2000 5452 5452 0 0.00 2 90832 2805 0 0
x_make_qstorno_2100_3300 461266 461266 0 0.00 0 125189 152 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 02-10-2022 22:39:55 to 02-10-2022 22:40:00.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:32 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:32 29657 8192000000 9898129104 10205782016 969664608 10.583 100 84 169 0 997416480 5655360 22096512
20:11:08 28902 8192000000 9941787936 10266689536 1026656768 11.136 100 89 178 0 1058247872 8691616 22899488
20:12:42 17296 8192000000 9977807920 10310873088 1061972400 11.475 100 87 172 0 1093199488 10029728 21197360
20:14:07 8440 8192000000 10022330240 10368405504 1115792896 11.987 100 79 156 0 1145057280 10763376 18501008
20:15:19 5515 8192000000 10081210304 10436050944 1165442336 12.454 100 86 171 0 1198362432 13830640 19089456
20:16:26 5113 8192000000 10125232048 10493825024 1211359760 12.882 100 74 148 0 1240322544 12752032 16210752
20:17:35 2303 8192000000 10165516880 10536710144 1243187712 13.176 100 89 178 2 1278587968 16076256 19324000
20:18:39 780 8192000000 10194231664 10576142336 1278603136 13.500 100 91 183 0 1312367408 13418128 20346144
20:19:42 1366 8192000000 10206922784 10595397632 1293047744 13.632 100 78 151 0 1321406160 11408112 16950304
20:20:46 3820 8192000000 10211111344 10602688512 1303227280 13.725 100 65 128 0 1329328240 12385328 13715632
20:21:51 6585 8192000000 10201607360 10602192896 1306279344 13.752 100 29 58 0 1319588400 6431216 6877840
20:23:01 2872 8192000000 10200936128 10615214080 1317804416 13.857 100 25 50 1 1329548784 6237152 5507216
20:24:06 67096 8192000000 10237226304 10646646784 1328525872 13.954 100 53 106 0 1348324336 6737808 13060656
20:26:14 4306 8192000000 10234655184 10650890240 1336970368 14.030 100 37 75 0 1351092448 5635136 8486944
20:27:21 11211 8192000000 10231238960 10647633920 1332582960 13.990 100 22 44 0 1343035632 5128736 5323936
20:28:35 6640 8192000000 10217549968 10636922880 1326307536 13.934 100 29 58 0 1338373984 5506320 6560128
20:29:43 5125 8192000000 10168868432 10575470592 1244163504 13.185 95 30 60 1 1257501200 6586992 6750704
20:30:48 11847 8192000000 10147911936 10552795136 1194521984 12.725 93 17 34 0 1203967504 5475152 3970368
20:32:03 59834 8192000000 10137144064 10541056000 1211406464 12.882 96 31 60 0 1224402016 6621344 6374208
20:34:04 4859 8192000000 10163468032 10564091904 1259117760 13.322 99 39 77 0 1274750400 6984272 8648368
20:35:09 11785 8192000000 10170246240 10578935808 1281256608 13.524 100 17 34 0 1290948512 6050464 3641440
20:36:29 23017 8192000000 10207101328 10607403008 1296053792 13.659 100 30 57 2 1307966416 5844912 6067712
20:37:53 23565 8192000000 10199850432 10615029760 1306978832 13.759 100 29 57 0 1319125888 5775808 6371248
20:39:28 11992 8192000000 10237454560 10647977984 1331978192 13.985 100 42 84 0 1351758288 10057744 9722352
20:40:48 10236 8192000000 10256227648 10675027968 1351092496 14.157 100 39 78 0 1372929904 13003744 8833664
20:42:00 14201 8192000000 10270712432 10688135168 1354795440 14.191 99 40 80 1 1378768672 14478464 9494768
20:43:22 13114 8192000000 10298046192 10715369472 1379950560 14.416 100 51 102 1 1409077120 16885888 12240672
20:44:53 18991 8192000000 10309778352 10729095168 1389729664 14.503 100 44 88 1 1421996752 21042320 11224768
20:46:41 13613 8192000000 10329813536 10749714432 1401256800 14.606 100 51 102 0 1436997968 23040192 12700976
20:48:05 2290 8192000000 10352202048 10767294464 1416014768 14.737 100 70 136 0 1459192464 26132080 17045616
20:49:11 7737 8192000000 10349879536 10772029440 1417421856 14.750 100 62 124 1 1462327376 29708320 15197200
20:50:23 6497 8192000000 10350546992 10774827008 1403981216 14.630 99 59 116 0 1452767536 34269616 14516704
20:51:35 5261 8192000000 10358056208 10780540928 1418056416 14.755 100 54 106 1 1462921024 31872496 12992112
20:52:45 3235 8192000000 10360816336 10774757376 1405089536 14.640 100 71 141 0 1463745520 41224064 17431920
20:53:55 3025 8192000000 10358869296 10773606400 1406659904 14.654 100 72 144 1 1461543536 37419088 17464544
20:55:02 2817 8192000000 10334705024 10754895872 1385453408 14.465 99 55 108 1 1431487728 31517312 14517008
20:56:10 8118 8192000000 10321466192 10747293696 1388000992 14.488 100 47 94 0 1430535840 31170400 11364448
20:57:30 7259 8192000000 10328671840 10756575232 1387168448 14.481 100 32 62 0 1429748352 33726656 8853248
20:58:42 14688 8192000000 10315502400 10746499072 1351659792 14.162 97 27 55 0 1393048528 34255936 7132800
21:00:07 9149 8192000000 10295292224 10712952832 1332036784 13.986 97 39 78 2 1380672272 38276000 10359488
21:01:19 15752 8192000000 10291107456 10711859200 1344398368 14.097 99 27 54 0 1385070848 33217872 7454608
21:02:42 20423 8192000000 10299615568 10727878656 1358218016 14.221 100 29 59 0 1401306480 34358992 8729472
21:04:09 36616 8192000000 10274971808 10711756800 1315394048 13.835 97 21 41 0 1353364736 32289344 5681344
21:05:48 28256 8192000000 10256684080 10685865984 1332687600 13.991 99 31 64 1 1346256368 6767184 6801584
21:07:30 38039 8192000000 10264940016 10693718016 1329668624 13.964 98 34 67 1 1352684000 14806272 8209104
21:09:09 68638 8192000000 10268954032 10711171072 1357129504 14.212 100 26 52 1 1376871312 13782880 5958928
21:11:36 66704 8192000000 10244768432 10695323648 1307548544 13.764 95 18 36 0 1320523216 8721872 4252800
21:13:45 4435 8192000000 10274673616 10712543232 1351538352 14.161 99 28 57 2 1368461504 10882592 6040560
21:14:51 32011 8192000000 10274219344 10720526336 1350271248 14.150 99 24 48 1 1370253456 14229888 5752320
21:16:26 1865 8192000000 10296719952 10728738816 1373680240 14.360 100 56 111 1 1393526480 7406768 12439472
21:17:29 153776 8192000000 10300654272 10754891776 1365600256 14.288 98 16 32 0 1382489600 13436480 3452864
21:21:14 62480 8192000000 10314280912 10771197952 1400989872 14.604 100 24 47 0 1428172048 20269664 6912512
21:23:26 54644 8192000000 10299237648 10766688256 1386398528 14.474 99 22 44 0 1405907088 14147744 5360816
21:25:28 54779 8192000000 10309490912 10780557312 1409308672 14.678 100 12 24 0 1426858160 14418032 3131456
21:27:24 38156 8192000000 10313459728 10767863808 1390016416 14.506 99 15 28 0 1410693808 17574208 3103184
21:29:08 50662 8192000000 10307600336 10771054592 1392370176 14.527 99 20 40 1 1409923184 12980208 4572800
21:31:01 26537 8192000000 10340176352 10787663872 1414970656 14.728 100 38 76 0 1436071184 12349040 8751488
21:32:27 73649 8192000000 10313895024 10781261824 1368587616 14.314 97 10 20 0 1387945856 17184848 2173392
21:34:43 77095 8192000000 10319232688 10764201984 1355302704 14.195 97 30 60 0 1378466112 16470800 6692608
21:37:04 40456 8192000000 10267197792 10707668992 1312546944 13.809 95 17 34 0 1326046736 9711072 3788720
21:38:47 21676 8192000000 10273602288 10703130624 1318060480 13.859 96 34 68 0 1335880992 10853520 6966992
21:40:10 39719 8192000000 10260360304 10687471616 1319874624 13.876 97 59 118 1 1343343552 10670272 12798656
21:41:50 52366 8192000000 10248629376 10696077312 1337228064 14.032 99 12 22 0 1346492320 7144672 2119584
21:43:51 57251 8192000000 10250942736 10703319040 1348145776 14.131 99 16 32 0 1359551632 7803120 3602736
21:46:13 58400 8192000000 10241753152 10685554688 1292996624 13.632 95 27 53 0 1310171360 11036640 6138096
21:48:12 87481 8192000000 10270223728 10724995072 1357357952 14.214 100 15 30 0 1382372192 21346272 3667968
21:50:43 56968 8192000000 10277070848 10739879936 1348772320 14.136 99 12 23 1 1378963792 27552112 2639360
21:52:43 61781 8192000000 10275125696 10731905024 1355721312 14.199 99 19 38 0 1382032928 21614416 4697200
21:54:45 84985 8192000000 10277496576 10720489472 1336293584 14.024 97 21 42 0 1355116832 14373904 4449344
21:57:23 47216 8192000000 10276824608 10754949120 1374869216 14.371 100 18 36 0 1400931840 21750560 4312064
21:59:17 116642 8192000000 10281652256 10748403712 1344922016 14.102 98 16 32 0 1375741024 27055264 3763744
22:02:14 20222 8192000000 10275457312 10723520512 1343874592 14.092 98 17 34 0 1360589120 12729536 3984992
22:03:37 126741 8192000000 10259788080 10711080960 1337524048 14.035 98 23 46 0 1355801952 12987856 5290048
22:06:48 67511 8192000000 10267716848 10739519488 1359488208 14.233 99 7 14 0 1375340352 14305264 1546880
22:08:56 68305 8192000000 10286153264 10743734272 1368690560 14.315 99 15 30 0 1387124640 15496144 2937936
22:12:16 66613 8192000000 10294206848 10757853184 1379878672 14.415 99 8 15 2 1395029936 14133008 1018256
22:14:23 57854 8192000000 10293975744 10756616192 1377245968 14.392 99 8 16 0 1386630752 7706384 1678400
22:16:27 79405 8192000000 10310334992 10774781952 1393394464 14.536 99 27 54 0 1415997904 16257184 6346256
22:18:57 61248 8192000000 10324728816 10781708288 1407784240 14.664 100 24 48 0 1427483584 14160048 5539296
22:21:07 56124 8192000000 10305946656 10785849344 1369373824 14.321 98 20 41 0 1392410896 18307648 4729424
22:23:14 4009 8192000000 10312375360 10755010560 1380472192 14.421 99 62 122 0 1402991968 8768176 13751600
22:24:22 81631 8192000000 10277199856 10738307072 1313890768 13.821 95 21 42 0 1336024352 17913424 4220160
22:26:46 100010 8192000000 10273294416 10731937792 1350686720 14.154 98 23 46 0 1368773184 12887312 5199152
22:29:26 52983 8192000000 10249303056 10715451392 1320348672 13.880 97 7 12 0 1333113760 11614064 1151024
22:31:19 55162 8192000000 10245922656 10701840384 1310482592 13.790 96 20 40 0 1322173680 6841056 4850032
22:33:20 97210 8192000000 10258362160 10717544448 1330587872 13.972 98 17 34 1 1350211504 15983872 3639760
22:35:58 77950 8192000000 10271715120 10737041408 1341159344 14.068 98 21 42 0 1369587232 23006640 5421248
22:38:16 19080 8192000000 8990892464 9190612992 166344960 1.990 13 3 6 0 169290848 2361760 584128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-10-2022 22:40:02 to 02-10-2022 22:40:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-10-2022 22:40:18 to 02-10-2022 22:40:20.

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          238809
MON$OLDEST_ACTIVE               238810
MON$OLDEST_SNAPSHOT             238810
MON$NEXT_TRANSACTION            238810
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                       2026464
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.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:116: 
:117: mscs-test-161.budzdorov.ru    Sun Oct  2 22:38:36 2022
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:119: 
:120: 
:121: mscs-test-161.budzdorov.ru    Sun Oct  2 22:38:36 2022
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:123: 
--- end of diff output ---

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

02.10.22 22:40:21. 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-10-02 22:40:23 No severe PSQL-related problems occured
02.10.22 22:40:25. End of report.