Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.07.2022 01: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 16013292 kB
MemAvailable 31039216 kB
Buffers 0 kB
Cached 15671216 kB
SwapCached 2500 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-21 01:38:21
DTS_END                         2022-07-21 01:38:21.5580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41372
TRN_ID                          242859
ATT_ID                          697
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: 9699.25 17481 1163910
customer order: creation 181.40 358 21768
customer order: refuse 15.17 348 1820
order to supplier: creation 47.92 1127 5750
order to supplier: removal 3.68 96014 442
invoice (draft): creation 46.25 2925 5550
invoice (draft): removal 55.08 787 6610
invoice accept: apply 41.88 16788 5026
invoice accept: cancel 50.09 731 6011
customer reserve: creation 3964.18 163 475702
customer reserve: removal 4984.31 55 598117
realization accept: apply 85.85 170 10302
realization accept: cancel 90.24 102 10829
payment from customer: creation 61.02 117 7322
payment from customer: removal 6.13 96 736
payment to supplier: creation 49.98 123 5998
payment to supplier: removal 3.33 111 400
service: total inventory turnovers 5.13 923 615
service: total monetary turnovers 6.67 39 800
service: aggregate perf. data 0.90 20077 108
service: refresh index statistics 0.03 208572 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-07-2022 01:39:30 to 21-07-2022 01:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2259
TEST_TIME 2 2841
TEST_TIME 3 3322
TEST_TIME 4 3054
TEST_TIME 5 3897
TEST_TIME 6 3794
TEST_TIME 7 3741
TEST_TIME 8 3953
TEST_TIME 9 4372
TEST_TIME 10 4906
TEST_TIME 11 5523
TEST_TIME 12 6171
TEST_TIME 13 6494
TEST_TIME 14 6643
TEST_TIME 15 6795
TEST_TIME 16 6902
TEST_TIME 17 7347
TEST_TIME 18 7783
TEST_TIME 19 8613
TEST_TIME 20 9119
TEST_TIME 21 9155
TEST_TIME 22 9193
TEST_TIME 23 9438
TEST_TIME 24 9558
TEST_TIME 25 10526
TEST_TIME 26 11119
TEST_TIME 27 10963
TEST_TIME 28 11252
TEST_TIME 29 11051
TEST_TIME 30 11239
TEST_TIME 31 11215
TEST_TIME 32 11198
TEST_TIME 33 10984
TEST_TIME 34 10695
TEST_TIME 35 10714
TEST_TIME 36 10536
TEST_TIME 37 10424
TEST_TIME 38 10386
TEST_TIME 39 10447
TEST_TIME 40 10531
TEST_TIME 41 10463
TEST_TIME 42 10453
TEST_TIME 43 10319
TEST_TIME 44 10346
TEST_TIME 45 10208
TEST_TIME 46 10124
TEST_TIME 47 10122
TEST_TIME 48 10064
TEST_TIME 49 10025
TEST_TIME 50 9990
TEST_TIME 51 9956
TEST_TIME 52 10009
TEST_TIME 53 9962
TEST_TIME 54 9890
TEST_TIME 55 9990
TEST_TIME 56 10063
TEST_TIME 57 10071
TEST_TIME 58 10147
TEST_TIME 59 10122
TEST_TIME 60 10185
TEST_TIME 61 10250
TEST_TIME 62 10256
TEST_TIME 63 10314
TEST_TIME 64 10277
TEST_TIME 65 10169
TEST_TIME 66 10085
TEST_TIME 67 10034
TEST_TIME 68 9986
TEST_TIME 69 10019
TEST_TIME 70 9951
TEST_TIME 71 9893
TEST_TIME 72 9895
TEST_TIME 73 9933
TEST_TIME 74 9917
TEST_TIME 75 9900
TEST_TIME 76 9921
TEST_TIME 77 9986
TEST_TIME 78 9984
TEST_TIME 79 9982
TEST_TIME 80 10054
TEST_TIME 81 9987
TEST_TIME 82 10028
TEST_TIME 83 10013
TEST_TIME 84 10112
TEST_TIME 85 10117
TEST_TIME 86 10047
TEST_TIME 87 10039
TEST_TIME 88 9954
TEST_TIME 89 9912
TEST_TIME 90 9944
TEST_TIME 91 9899
TEST_TIME 92 9816
TEST_TIME 93 9792
TEST_TIME 94 9856
TEST_TIME 95 9819
TEST_TIME 96 9814
TEST_TIME 97 9887
TEST_TIME 98 9856
TEST_TIME 99 9857
TEST_TIME 100 9868
TEST_TIME 101 9869
TEST_TIME 102 9853
TEST_TIME 103 9880
TEST_TIME 104 9946
TEST_TIME 105 9921
TEST_TIME 106 9983
TEST_TIME 107 9922
TEST_TIME 108 9860
TEST_TIME 109 9851
TEST_TIME 110 9833
TEST_TIME 111 9799
TEST_TIME 112 9772
TEST_TIME 113 9753
TEST_TIME 114 9720
TEST_TIME 115 9729
TEST_TIME 116 9700
TEST_TIME 117 9727
TEST_TIME 118 9782
TEST_TIME 119 9763
TEST_TIME 120 9700

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-07-2022 01:39:34 to 21-07-2022 01:39:37.

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 1604621 1604621 0 0.00 0 26735 6 0 0
doc_list_biud 1604621 1604621 0 0.00 0 37499 26 0 0
sp_add_invoice_to_stock 5218 5026 192 3.67 40 192125 16788 0 192
sp_cancel_adding_invoice 6011 6011 0 0.00 4 75334 731 0 0
sp_cancel_client_order 1820 1820 0 0.00 4 17253 348 0 0
sp_cancel_customer_reserve 598117 598117 0 0.00 0 37473 55 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 2 5466 96 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 25139 112 0 0
sp_cancel_supplier_invoice 6629 6610 19 0.28 1 20440 787 0 19
sp_cancel_supplier_order 442 442 0 0.00 6 983451 96014 0 0
sp_cancel_write_off 10829 10829 0 0.00 1 16954 103 0 0
sp_client_order 21768 21768 0 0.00 3 37461 359 0 0
sp_customer_reserve 475702 475702 0 0.00 0 77824 164 0 0
sp_fill_shopping_cart 33068 33068 0 0.00 1 11829 34 0 0
sp_fill_shopping_cart_clo_res 475699 475699 0 0.00 0 24289 13 0 0
sp_get_clo_for_invoice 5023 5023 0 0.00 0 19313 305 0 0
sp_kill_cost_storno 17976 17976 0 0.00 0 17308 56 0 0
sp_kill_qstorno_ret_qs2qd 1214115 1214115 0 0.00 0 37439 30 0 0
sp_kill_qty_storno 639154 639154 0 0.00 0 37497 65 0 0
sp_lock_selected_doc 21327 21327 0 0.00 0 8416 5 0 0
sp_make_cost_storno 28645 28645 0 0.00 0 25525 46 0 0
sp_multiply_rows_for_pdistr 28645 28645 0 0.00 0 12033 29 0 0
sp_multiply_rows_for_qdistr 21768 21768 0 0.00 0 37433 181 0 0
sp_pay_from_customer 7322 7322 0 0.00 4 23137 118 0 0
sp_pay_to_supplier 5998 5998 0 0.00 4 17643 124 0 0
sp_payment_common 13320 13320 0 0.00 1 17784 28 0 0
sp_qd_handle_on_cancel_clo 1820 1820 0 0.00 0 17247 323 0 0
sp_qd_handle_on_invoice_upd_sts 11034 11034 0 0.00 0 37494 323 0 0
sp_qd_handle_on_reserve_upd_sts 10302 10302 0 0.00 0 34452 58 0 0
sp_reserve_write_off 10302 10302 0 0.00 6 34481 170 0 0
sp_supplier_invoice 5550 5550 0 0.00 5 93685 2926 0 0
sp_supplier_order 5750 5750 0 0.00 4 29186 1128 0 0
srv_aggregate_perf_data 108 108 0 0.00 2788 201124 20078 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2128 105202 45125 0 0
srv_find_qd_qs_mism 1114661 1114661 0 0.00 0 16153 5 0 0
srv_make_invnt_saldo 615 615 0 0.00 34 22944 923 0 0
srv_make_money_saldo 800 800 0 0.00 1 6673 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 128539 314326 208573 0 0
t$perf-abend:sp_add_invoice_to_stock 192 192 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5027 5027 0 0.00 0 14384 172 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 4740 87 0 0
t$perf-norm:sp_cancel_client_order 1820 1820 0 0.00 0 4181 15 0 0
t$perf-norm:sp_cancel_customer_reserve 744 744 0 0.00 0 1137 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 3001 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 2966 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 3819 21 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 30310 1580 0 0
t$perf-norm:sp_cancel_write_off 1066 1066 0 0.00 0 1512 8 0 0
t$perf-norm:sp_client_order 21768 21768 0 0.00 0 9932 15 0 0
t$perf-norm:sp_customer_reserve 1068 1068 0 0.00 0 1730 5 0 0
t$perf-norm:sp_pay_from_customer 7322 7322 0 0.00 0 7589 11 0 0
t$perf-norm:sp_pay_to_supplier 5998 5998 0 0.00 0 7795 10 0 0
t$perf-norm:sp_reserve_write_off 10302 10302 0 0.00 0 7582 8 0 0
t$perf-norm:sp_supplier_invoice 5551 5551 0 0.00 0 7294 18 0 0
t$perf-norm:sp_supplier_order 5750 5750 0 0.00 0 5948 12 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 299 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 34 1 0 0
t$perf-norm:srv_make_invnt_saldo 615 615 0 0.00 0 1077 17 0 0
t$perf-norm:srv_make_money_saldo 800 800 0 0.00 0 4288 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 1005 253 0 0
v_add_invoice_to_stock 10430 10046 384 3.68 0 9078 19 0 384
v_all_customers 39322 39322 0 0.00 0 20 0 0 0
v_all_suppliers 31754 31754 0 0.00 0 20 1 0 0
v_all_wares 43536 43536 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 97 5 0 0
v_cancel_client_order 3640 3640 0 0.00 0 2473 5 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 5102 13 0 0
v_cancel_customer_reserve 1488 1488 0 0.00 0 3520 7 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 25130 34 0 0
v_cancel_supplier_invoice 804 766 38 4.72 0 3592 19 0 38
v_cancel_supplier_order 884 884 0 0.00 0 3264 11 0 0
v_cancel_write_off 2132 2132 0 0.00 0 3837 14 0 0
v_max_id_clo_ord 5750 5750 0 0.00 0 2806 2 0 0
v_max_id_clo_res 1068 1068 0 0.00 0 3520 20 0 0
v_max_id_ord_sup 5549 5549 0 0.00 0 7517 4 0 0
v_max_non_paid_invoice 1420 1420 0 0.00 0 906 3 0 0
v_max_non_paid_realizn 3957 3957 0 0.00 0 7849 7 0 0
v_min_id_clo_ord 5750 5750 0 0.00 0 9005 10 0 0
v_min_id_clo_res 1068 1068 0 0.00 0 5886 13 0 0
v_min_id_ord_sup 5549 5549 0 0.00 0 6358 7 0 0
v_min_non_paid_invoice 5998 5998 0 0.00 0 10907 19 0 0
v_min_non_paid_realizn 7322 7322 0 0.00 0 6794 18 0 0
v_our_firm 10944 10944 0 0.00 0 20 1 0 0
v_reserve_write_off 20604 20604 0 0.00 0 10546 7 0 0
x_lock_depdocs_on_canc_invoice 12238 12238 0 0.00 0 18924 106 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 5679 324 0 0
x_make_qstorno_1000_1200 5750 5750 0 0.00 1 29176 1056 0 0
x_make_qstorno_1200_2000 5550 5550 0 0.00 2 91442 2804 0 0
x_make_qstorno_2100_3300 475702 475702 0 0.00 0 77823 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-07-2022 01:39:38 to 21-07-2022 01:39:42.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:25 5 8192000000 8800787488 8812793856 6653936 0.081 3 1 2 0 6754864 58144 42784
23:09:26 30928 8192000000 9912300400 10224136192 989661328 10.778 100 92 184 0 1020272656 6366832 24244496
23:11:02 17204 8192000000 9949008416 10272759808 1035689504 11.223 100 86 172 0 1066241296 8508352 22043440
23:12:26 14436 8192000000 9986537776 10317545472 1069551680 11.548 100 88 174 2 1100479984 9608784 21319520
23:13:45 8474 8192000000 10032343456 10382901248 1122638512 12.052 100 76 152 0 1150839568 10394048 17807008
23:14:58 9656 8192000000 10083996752 10445090816 1173004624 12.525 100 75 150 0 1202342944 12618384 16719936
23:16:10 4469 8192000000 10125935056 10496438272 1221789328 12.978 100 81 162 0 1252948416 13462464 17696624
23:17:18 2410 8192000000 10164236640 10540048384 1252829312 13.264 100 86 170 0 1285689376 14692864 18167200
23:18:22 2337 8192000000 10195675536 10579472384 1280640000 13.519 100 80 158 0 1311273984 13307104 17326880
23:19:26 1289 8192000000 10215906128 10601979904 1299834048 13.694 100 90 179 0 1333520048 13948768 19737232
23:20:30 1725 8192000000 10217600048 10612727808 1307446480 13.763 100 71 141 1 1331884896 8995344 15443072
23:21:33 9419 8192000000 10199743280 10614157312 1317793168 13.857 100 25 50 0 1329195552 5915792 5486592
23:22:49 31644 8192000000 10210391824 10623832064 1320474720 13.881 100 29 56 0 1333333600 6213424 6645456
23:24:21 10545 8192000000 10229302272 10642862080 1330177264 13.969 100 21 42 0 1340695072 5768512 4749296
23:25:35 10734 8192000000 10223067952 10644361216 1336601472 14.027 100 18 36 0 1347141872 5841072 4699328
23:26:47 54696 8192000000 10240017456 10653773824 1342791840 14.083 100 31 60 1 1355035168 5252048 6991280
23:28:42 4704 8192000000 10209016368 10623201280 1318879920 13.867 100 24 48 0 1330255056 5550464 5824672
23:29:48 10531 8192000000 10206345072 10618970112 1301144656 13.706 99 23 45 1 1312314240 6116240 5053344
23:31:01 64979 8192000000 10103982352 10498912256 1163699632 12.438 92 32 62 0 1175988000 4653936 7634432
23:33:07 7093 8192000000 10137667152 10541707264 1230567680 13.059 98 24 49 1 1242200368 5596736 6035952
23:34:14 5757 8192000000 10165291632 10570952704 1274678400 13.464 100 32 65 2 1292030640 9788176 7564064
23:35:21 72372 8192000000 10180575760 10598105088 1293961344 13.640 100 22 45 1 1304101488 4617472 5522672
23:37:35 6627 8192000000 10201347056 10617122816 1308948992 13.777 100 31 62 1 1321778448 6090080 6739376
23:38:44 18370 8192000000 10245630832 10655662080 1335644560 14.018 100 42 82 0 1350749952 5296448 9808944
23:40:12 10241 8192000000 10265784416 10678489088 1355500272 14.197 100 56 110 0 1379458112 11589776 12368064
23:41:25 105202 8192000000 10287660496 10717990912 1373585104 14.359 100 36 72 0 1403039808 20641232 8813472
23:44:23 22905 8192000000 10295653456 10724392960 1383368688 14.447 100 29 58 0 1412902816 22770352 6763776
23:45:53 6916 8192000000 10333731216 10751823872 1401177584 14.605 100 68 137 2 1445667088 27542944 16946560
23:47:04 12839 8192000000 10335250912 10765565952 1410989120 14.693 100 44 88 0 1450793712 29279856 10524736
23:48:25 3628 8192000000 10358918576 10774925312 1413937328 14.719 100 67 134 1 1467290336 36645344 16707664
23:49:32 4369 8192000000 10369007760 10787778560 1423473248 14.803 100 69 137 0 1478340944 38026624 16841072
23:50:40 3532 8192000000 10386141680 10809561088 1432979184 14.888 100 72 141 0 1494064352 43936400 17148768
23:51:49 2363 8192000000 10382545200 10797588480 1429602512 14.858 100 88 176 1 1487501568 35092512 22806544
23:53:02 2354 8192000000 10380770352 10796281856 1424076192 14.809 99 77 150 0 1486660016 43321056 19262768
23:54:08 2716 8192000000 10394733584 10808385536 1428999456 14.852 100 84 169 1 1494271616 43949968 21322192
23:55:15 4335 8192000000 10388991296 10812067840 1428364992 14.847 100 62 122 1 1494013232 49488016 16160224
23:57:15 2666 8192000000 10338503168 10756476928 1373666752 14.360 99 72 142 0 1441381472 49992096 17722624
23:58:21 11710 8192000000 10345433552 10767953920 1379827744 14.415 99 51 100 2 1433375296 40434464 13113088
23:59:36 23454 8192000000 10325930816 10757050368 1375885360 14.380 99 30 61 1 1424473040 39944960 8642720
00:01:06 23909 8192000000 10316300736 10750877696 1379897104 14.416 100 22 44 0 1429978080 43541088 6539888
00:02:39 16924 8192000000 10318667936 10755977216 1363578800 14.269 100 25 51 0 1429713744 58744880 7390064
00:03:58 16786 8192000000 10296312416 10731683840 1351661024 14.162 99 24 48 1 1391328576 32820128 6847424
00:05:18 63726 8192000000 10273266720 10732027904 1328436656 13.953 97 17 33 1 1353934400 21255904 4241840
00:07:22 46887 8192000000 10280865760 10730532864 1363548384 14.269 100 21 41 0 1378405264 10014528 4842352
00:09:09 50000 8192000000 10292378176 10742890496 1368694576 14.315 99 16 32 1 1384353904 12710208 2949120
00:11:16 92743 8192000000 10303655600 10732974080 1361980160 14.255 98 36 72 3 1379802976 9701440 8121376
00:13:50 98391 8192000000 10303420096 10746994688 1373739984 14.361 99 40 80 1 1402406032 19254848 9411200
00:16:31 68067 8192000000 10300055008 10749468672 1359343584 14.231 98 19 38 0 1381982480 17833568 4805328
00:18:39 30986 8192000000 10283961680 10727104512 1353787136 14.182 99 43 87 0 1376090416 12334496 9968784
00:20:16 53667 8192000000 10271785248 10735218688 1373004032 14.354 100 13 26 0 1385945024 9815280 3125712
00:22:10 98054 8192000000 10292393072 10753011712 1392845088 14.531 100 17 32 0 1409150704 12502992 3802624
00:24:49 64763 8192000000 10295342864 10750210048 1388386512 14.491 100 25 48 0 1410112768 16692080 5034176
00:26:54 43511 8192000000 10291077792 10752888832 1391975312 14.523 100 12 24 0 1403314864 8591792 2747760
00:28:39 74028 8192000000 10286347408 10751643648 1379348400 14.411 99 13 26 0 1394543824 12515120 2680304
00:31:01 51133 8192000000 10304389376 10763370496 1383279152 14.446 99 14 28 0 1396946176 10675840 2991184
00:32:52 23877 8192000000 10319250720 10775166976 1400892304 14.603 100 34 68 0 1422750928 13662432 8196192
00:34:23 45188 8192000000 10286783696 10743996416 1353856512 14.182 97 10 20 0 1366351552 10232576 2262464
00:36:17 46672 8192000000 10288383232 10748366848 1371371792 14.339 99 23 46 0 1385333552 8666112 5295648
00:38:17 52240 8192000000 10289987824 10739998720 1326128288 13.932 95 25 50 1 1344997168 13312960 5555920
00:40:24 47747 8192000000 10277567344 10730459136 1331698768 13.982 97 27 54 0 1348771600 10950368 6122464
00:42:12 79903 8192000000 10271718320 10723508224 1353913232 14.183 99 9 18 0 1377923632 22079264 1931136
00:45:10 36192 8192000000 10256427424 10709155840 1329214304 13.960 98 22 44 0 1346871936 12825184 4832448
00:46:46 63948 8192000000 10266313792 10735775744 1356094256 14.202 99 17 34 0 1374567808 14279376 4194176
00:49:14 60660 8192000000 10267424304 10716651520 1331884368 13.984 98 10 20 0 1348836800 14568256 2384176
00:51:23 62444 8192000000 10250015008 10709753856 1314636304 13.828 97 14 28 0 1339464048 21534368 3293376
00:53:26 93664 8192000000 10265009408 10717167616 1321657008 13.892 97 23 46 0 1343237536 15984352 5596176
00:56:31 28100 8192000000 10260986768 10715521024 1348416848 14.133 99 37 74 0 1366455568 9391264 8647456
00:58:10 72001 8192000000 10274534384 10735984640 1345269696 14.105 98 18 36 0 1364606432 15106128 4230608
01:00:25 43150 8192000000 10292411904 10735456256 1353563504 14.180 98 30 60 0 1370842672 10980192 6298976
01:02:10 93822 8192000000 10292020816 10745790464 1366493552 14.296 99 22 44 0 1386700896 15226784 4980560
01:04:46 71913 8192000000 10271764416 10733166592 1369692048 14.324 99 10 20 0 1383831536 12019648 2119840
01:07:00 20862 8192000000 10287194016 10731401216 1370047808 14.327 100 40 80 2 1393522896 14714320 8760768
01:08:24 2127 8192000000 10301281072 10753200128 1388484512 14.492 100 43 79 1 1402405408 5064464 8856432
01:09:26 73233 8192000000 10306028656 10770767872 1401536400 14.609 100 9 18 1 1412989216 9438256 2014560
01:11:40 73561 8192000000 10309335168 10771873792 1380820288 14.424 98 11 22 0 1398831216 15232368 2778560
01:13:57 30136 8192000000 10314854432 10769936384 1401250928 14.606 100 33 63 0 1416209408 7940272 7018208
01:15:30 93623 8192000000 10324792096 10782740480 1390481184 14.510 99 15 30 0 1411441632 17528512 3431936
01:18:11 48698 8192000000 10325654912 10774511616 1393022432 14.533 99 31 61 0 1408236016 8764336 6449248
01:20:03 19924 8192000000 10297470464 10740883456 1354060016 14.184 97 31 61 0 1369587296 9204144 6323136
01:21:26 36547 8192000000 10296686928 10742251520 1356111456 14.202 98 36 72 0 1377664688 13971312 7581920
01:23:07 63533 8192000000 10288737712 10736766976 1329396848 13.962 96 20 38 0 1345320944 11593712 4330384
01:25:23 83227 8192000000 10288307776 10745507840 1362160704 14.257 98 13 26 0 1377786272 12460320 3165248
01:27:46 43657 8192000000 10264536416 10720731136 1337567456 14.035 97 18 36 0 1352422000 10999936 3854608
01:29:34 42644 8192000000 10257706400 10717831168 1366394240 14.295 100 8 17 0 1375999328 7942848 1662240
01:31:18 59575 8192000000 10260566944 10715336704 1335814688 14.020 97 18 36 0 1350375280 10141136 4419456
01:33:21 86340 8192000000 10269002384 10731122688 1320521152 13.881 96 8 16 0 1337409744 15173872 1714720
01:35:48 71130 8192000000 10276263600 10729897984 1344522816 14.098 98 20 40 0 1362839328 13221792 5094720
01:38:01 31535 8192000000 9029969120 9242324992 193155440 2.303 15 6 12 0 201737376 7364304 1217632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-07-2022 01:39:44 to 21-07-2022 01:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 192 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-07-2022 01:40:00 to 21-07-2022 01:40:02.

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          243170
MON$OLDEST_ACTIVE               243171
MON$OLDEST_SNAPSHOT             243171
MON$NEXT_TRANSACTION            243171
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                       2029088
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.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 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'

21.07.22 01:40:03. 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-21 01:40:05 No severe PSQL-related problems occured
21.07.22 01:40:06. End of report.