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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16665512 kB
MemAvailable 31261904 kB
Buffers 0 kB
Cached 14989592 kB
SwapCached 1204 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.9
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-01-09 22:38:03
DTS_END                         2022-01-09 22:38:03.1630
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/33442
TRN_ID                          238733
ATT_ID                          612
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: 9054.00 19759 1086480
customer order: creation 168.68 370 20242
customer order: refuse 14.38 368 1725
order to supplier: creation 45.85 1235 5502
order to supplier: removal 3.44 98070 413
invoice (draft): creation 46.71 3708 5605
invoice (draft): removal 50.06 777 6007
invoice accept: apply 41.49 18811 4979
invoice accept: cancel 45.39 837 5447
customer reserve: creation 3883.60 183 466032
customer reserve: removal 4471.48 60 536578
realization accept: apply 82.40 187 9888
realization accept: cancel 71.83 113 8619
payment from customer: creation 58.45 117 7014
payment from customer: removal 5.75 72 690
payment to supplier: creation 48.44 138 5813
payment to supplier: removal 3.41 54 409
service: total inventory turnovers 4.78 979 574
service: total monetary turnovers 7.02 18 842
service: aggregate perf. data 0.81 20851 97
service: refresh index statistics 0.03 248229 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-01-2022 22:38:38 to 09-01-2022 22:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6137
TEST_TIME 2 5043
TEST_TIME 3 4971
TEST_TIME 4 5411
TEST_TIME 5 5159
TEST_TIME 6 5483
TEST_TIME 7 5589
TEST_TIME 8 5701
TEST_TIME 9 5596
TEST_TIME 10 5871
TEST_TIME 11 5736
TEST_TIME 12 5992
TEST_TIME 13 6242
TEST_TIME 14 6219
TEST_TIME 15 6354
TEST_TIME 16 6454
TEST_TIME 17 6728
TEST_TIME 18 7380
TEST_TIME 19 8006
TEST_TIME 20 8355
TEST_TIME 21 8632
TEST_TIME 22 9337
TEST_TIME 23 9631
TEST_TIME 24 9818
TEST_TIME 25 10228
TEST_TIME 26 10201
TEST_TIME 27 10523
TEST_TIME 28 10420
TEST_TIME 29 10232
TEST_TIME 30 10290
TEST_TIME 31 10335
TEST_TIME 32 10177
TEST_TIME 33 9932
TEST_TIME 34 9833
TEST_TIME 35 9702
TEST_TIME 36 9760
TEST_TIME 37 9640
TEST_TIME 38 9501
TEST_TIME 39 9408
TEST_TIME 40 9465
TEST_TIME 41 9347
TEST_TIME 42 9374
TEST_TIME 43 9401
TEST_TIME 44 9323
TEST_TIME 45 9299
TEST_TIME 46 9222
TEST_TIME 47 9065
TEST_TIME 48 9069
TEST_TIME 49 9004
TEST_TIME 50 9052
TEST_TIME 51 8971
TEST_TIME 52 8922
TEST_TIME 53 8824
TEST_TIME 54 8737
TEST_TIME 55 8837
TEST_TIME 56 8802
TEST_TIME 57 8928
TEST_TIME 58 8940
TEST_TIME 59 8879
TEST_TIME 60 8904
TEST_TIME 61 8905
TEST_TIME 62 8938
TEST_TIME 63 8973
TEST_TIME 64 8999
TEST_TIME 65 9054
TEST_TIME 66 9122
TEST_TIME 67 9189
TEST_TIME 68 9205
TEST_TIME 69 9204
TEST_TIME 70 9314
TEST_TIME 71 9242
TEST_TIME 72 9176
TEST_TIME 73 9135
TEST_TIME 74 9123
TEST_TIME 75 9098
TEST_TIME 76 9052
TEST_TIME 77 9015
TEST_TIME 78 9056
TEST_TIME 79 8992
TEST_TIME 80 8905
TEST_TIME 81 8939
TEST_TIME 82 8943
TEST_TIME 83 8882
TEST_TIME 84 8972
TEST_TIME 85 8973
TEST_TIME 86 8988
TEST_TIME 87 9023
TEST_TIME 88 8984
TEST_TIME 89 9012
TEST_TIME 90 9101
TEST_TIME 91 9130
TEST_TIME 92 9138
TEST_TIME 93 9123
TEST_TIME 94 9116
TEST_TIME 95 9187
TEST_TIME 96 9158
TEST_TIME 97 9158
TEST_TIME 98 9169
TEST_TIME 99 9164
TEST_TIME 100 9102
TEST_TIME 101 9046
TEST_TIME 102 9020
TEST_TIME 103 9010
TEST_TIME 104 8951
TEST_TIME 105 8921
TEST_TIME 106 8899
TEST_TIME 107 8931
TEST_TIME 108 8944
TEST_TIME 109 8936
TEST_TIME 110 8909
TEST_TIME 111 8899
TEST_TIME 112 8992
TEST_TIME 113 9014
TEST_TIME 114 9050
TEST_TIME 115 9052
TEST_TIME 116 9047
TEST_TIME 117 9059
TEST_TIME 118 9144
TEST_TIME 119 9125
TEST_TIME 120 9056

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-01-2022 22:38:41 to 09-01-2022 22:38:45.

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 1516549 1516549 0 0.00 0 16094 7 0 0
doc_list_biud 1516528 1516528 0 0.00 0 21385 27 0 0
sp_add_invoice_to_stock 5074 4979 95 1.87 29 193092 18812 0 95
sp_cancel_adding_invoice 5447 5447 0 0.00 4 55810 837 0 0
sp_cancel_client_order 1725 1725 0 0.00 4 11282 368 0 0
sp_cancel_customer_reserve 536578 536578 0 0.00 1 21389 60 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 2 3679 72 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 2 2557 54 0 0
sp_cancel_supplier_invoice 6010 6007 3 0.04 1 16965 778 0 3
sp_cancel_supplier_order 413 413 0 0.00 5 1136588 98070 0 0
sp_cancel_write_off 8619 8619 0 0.00 1 11948 114 0 0
sp_client_order 20242 20242 0 0.00 3 18545 370 0 0
sp_customer_reserve 466032 466032 0 0.00 0 47484 184 0 0
sp_fill_shopping_cart 31346 31346 0 0.00 1 28599 48 0 0
sp_fill_shopping_cart_clo_res 466024 466024 0 0.00 0 11248 14 0 0
sp_get_clo_for_invoice 4971 4971 0 0.00 0 16382 343 0 0
sp_kill_cost_storno 15165 15165 0 0.00 0 6057 57 0 0
sp_kill_qstorno_ret_qs2qd 1088195 1088195 0 0.00 0 21357 32 0 0
sp_kill_qty_storno 573635 573635 0 0.00 0 21385 70 0 0
sp_lock_selected_doc 20618 20618 0 0.00 0 13354 5 0 0
sp_make_cost_storno 27686 27686 0 0.00 0 11903 59 0 0
sp_multiply_rows_for_pdistr 27673 27673 0 0.00 0 7195 28 0 0
sp_multiply_rows_for_qdistr 20242 20242 0 0.00 0 10435 178 0 0
sp_pay_from_customer 7014 7014 0 0.00 4 16112 117 0 0
sp_pay_to_supplier 5813 5813 0 0.00 5 11791 138 0 0
sp_payment_common 12819 12819 0 0.00 1 16022 27 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 11250 340 0 0
sp_qd_handle_on_invoice_upd_sts 10411 10411 0 0.00 0 16522 332 0 0
sp_qd_handle_on_reserve_upd_sts 9882 9882 0 0.00 0 15818 67 0 0
sp_reserve_write_off 9888 9888 0 0.00 6 15868 187 0 0
sp_supplier_invoice 5605 5605 0 0.00 5 91076 3708 0 0
sp_supplier_order 5502 5502 0 0.00 5 29351 1235 0 0
srv_aggregate_perf_data 97 97 0 0.00 2897 242637 20852 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1993 114730 46958 0 0
srv_find_qd_qs_mism 1038125 1038125 0 0.00 0 16093 6 0 0
srv_make_invnt_saldo 574 574 0 0.00 15 25684 979 0 0
srv_make_money_saldo 842 842 0 0.00 0 301 19 0 0
srv_recalc_idx_stat 4 4 0 0.00 109058 572022 248230 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4979 4979 0 0.00 0 9870 166 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 5535 124 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 4441 11 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 1445 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 8403 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 1636 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 747 11 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 50777 1422 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 4416 11 0 0
t$perf-norm:sp_client_order 20242 20242 0 0.00 0 9626 20 0 0
t$perf-norm:sp_customer_reserve 916 916 0 0.00 0 3780 9 0 0
t$perf-norm:sp_pay_from_customer 7014 7014 0 0.00 0 9629 10 0 0
t$perf-norm:sp_pay_to_supplier 5813 5813 0 0.00 0 5908 12 0 0
t$perf-norm:sp_reserve_write_off 9888 9888 0 0.00 0 8307 11 0 0
t$perf-norm:sp_supplier_invoice 5605 5605 0 0.00 0 9627 18 0 0
t$perf-norm:sp_supplier_order 5502 5502 0 0.00 0 6092 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 265 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1343 23 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 621 15 0 0
t$perf-norm:srv_make_money_saldo 842 842 0 0.00 0 2633 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 5 0 0
v_add_invoice_to_stock 10118 9928 190 1.87 0 6648 19 0 190
v_all_customers 36834 36834 0 0.00 0 16 0 0 0
v_all_suppliers 30630 30630 0 0.00 0 26 1 0 0
v_all_wares 40482 40482 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 1718 9 0 0
v_cancel_client_order 3450 3450 0 0.00 0 2924 7 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 1730 6 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 3517 8 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 2512 8 0 0
v_cancel_supplier_invoice 816 810 6 0.73 0 2463 19 0 6
v_cancel_supplier_order 826 826 0 0.00 0 1721 6 0 0
v_cancel_write_off 2096 2096 0 0.00 0 3216 15 0 0
v_max_id_clo_ord 5500 5500 0 0.00 0 1561 1 0 0
v_max_id_clo_res 916 916 0 0.00 0 3903 23 0 0
v_max_id_ord_sup 5605 5605 0 0.00 0 4722 5 0 0
v_max_non_paid_invoice 1598 1598 0 0.00 0 2994 6 0 0
v_max_non_paid_realizn 3680 3680 0 0.00 0 15978 11 0 0
v_min_id_clo_ord 5500 5500 0 0.00 0 8097 8 0 0
v_min_id_clo_res 916 916 0 0.00 0 9689 17 0 0
v_min_id_ord_sup 5605 5605 0 0.00 0 9979 17 0 0
v_min_non_paid_invoice 5807 5807 0 0.00 0 5945 13 0 0
v_min_non_paid_realizn 7009 7009 0 0.00 0 6468 20 0 0
v_our_firm 10308 10308 0 0.00 0 12 1 0 0
v_reserve_write_off 19764 19764 0 0.00 0 4010 7 0 0
x_lock_depdocs_on_canc_invoice 11049 11049 0 0.00 0 9181 113 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 10036 319 0 0
x_make_qstorno_1000_1200 5502 5502 0 0.00 1 29323 1160 0 0
x_make_qstorno_1200_2000 5605 5605 0 0.00 2 91066 3515 0 0
x_make_qstorno_2100_3300 466032 466032 0 0.00 0 47482 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-01-2022 22:38:46 to 09-01-2022 22:38:50.

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:06 8 8192000000 8811543136 8826953728 15332224 0.186 5 3 4 0 15562960 89856 140880
20:09:07 29186 8192000000 9894891696 10208219136 979715856 10.681 100 81 160 2 1006912960 6121456 21075648
20:10:48 31387 8192000000 9951624448 10276843520 1032501056 11.193 100 86 172 0 1063639632 8087760 23050816
20:12:27 20876 8192000000 9992226176 10330906624 1077372352 11.622 100 88 176 2 1109189760 9801120 22016288
20:13:51 11838 8192000000 10029155856 10376908800 1117830416 12.006 100 80 160 0 1148201344 11675456 18695472
20:15:10 7952 8192000000 10090733456 10449080320 1176530848 12.558 100 87 174 0 1210397712 14188576 19678288
20:16:22 6229 8192000000 10130921776 10499596288 1212795584 12.895 100 77 154 3 1242066128 12060784 17209760
20:17:32 4569 8192000000 10160174784 10535227392 1239505152 13.142 100 85 170 0 1271120320 12843776 18771392
20:18:38 1765 8192000000 10194133536 10575101952 1277039488 13.486 100 92 185 1 1311691616 13655584 20996544
20:19:41 1339 8192000000 10217266480 10602762240 1299828880 13.694 100 88 173 1 1332673168 13119296 19724992
20:20:44 2053 8192000000 10217181760 10611814400 1305956192 13.749 100 82 165 1 1334542416 8699600 19886624
20:21:46 5302 8192000000 10208281200 10616107008 1311963632 13.804 100 29 58 1 1325200464 6871712 6365120
20:22:54 5981 8192000000 10206479216 10627923968 1319586752 13.873 100 24 48 2 1331168944 6005392 5576800
20:24:00 6612 8192000000 10214820208 10633428992 1325266112 13.924 100 19 38 1 1335600752 6374640 3960000
20:25:09 27224 8192000000 10250745296 10648383488 1328995216 13.958 100 68 139 1 1351794480 6808192 15991072
20:26:37 5232 8192000000 10228625616 10652053504 1337465488 14.035 100 23 46 0 1349662688 6525344 5671856
20:27:46 6330 8192000000 10199157984 10615427072 1287455824 13.581 97 17 34 2 1296581760 5532544 3593392
20:28:54 44404 8192000000 10194169744 10608492544 1251782560 13.255 95 27 55 0 1263708240 5788144 6137536
20:30:41 7166 8192000000 10171070880 10573643776 1242001296 13.165 97 29 57 2 1253326064 4642912 6681856
20:31:50 6399 8192000000 10144655504 10536546304 1236657776 13.115 99 26 52 1 1248754896 5915056 6182064
20:32:59 69352 8192000000 10154764896 10563756032 1244440256 13.187 98 23 46 0 1255312032 5646944 5224832
20:35:08 13813 8192000000 10179987872 10590490624 1286031712 13.568 100 26 52 1 1298126448 5900464 6194272
20:36:25 9512 8192000000 10189625376 10602631168 1297732720 13.675 100 24 45 3 1309455536 6083424 5639392
20:37:40 17421 8192000000 10193567200 10612342784 1307105936 13.760 100 16 32 1 1316393024 5438912 3848176
20:39:02 12125 8192000000 10232176560 10639249408 1326917888 13.939 100 55 112 3 1345769312 6430480 12420944
20:40:18 23415 8192000000 10253512912 10666598400 1343388064 14.088 100 43 85 1 1363176240 9953216 9834960
20:41:49 9986 8192000000 10271782416 10687393792 1357158256 14.212 100 60 120 2 1386630368 15131568 14340544
20:43:02 22049 8192000000 10276429616 10695348224 1365625456 14.288 100 43 86 0 1390898416 15394608 9878352
20:44:38 11731 8192000000 10303874656 10725228544 1384437760 14.456 100 44 88 1 1417470688 21191952 11840976
20:45:53 8048 8192000000 10307423824 10732744704 1386023440 14.470 100 51 101 0 1423415152 24540624 12851088
20:47:14 8944 8192000000 10326634464 10749267968 1399444688 14.590 100 56 110 0 1440356048 27079648 13831712
20:48:28 10377 8192000000 10326023136 10754531328 1392163456 14.525 99 40 78 3 1431406224 29703856 9538912
20:49:42 9678 8192000000 10356200416 10768846848 1402306160 14.616 100 79 158 0 1459274272 37791232 19176880
20:50:58 3396 8192000000 10342456544 10755043328 1395452432 14.554 99 76 152 2 1448475040 35165904 17856704
20:52:04 3624 8192000000 10345704832 10761064448 1399035824 14.586 100 67 134 3 1454235984 38426864 16773296
20:53:12 2648 8192000000 10362092560 10771066880 1395834048 14.558 99 87 172 1 1460779760 43266864 21678848
20:54:21 4106 8192000000 10344845600 10754105344 1381538112 14.430 99 78 156 1 1449790288 47737600 20514576
20:55:35 1989 8192000000 10350125904 10755883008 1377932352 14.398 100 76 152 2 1449058160 51511264 19614544
20:56:45 11166 8192000000 10331795008 10754473984 1372129648 14.346 100 55 110 0 1442047808 54950832 14967328
20:58:07 10926 8192000000 10333184512 10761138176 1380098816 14.417 100 48 95 1 1443161744 50377312 12685616
20:59:21 9523 8192000000 10322098208 10752352256 1365185760 14.284 99 35 70 1 1425823968 50404128 10234080
21:00:41 8452 8192000000 10314607216 10748153856 1369132672 14.319 100 36 72 1 1420151568 41258816 9760080
21:01:55 34931 8192000000 10292455744 10733576192 1360930832 14.246 100 23 46 0 1395934800 29002752 6001216
21:03:52 38130 8192000000 10296997648 10742624256 1375850400 14.379 100 12 24 2 1406111280 26741568 3519312
21:06:13 19968 8192000000 10292045456 10740097024 1369650448 14.324 99 23 46 1 1388701056 13587840 5462768
21:07:41 70910 8192000000 10272161136 10713460736 1319771312 13.875 96 11 22 0 1333761648 11623024 2367312
21:10:12 102821 8192000000 10290932000 10727088128 1362047536 14.256 99 40 79 1 1377218720 6368400 8802784
21:12:59 60977 8192000000 10287941376 10726555648 1365290144 14.285 99 31 62 0 1383988704 11340928 7357632
21:15:00 67959 8192000000 10277179136 10719752192 1327960864 13.949 96 10 19 2 1340236544 10022336 2253344
21:17:13 31335 8192000000 10288116064 10736529408 1382044656 14.435 100 21 42 0 1397517312 10432624 5040032
21:18:45 64891 8192000000 10287481408 10729218048 1377982256 14.399 99 24 49 1 1396136752 12270640 5883856
21:20:56 44888 8192000000 10285210400 10737864704 1387123744 14.480 100 20 40 2 1404880032 12842448 4913840
21:22:45 74750 8192000000 10281058944 10738360320 1363872048 14.272 98 18 36 0 1376222224 7951088 4399088
21:25:01 94509 8192000000 10284127248 10750586880 1393542512 14.537 100 11 22 1 1404344896 8078880 2723504
21:27:47 59179 8192000000 10285669840 10751418368 1394326320 14.544 100 15 30 1 1407018448 9276480 3415648
21:29:58 36005 8192000000 10284230528 10743459840 1378498080 14.403 99 16 32 2 1393947792 11376864 4072848
21:31:34 55238 8192000000 10287227520 10746130432 1378203376 14.400 99 17 33 0 1395402448 12991920 4207152
21:33:32 65321 8192000000 10272190992 10730696704 1349293824 14.141 97 14 28 0 1361920608 9659248 2967536
21:35:48 84679 8192000000 10283902144 10737405952 1344717552 14.100 97 13 26 1 1357244496 9637984 2888960
21:38:18 52346 8192000000 10282332496 10724220928 1339356320 14.052 97 28 56 3 1353492864 7601312 6535232
21:40:11 39802 8192000000 10284563888 10737438720 1371819360 14.343 99 18 36 0 1384243456 8427680 3996416
21:41:54 114730 8192000000 10268927472 10716663808 1273613456 13.455 92 23 46 1 1291318448 11956144 5748848
21:44:49 114156 8192000000 10267278944 10719129600 1299702032 13.693 94 10 20 0 1318880368 16103536 3074800
21:47:52 40230 8192000000 10272333568 10711441408 1339301600 14.051 99 22 44 1 1360860640 16529600 5029440
21:49:38 104886 8192000000 10271589776 10716528640 1356958816 14.210 100 17 34 2 1377330720 15957904 4414000
21:52:25 16353 8192000000 10283333712 10730872832 1369747984 14.325 100 36 72 1 1392601648 14907296 7946368
21:53:44 45981 8192000000 10253858720 10701996032 1341220000 14.068 98 17 34 2 1355406832 9757040 4429792
21:55:30 66207 8192000000 10253034832 10705108992 1328710912 13.956 98 12 24 1 1340876768 9330448 2835408
21:57:37 80024 8192000000 10259525984 10705514496 1348553200 14.134 99 17 34 0 1362732256 9425568 4753488
22:00:59 80531 8192000000 10266972896 10723291136 1351260384 14.159 99 14 28 0 1365618160 11242208 3115568
22:03:58 68227 8192000000 10281832736 10727960576 1361000944 14.246 99 14 27 2 1375125120 10613184 3510992
22:06:09 48254 8192000000 10303162720 10740441088 1373975280 14.363 99 44 88 1 1400693904 16978800 9739824
22:08:04 45973 8192000000 10286147360 10737045504 1357153536 14.212 98 13 26 1 1371098640 11379952 2565152
22:09:52 70357 8192000000 10266257216 10717351936 1293276656 13.634 93 9 18 0 1303208800 7943680 1988464
22:12:03 30121 8192000000 10324650592 10760245248 1392442512 14.528 100 41 82 1 1419248736 17945008 8861216
22:13:36 84490 8192000000 10297375424 10747707392 1336293152 14.024 96 7 14 0 1351050976 13412048 1345776
22:16:06 111989 8192000000 10297253536 10757074944 1377036272 14.390 99 21 42 0 1396736272 14565248 5134752
22:19:10 67154 8192000000 10286426800 10736418816 1380318608 14.419 99 28 56 0 1393548176 7002768 6226800
22:21:17 42585 8192000000 10273857968 10736529408 1370159696 14.328 98 10 20 0 1380189712 7609424 2420592
22:23:15 72662 8192000000 10293784608 10750537728 1371221360 14.338 98 17 33 2 1385114752 9532800 4360592
22:25:28 85361 8192000000 10280386976 10755923968 1356891264 14.209 98 8 16 0 1374398816 15871520 1636032
22:27:54 50310 8192000000 10286815488 10738184192 1341288672 14.069 97 22 44 1 1360865648 14998752 4578224
22:29:48 62177 8192000000 10273927696 10734428160 1353889728 14.182 98 18 36 1 1373407920 15106176 4412016
22:31:59 77776 8192000000 10278417728 10726379520 1350345072 14.151 98 18 36 0 1364478864 10357424 3776368
22:34:17 29639 8192000000 10290866240 10724974592 1352634256 14.171 99 51 100 0 1376562592 12671088 11257248
22:35:50 63546 8192000000 10257967008 10720911360 1340516384 14.062 99 18 36 1 1364828336 20174000 4137952
22:37:56 13673 8192000000 9128887552 9368399872 286300480 3.376 22 1 2 0 290007104 3518912 187712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-01-2022 22:38:52 to 09-01-2022 22:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 09-01-2022 22:39:08 to 09-01-2022 22:39:09.

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          239042
MON$OLDEST_ACTIVE               239043
MON$OLDEST_SNAPSHOT             239043
MON$NEXT_TRANSACTION            239043
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                       2009728
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
--- end of diff output ---

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

09.01.22 22:39:10. 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-01-09 22:39:13 No severe PSQL-related problems occured
09.01.22 22:39:14. End of report.