Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.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 15807296 kB
MemAvailable 30449616 kB
Buffers 0 kB
Cached 15894488 kB
SwapCached 1924 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-21 01:38:27
DTS_END                         2022-10-21 01:38:27.8900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53928
TRN_ID                          240616
ATT_ID                          648
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: 9066.67 23413 1088000
customer order: creation 172.14 441 20657
customer order: refuse 14.66 390 1759
order to supplier: creation 45.94 1260 5513
order to supplier: removal 3.42 94741 410
invoice (draft): creation 45.73 3386 5487
invoice (draft): removal 51.41 773 6169
invoice accept: apply 41.45 17345 4974
invoice accept: cancel 45.93 672 5512
customer reserve: creation 3811.90 175 457428
customer reserve: removal 4544.27 56 545312
realization accept: apply 83.44 166 10013
realization accept: cancel 75.43 103 9051
payment from customer: creation 59.95 102 7194
payment from customer: removal 5.59 66 671
payment to supplier: creation 49.67 110 5960
payment to supplier: removal 3.53 60 423
service: total inventory turnovers 4.78 999 574
service: total monetary turnovers 6.63 18 795
service: aggregate perf. data 0.78 20404 94
service: refresh index statistics 0.03 326988 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-10-2022 01:39:25 to 21-10-2022 01:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1054
TEST_TIME 2 2202
TEST_TIME 3 3049
TEST_TIME 4 3380
TEST_TIME 5 3473
TEST_TIME 6 4228
TEST_TIME 7 4569
TEST_TIME 8 4879
TEST_TIME 9 4960
TEST_TIME 10 5203
TEST_TIME 11 5258
TEST_TIME 12 5461
TEST_TIME 13 5643
TEST_TIME 14 5828
TEST_TIME 15 6185
TEST_TIME 16 6949
TEST_TIME 17 7963
TEST_TIME 18 8168
TEST_TIME 19 8565
TEST_TIME 20 9357
TEST_TIME 21 9783
TEST_TIME 22 10323
TEST_TIME 23 10341
TEST_TIME 24 9944
TEST_TIME 25 9768
TEST_TIME 26 10114
TEST_TIME 27 9939
TEST_TIME 28 9819
TEST_TIME 29 9769
TEST_TIME 30 9789
TEST_TIME 31 9742
TEST_TIME 32 9658
TEST_TIME 33 9430
TEST_TIME 34 9333
TEST_TIME 35 9499
TEST_TIME 36 9398
TEST_TIME 37 9309
TEST_TIME 38 9299
TEST_TIME 39 9176
TEST_TIME 40 9126
TEST_TIME 41 9012
TEST_TIME 42 8871
TEST_TIME 43 9008
TEST_TIME 44 9019
TEST_TIME 45 8985
TEST_TIME 46 9023
TEST_TIME 47 8971
TEST_TIME 48 8970
TEST_TIME 49 9043
TEST_TIME 50 9052
TEST_TIME 51 9020
TEST_TIME 52 9026
TEST_TIME 53 9043
TEST_TIME 54 9169
TEST_TIME 55 9268
TEST_TIME 56 9267
TEST_TIME 57 9206
TEST_TIME 58 9214
TEST_TIME 59 9106
TEST_TIME 60 9078
TEST_TIME 61 8992
TEST_TIME 62 8976
TEST_TIME 63 9052
TEST_TIME 64 8950
TEST_TIME 65 8934
TEST_TIME 66 9000
TEST_TIME 67 8978
TEST_TIME 68 8898
TEST_TIME 69 8940
TEST_TIME 70 8912
TEST_TIME 71 9100
TEST_TIME 72 9162
TEST_TIME 73 9225
TEST_TIME 74 9173
TEST_TIME 75 9238
TEST_TIME 76 9245
TEST_TIME 77 9263
TEST_TIME 78 9284
TEST_TIME 79 9204
TEST_TIME 80 9174
TEST_TIME 81 9090
TEST_TIME 82 9116
TEST_TIME 83 9130
TEST_TIME 84 9076
TEST_TIME 85 9028
TEST_TIME 86 8962
TEST_TIME 87 8976
TEST_TIME 88 9036
TEST_TIME 89 9023
TEST_TIME 90 9010
TEST_TIME 91 9083
TEST_TIME 92 9092
TEST_TIME 93 9130
TEST_TIME 94 9124
TEST_TIME 95 9104
TEST_TIME 96 9108
TEST_TIME 97 9174
TEST_TIME 98 9229
TEST_TIME 99 9228
TEST_TIME 100 9217
TEST_TIME 101 9248
TEST_TIME 102 9168
TEST_TIME 103 9144
TEST_TIME 104 9125
TEST_TIME 105 9147
TEST_TIME 106 9100
TEST_TIME 107 9071
TEST_TIME 108 9058
TEST_TIME 109 9011
TEST_TIME 110 8956
TEST_TIME 111 8983
TEST_TIME 112 9025
TEST_TIME 113 9011
TEST_TIME 114 8985
TEST_TIME 115 8983
TEST_TIME 116 9033
TEST_TIME 117 9086
TEST_TIME 118 9083
TEST_TIME 119 9088
TEST_TIME 120 9070

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-10-2022 01:39:28 to 21-10-2022 01:39:32.

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 1511891 1511891 0 0.00 0 21245 6 0 0
doc_list_biud 1511891 1511891 0 0.00 0 32652 26 0 0
sp_add_invoice_to_stock 5159 4974 185 3.58 44 155218 17345 0 185
sp_cancel_adding_invoice 5512 5512 0 0.00 5 59912 673 0 0
sp_cancel_client_order 1759 1759 0 0.00 5 16391 391 0 0
sp_cancel_customer_reserve 545312 545312 0 0.00 0 32655 56 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 2 3536 66 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 2573 60 0 0
sp_cancel_supplier_invoice 6177 6169 8 0.12 1 25980 774 0 8
sp_cancel_supplier_order 410 410 0 0.00 5 963634 94742 0 0
sp_cancel_write_off 9051 9051 0 0.00 1 13722 104 0 0
sp_client_order 20657 20657 0 0.00 3 33625 442 0 0
sp_customer_reserve 457428 457428 0 0.00 0 53047 175 0 0
sp_fill_shopping_cart 31635 31635 0 0.00 1 14559 37 0 0
sp_fill_shopping_cart_clo_res 457425 457425 0 0.00 0 19923 16 0 0
sp_get_clo_for_invoice 4967 4967 0 0.00 0 22219 299 0 0
sp_kill_cost_storno 15657 15657 0 0.00 0 13408 51 0 0
sp_kill_qstorno_ret_qs2qd 1106254 1106254 0 0.00 0 32647 31 0 0
sp_kill_qty_storno 583191 583191 0 0.00 0 32651 66 0 0
sp_lock_selected_doc 20710 20710 0 0.00 0 8296 6 0 0
sp_make_cost_storno 28132 28132 0 0.00 0 21192 47 0 0
sp_multiply_rows_for_pdistr 28132 28132 0 0.00 0 13280 24 0 0
sp_multiply_rows_for_qdistr 20657 20657 0 0.00 0 14603 185 0 0
sp_pay_from_customer 7194 7194 0 0.00 4 16115 102 0 0
sp_pay_to_supplier 5960 5960 0 0.00 4 20869 110 0 0
sp_payment_common 13154 13154 0 0.00 1 7182 20 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 16385 352 0 0
sp_qd_handle_on_invoice_upd_sts 10477 10477 0 0.00 0 31214 287 0 0
sp_qd_handle_on_reserve_upd_sts 10013 10013 0 0.00 0 12621 65 0 0
sp_reserve_write_off 10013 10013 0 0.00 6 15685 166 0 0
sp_supplier_invoice 5487 5487 0 0.00 7 109944 3386 0 0
sp_supplier_order 5513 5513 0 0.00 4 36932 1261 0 0
srv_aggregate_perf_data 94 94 0 0.00 2114 196403 20404 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1509 126108 45864 0 0
srv_find_qd_qs_mism 1040427 1040427 0 0.00 0 16110 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 41 65997 999 0 0
srv_make_money_saldo 795 795 0 0.00 1 561 19 0 0
srv_recalc_idx_stat 4 4 0 0.00 172880 471880 326989 0 0
t$perf-abend:sp_add_invoice_to_stock 185 185 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4974 4974 0 0.00 0 40982 160 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 5455 88 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 5652 18 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 592 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 1113 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 4291 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 5844 29 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 24127 1403 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 7431 13 0 0
t$perf-norm:sp_client_order 20657 20657 0 0.00 0 16358 16 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 3955 8 0 0
t$perf-norm:sp_pay_from_customer 7194 7194 0 0.00 0 7084 12 0 0
t$perf-norm:sp_pay_to_supplier 5960 5960 0 0.00 0 7072 15 0 0
t$perf-norm:sp_reserve_write_off 10013 10013 0 0.00 0 10251 11 0 0
t$perf-norm:sp_supplier_invoice 5487 5487 0 0.00 0 5270 13 0 0
t$perf-norm:sp_supplier_order 5513 5513 0 0.00 0 4631 12 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 547 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1121 22 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 985 22 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 3388 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10300 9930 370 3.59 0 6451 17 0 370
v_all_customers 37592 37592 0 0.00 0 17 0 0 0
v_all_suppliers 30890 30890 0 0.00 0 18 1 0 0
v_all_wares 41314 41314 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 320 6 0 0
v_cancel_client_order 3518 3518 0 0.00 0 8449 10 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 3488 9 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 3347 9 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1379 6 0 0
v_cancel_supplier_invoice 760 744 16 2.10 0 5293 21 0 16
v_cancel_supplier_order 820 820 0 0.00 0 302 3 0 0
v_cancel_write_off 1930 1930 0 0.00 0 6226 18 0 0
v_max_id_clo_ord 5512 5512 0 0.00 0 218 1 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 5637 25 0 0
v_max_id_ord_sup 5466 5466 0 0.00 0 4829 5 0 0
v_max_non_paid_invoice 1493 1493 0 0.00 0 328 3 0 0
v_max_non_paid_realizn 3872 3872 0 0.00 0 7177 6 0 0
v_min_id_clo_ord 5512 5512 0 0.00 0 3715 7 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 4087 17 0 0
v_min_id_ord_sup 5466 5466 0 0.00 0 5886 10 0 0
v_min_non_paid_invoice 5960 5960 0 0.00 0 3192 12 0 0
v_min_non_paid_realizn 7194 7194 0 0.00 0 7143 15 0 0
v_our_firm 10366 10366 0 0.00 0 22 1 0 0
v_reserve_write_off 20026 20026 0 0.00 0 5704 7 0 0
x_lock_depdocs_on_canc_invoice 11309 11309 0 0.00 0 19209 109 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 4454 298 0 0
x_make_qstorno_1000_1200 5513 5513 0 0.00 1 36923 1196 0 0
x_make_qstorno_1200_2000 5487 5487 0 0.00 3 109937 3245 0 0
x_make_qstorno_2100_3300 457428 457428 0 0.00 0 53047 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2022 01:39:33 to 21-10-2022 01:39:37.

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:31 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:33 35360 8192000000 9921471312 10234929152 995978528 10.840 100 92 184 0 1026515040 6213360 24323152
23:11:14 18681 8192000000 9958310192 10279862272 1037148224 11.237 100 95 190 0 1070720784 8989536 24583024
23:12:35 15979 8192000000 9978699584 10309767168 1060621792 11.462 100 90 180 0 1093406192 9903248 22881152
23:14:10 5567 8192000000 10032437248 10380865536 1124440192 12.069 100 74 146 1 1151956096 10151680 17364224
23:15:24 4366 8192000000 10094481232 10451972096 1174171120 12.536 100 88 176 1 1207537760 13071680 20294960
23:16:33 3439 8192000000 10138818864 10507087872 1222619936 12.986 100 81 161 0 1254719936 14166544 17933456
23:17:39 1444 8192000000 10165929568 10538950656 1249787744 13.236 100 86 170 0 1281575968 13867952 17920272
23:18:42 1203 8192000000 10184338048 10564833280 1273927840 13.458 100 87 172 1 1305980704 12578784 19474080
23:19:45 1145 8192000000 10213254960 10597548032 1296188240 13.661 100 85 171 0 1329044192 12893184 19962768
23:20:49 11256 8192000000 10214035104 10605350912 1302847552 13.721 100 75 148 0 1334636016 15476752 16311712
23:22:03 5312 8192000000 10200130512 10611458048 1313137008 13.815 100 29 56 0 1326652432 6786384 6729040
23:23:10 6955 8192000000 10205915792 10623930368 1323678272 13.910 100 26 51 1 1334246768 4654496 5914000
23:24:19 4049 8192000000 10226921856 10632843264 1334957440 14.012 100 42 84 1 1350116704 4733552 10425712
23:25:24 9186 8192000000 10242684672 10647355392 1332637712 13.991 100 62 123 0 1353777776 6345840 14794224
23:26:34 7609 8192000000 10223556560 10646007808 1337821856 14.038 100 18 36 0 1347805600 5993056 3990688
23:27:42 5726 8192000000 10235184992 10655080448 1343242352 14.087 100 23 44 1 1354468912 5861504 5365056
23:28:51 12108 8192000000 10205336608 10621120512 1277208528 13.488 96 31 63 1 1289954624 5212688 7533408
23:30:04 19020 8192000000 10195937536 10614743040 1263105696 13.358 95 10 20 0 1269800752 4701184 1993872
23:31:23 3764 8192000000 10121263904 10513633280 1197303728 12.751 96 31 61 1 1210305184 5905184 7096272
23:32:29 4513 8192000000 10140496752 10544828416 1224367600 13.002 97 23 46 0 1235620480 5598032 5654848
23:33:38 11754 8192000000 10159066400 10566778880 1266408016 13.389 100 32 63 2 1278598432 4554656 7635760
23:34:50 18199 8192000000 10175642784 10584670208 1283726192 13.547 100 27 51 1 1295810496 5647696 6436608
23:36:09 6733 8192000000 10194893184 10602598400 1295884480 13.658 100 38 77 1 1310788336 6106480 8797376
23:37:18 9586 8192000000 10208659152 10616844288 1309941456 13.786 100 28 54 0 1321355008 4818624 6594928
23:38:30 93619 8192000000 10239551280 10652405760 1334317856 14.006 100 45 91 0 1355393952 10104000 10972096
23:41:11 11866 8192000000 10275751088 10690232320 1359751136 14.235 100 60 119 2 1388941248 15545792 13644320
23:42:28 15098 8192000000 10283454192 10706276352 1375061056 14.372 100 38 76 0 1400251072 16015888 9174128
23:43:50 21286 8192000000 10296035552 10721914880 1385770848 14.468 100 44 86 1 1416131424 20140736 10219840
23:45:20 11535 8192000000 10320860368 10739171328 1397570624 14.573 100 47 90 0 1427364768 18500224 11293920
23:46:44 12998 8192000000 10318339968 10746933248 1393497296 14.537 99 40 78 0 1423390544 19719328 10173920
23:48:02 9137 8192000000 10344219424 10766606336 1405634144 14.645 99 57 114 0 1447377664 27712192 14031328
23:49:20 4424 8192000000 10372228528 10796642304 1434935312 14.905 100 55 110 2 1481650176 32567760 14147104
23:50:28 1657 8192000000 10378278144 10801278976 1436162704 14.916 100 65 128 1 1488822544 36265664 16394176
23:51:35 1507 8192000000 10387634112 10804514816 1433667056 14.894 100 79 158 0 1493174320 40105824 19401440
23:52:43 6326 8192000000 10385713760 10812661760 1433431536 14.892 100 56 111 2 1493703056 45563872 14707648
23:54:00 3768 8192000000 10380990688 10802040832 1414351776 14.723 99 81 162 3 1486309584 51156688 20801120
23:55:06 12415 8192000000 10354164688 10786869248 1373471344 14.358 97 46 92 1 1439180560 53716832 11992384
23:56:31 5900 8192000000 10318966080 10744868864 1377240640 14.392 99 41 82 2 1424911408 36440448 11230320
23:58:07 13071 8192000000 10293945472 10722652160 1347577808 14.126 99 29 58 1 1393205936 37886928 7741200
23:59:23 13157 8192000000 10266247008 10697822208 1328734656 13.956 99 19 35 0 1360317904 26924496 4658752
00:00:37 24245 8192000000 10242833104 10682982400 1316390400 13.844 98 18 36 0 1338824736 17743680 4690656
00:02:21 38467 8192000000 10279325952 10706870272 1359095920 14.229 100 57 114 1 1383879920 11107472 13676528
00:04:01 27455 8192000000 10262410928 10708226048 1346332816 14.114 100 28 56 0 1375673168 22054272 7286080
00:05:29 41909 8192000000 10251802016 10687119360 1317929584 13.858 97 22 45 0 1338567456 15470448 5167424
00:07:14 88110 8192000000 10253920176 10701074432 1305742448 13.747 96 13 27 1 1324008752 14921568 3344736
00:09:43 66752 8192000000 10261764928 10709712896 1359834240 14.236 100 34 68 1 1376845840 8881248 8130352
00:11:50 39803 8192000000 10267451952 10691997696 1322304912 13.898 97 44 84 2 1350501120 18382080 9814128
00:13:35 49595 8192000000 10258479584 10706190336 1357965712 14.219 100 11 22 0 1369413584 8944080 2503792
00:15:27 13043 8192000000 10249299440 10682466304 1336669648 14.027 99 25 47 0 1351983312 10725104 4588560
00:16:43 45849 8192000000 10257184016 10699661312 1326643088 13.937 97 17 34 0 1345886336 15402096 3841152
00:18:30 39180 8192000000 10273101632 10716004352 1365704512 14.289 100 14 28 1 1383282672 14231088 3347072
00:21:04 67502 8192000000 10268205568 10717941760 1368139984 14.310 100 21 42 0 1383989456 10746496 5102976
00:23:21 68788 8192000000 10266943936 10721607680 1375930016 14.380 100 8 16 0 1385716848 8234400 1552432
00:25:30 30816 8192000000 10296951568 10736300032 1371570416 14.341 99 42 83 3 1387805104 7377328 8857360
00:27:01 67187 8192000000 10274201248 10723229696 1348153024 14.131 97 11 22 0 1359700640 9419600 2128016
00:29:13 55188 8192000000 10314589824 10762973184 1396997472 14.568 100 26 53 0 1412077952 8883632 6196848
00:31:12 73657 8192000000 10301273504 10757095424 1348639968 14.135 96 15 30 0 1368821744 16792000 3389776
00:33:37 119409 8192000000 10297246624 10751700992 1372101504 14.346 98 9 18 0 1386966768 12897072 1968192
00:36:37 18389 8192000000 10307060672 10745925632 1371007056 14.336 98 46 92 1 1390816768 7796064 12013648
00:37:58 59082 8192000000 10299568496 10741772288 1369218848 14.320 98 50 99 0 1388251936 7846384 11186704
00:39:58 14378 8192000000 10291426032 10730303488 1343592672 14.090 96 49 97 0 1363644704 9392480 10659552
00:41:19 73581 8192000000 10253617424 10714906624 1325532720 13.927 96 12 24 0 1342016816 13679024 2805072
00:43:33 41299 8192000000 10259724480 10710044672 1344544608 14.098 99 11 22 0 1362440304 15281056 2614640
00:45:14 26816 8192000000 10296848320 10742427648 1384506752 14.457 100 24 49 1 1399346976 8623216 6217008
00:46:51 16502 8192000000 10313207328 10755067904 1370888736 14.335 98 56 112 1 1399193920 14768480 13536704
00:48:18 55938 8192000000 10300227728 10766602240 1389313952 14.500 100 15 30 1 1414139568 20969296 3856320
00:50:22 50047 8192000000 10289033152 10732900352 1348794080 14.137 98 24 48 0 1364564704 10382416 5388208
00:52:13 35791 8192000000 10272875232 10702901248 1289090240 13.596 94 34 67 0 1303999456 7380848 7528368
00:53:50 61668 8192000000 10273953120 10715222016 1326169856 13.933 98 28 55 1 1352391168 20337360 5883952
00:55:54 27521 8192000000 10284317072 10724216832 1363801520 14.271 100 33 68 0 1383932128 12682576 7448032
00:57:28 75757 8192000000 10238576064 10680696832 1287262624 13.579 95 21 42 0 1299814800 8143648 4408528
00:59:45 68073 8192000000 10268808064 10713948160 1335861552 14.020 98 38 76 1 1358688832 14391680 8435600
01:01:57 57183 8192000000 10231928016 10683379712 1314215152 13.824 97 8 16 0 1326071600 10115728 1740720
01:03:55 47385 8192000000 10254311664 10701975552 1345821104 14.110 99 17 33 0 1359841904 10575488 3445312
01:05:45 57480 8192000000 10264178160 10707427328 1333409408 13.998 98 11 22 0 1347428064 11583088 2435568
01:07:46 27533 8192000000 10257286944 10699526144 1330736240 13.974 98 22 44 1 1342883296 7705216 4441840
01:09:15 95091 8192000000 10272474112 10724499456 1347663408 14.126 98 18 36 0 1366356976 14395232 4298336
01:11:56 58342 8192000000 10285921456 10733703168 1368520224 14.314 99 15 30 0 1379418720 7949840 2948656
01:13:59 46574 8192000000 10303611536 10742497280 1382374704 14.438 100 42 84 0 1403578128 11581152 9622272
01:16:07 93845 8192000000 10311377744 10761605120 1390802496 14.513 100 16 30 0 1409927984 15992912 3132576
01:18:44 54760 8192000000 10317137488 10769747968 1400112656 14.596 100 25 50 0 1418563872 12579216 5872000
01:20:41 126107 8192000000 10310603088 10767196160 1374494048 14.367 98 27 54 0 1392983392 12618448 5870896
01:25:07 101601 8192000000 10265307120 10728062976 1338310688 14.042 95 11 22 0 1351342576 10286272 2745616
01:28:00 56094 8192000000 10305610160 10764943360 1387242096 14.481 99 13 26 0 1403431088 13298832 2890160
01:30:03 51123 8192000000 10267508976 10733993984 1317107632 13.851 95 21 42 0 1332985808 10484144 5394032
01:31:54 55683 8192000000 10270598912 10719870976 1318051056 13.859 95 18 36 0 1334732288 12051424 4629808
01:33:51 66137 8192000000 10251531136 10705649664 1295738544 13.656 95 11 22 0 1316854656 18358944 2757168
01:35:59 120735 8192000000 10284890256 10733649920 1329771184 13.965 97 19 38 0 1344058624 10221680 4065760

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-10-2022 01:39:39 to 21-10-2022 01:39:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 185 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2022 01:39:55 to 21-10-2022 01:39:57.

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          239056
MON$OLDEST_ACTIVE               240746
MON$OLDEST_SNAPSHOT             240746
MON$NEXT_TRANSACTION            240746
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                       1985600
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.33636 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33636 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33636 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33636 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 ---
:45: 
:46: mscs-test-161.budzdorov.ru    Fri Oct 21 01:38:32 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Fri Oct 21 01:38:34 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Fri Oct 21 01:38:34 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
--- end of diff output ---

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

21.10.22 01:39:58. 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-21 01:40:01 No severe PSQL-related problems occured
21.10.22 01:40:02. End of report.