Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.10.2022 16: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 15483452 kB
MemAvailable 30526916 kB
Buffers 0 kB
Cached 16207080 kB
SwapCached 2040 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-05 16:38:34
DTS_END                         2022-10-05 16:38:34.4750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43350
TRN_ID                          253337
ATT_ID                          659
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: 9322.01 16527 1118641
customer order: creation 188.66 334 22639
customer order: refuse 16.38 403 1966
order to supplier: creation 50.18 1273 6022
order to supplier: removal 3.43 95370 411
invoice (draft): creation 49.75 2923 5970
invoice (draft): removal 51.19 769 6143
invoice accept: apply 45.54 17852 5465
invoice accept: cancel 45.73 798 5487
customer reserve: creation 4128.97 181 495476
customer reserve: removal 4427.82 58 531338
realization accept: apply 92.02 176 11042
realization accept: cancel 81.03 115 9723
payment from customer: creation 64.69 107 7763
payment from customer: removal 6.10 85 732
payment to supplier: creation 53.23 126 6387
payment to supplier: removal 3.84 78 461
service: total inventory turnovers 5.25 1090 630
service: total monetary turnovers 7.19 27 863
service: aggregate perf. data 0.99 15915 119
service: refresh index statistics 0.03 192866 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-10-2022 16:39:23 to 05-10-2022 16:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6640
TEST_TIME 2 4596
TEST_TIME 3 5009
TEST_TIME 4 5425
TEST_TIME 5 5584
TEST_TIME 6 5695
TEST_TIME 7 5854
TEST_TIME 8 5675
TEST_TIME 9 5876
TEST_TIME 10 5680
TEST_TIME 11 5798
TEST_TIME 12 6082
TEST_TIME 13 6629
TEST_TIME 14 7102
TEST_TIME 15 7365
TEST_TIME 16 7266
TEST_TIME 17 7564
TEST_TIME 18 7745
TEST_TIME 19 7892
TEST_TIME 20 8237
TEST_TIME 21 8410
TEST_TIME 22 8485
TEST_TIME 23 8662
TEST_TIME 24 8820
TEST_TIME 25 8951
TEST_TIME 26 9750
TEST_TIME 27 9785
TEST_TIME 28 10043
TEST_TIME 29 9956
TEST_TIME 30 10486
TEST_TIME 31 10820
TEST_TIME 32 10671
TEST_TIME 33 10876
TEST_TIME 34 10618
TEST_TIME 35 10368
TEST_TIME 36 10279
TEST_TIME 37 10155
TEST_TIME 38 10161
TEST_TIME 39 10020
TEST_TIME 40 9891
TEST_TIME 41 9880
TEST_TIME 42 9815
TEST_TIME 43 9728
TEST_TIME 44 9818
TEST_TIME 45 9819
TEST_TIME 46 9682
TEST_TIME 47 9569
TEST_TIME 48 9659
TEST_TIME 49 9658
TEST_TIME 50 9549
TEST_TIME 51 9467
TEST_TIME 52 9539
TEST_TIME 53 9522
TEST_TIME 54 9443
TEST_TIME 55 9358
TEST_TIME 56 9215
TEST_TIME 57 9357
TEST_TIME 58 9431
TEST_TIME 59 9433
TEST_TIME 60 9425
TEST_TIME 61 9410
TEST_TIME 62 9363
TEST_TIME 63 9454
TEST_TIME 64 9519
TEST_TIME 65 9521
TEST_TIME 66 9524
TEST_TIME 67 9504
TEST_TIME 68 9577
TEST_TIME 69 9645
TEST_TIME 70 9651
TEST_TIME 71 9638
TEST_TIME 72 9640
TEST_TIME 73 9548
TEST_TIME 74 9469
TEST_TIME 75 9489
TEST_TIME 76 9483
TEST_TIME 77 9416
TEST_TIME 78 9406
TEST_TIME 79 9390
TEST_TIME 80 9342
TEST_TIME 81 9286
TEST_TIME 82 9240
TEST_TIME 83 9252
TEST_TIME 84 9242
TEST_TIME 85 9225
TEST_TIME 86 9242
TEST_TIME 87 9215
TEST_TIME 88 9373
TEST_TIME 89 9340
TEST_TIME 90 9326
TEST_TIME 91 9352
TEST_TIME 92 9354
TEST_TIME 93 9359
TEST_TIME 94 9355
TEST_TIME 95 9404
TEST_TIME 96 9421
TEST_TIME 97 9470
TEST_TIME 98 9438
TEST_TIME 99 9367
TEST_TIME 100 9353
TEST_TIME 101 9341
TEST_TIME 102 9339
TEST_TIME 103 9294
TEST_TIME 104 9284
TEST_TIME 105 9264
TEST_TIME 106 9226
TEST_TIME 107 9226
TEST_TIME 108 9239
TEST_TIME 109 9260
TEST_TIME 110 9238
TEST_TIME 111 9266
TEST_TIME 112 9291
TEST_TIME 113 9310
TEST_TIME 114 9313
TEST_TIME 115 9309
TEST_TIME 116 9306
TEST_TIME 117 9349
TEST_TIME 118 9355
TEST_TIME 119 9361
TEST_TIME 120 9327

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-10-2022 16:39:26 to 05-10-2022 16:39:30.

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 1576187 1576187 0 0.00 0 17639 7 0 0
doc_list_biud 1576187 1576187 0 0.00 0 52796 25 0 0
sp_add_invoice_to_stock 5545 5465 80 1.44 34 181290 17852 0 80
sp_cancel_adding_invoice 5487 5487 0 0.00 4 56260 799 0 0
sp_cancel_client_order 1966 1966 0 0.00 4 15381 403 0 0
sp_cancel_customer_reserve 531338 531338 0 0.00 0 52798 58 0 0
sp_cancel_pay_from_customer 732 732 0 0.00 3 5925 86 0 0
sp_cancel_pay_to_supplier 461 461 0 0.00 3 4168 79 0 0
sp_cancel_supplier_invoice 6150 6143 7 0.11 1 14041 770 0 7
sp_cancel_supplier_order 411 411 0 0.00 7 1009500 95370 0 0
sp_cancel_write_off 9723 9723 0 0.00 1 9470 115 0 0
sp_client_order 22639 22639 0 0.00 3 53850 335 0 0
sp_customer_reserve 495476 495476 0 0.00 0 97636 182 0 0
sp_fill_shopping_cart 34629 34629 0 0.00 1 20415 35 0 0
sp_fill_shopping_cart_clo_res 495473 495473 0 0.00 0 17269 13 0 0
sp_get_clo_for_invoice 5457 5457 0 0.00 0 51855 292 0 0
sp_kill_cost_storno 16403 16403 0 0.00 0 7699 55 0 0
sp_kill_qstorno_ret_qs2qd 1078953 1078953 0 0.00 0 52794 32 0 0
sp_kill_qty_storno 571567 571567 0 0.00 0 52796 69 0 0
sp_lock_selected_doc 22862 22862 0 0.00 0 6945 5 0 0
sp_make_cost_storno 30649 30649 0 0.00 0 17464 51 0 0
sp_multiply_rows_for_pdistr 30649 30649 0 0.00 0 7065 26 0 0
sp_multiply_rows_for_qdistr 22639 22639 0 0.00 0 53651 140 0 0
sp_pay_from_customer 7763 7763 0 0.00 3 17765 107 0 0
sp_pay_to_supplier 6387 6387 0 0.00 5 22924 126 0 0
sp_payment_common 14150 14150 0 0.00 1 22888 33 0 0
sp_qd_handle_on_cancel_clo 1966 1966 0 0.00 0 15340 370 0 0
sp_qd_handle_on_invoice_upd_sts 10944 10944 0 0.00 0 33266 315 0 0
sp_qd_handle_on_reserve_upd_sts 11042 11042 0 0.00 0 16308 61 0 0
sp_reserve_write_off 11042 11042 0 0.00 6 17656 177 0 0
sp_supplier_invoice 5970 5970 0 0.00 5 71049 2923 0 0
sp_supplier_order 6022 6022 0 0.00 5 30033 1274 0 0
srv_aggregate_perf_data 119 119 0 0.00 2718 126087 15916 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2606 101693 42720 0 0
srv_find_qd_qs_mism 1066207 1066207 0 0.00 0 13339 6 0 0
srv_make_invnt_saldo 630 630 0 0.00 42 110676 1091 0 0
srv_make_money_saldo 863 863 0 0.00 0 1935 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 47520 417270 192866 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5466 5466 0 0.00 0 9714 147 0 0
t$perf-norm:sp_cancel_adding_invoice 474 474 0 0.00 0 9330 102 0 0
t$perf-norm:sp_cancel_client_order 1966 1966 0 0.00 0 3362 13 0 0
t$perf-norm:sp_cancel_customer_reserve 799 799 0 0.00 0 772 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 732 732 0 0.00 0 773 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 461 461 0 0.00 0 997 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 495 495 0 0.00 0 3514 26 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 26534 1437 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 1401 5 0 0
t$perf-norm:sp_client_order 22639 22639 0 0.00 0 5157 15 0 0
t$perf-norm:sp_customer_reserve 1143 1143 0 0.00 0 4192 8 0 0
t$perf-norm:sp_pay_from_customer 7763 7763 0 0.00 0 8064 11 0 0
t$perf-norm:sp_pay_to_supplier 6387 6387 0 0.00 0 4844 10 0 0
t$perf-norm:sp_reserve_write_off 11042 11042 0 0.00 0 8158 9 0 0
t$perf-norm:sp_supplier_invoice 5970 5970 0 0.00 0 8107 14 0 0
t$perf-norm:sp_supplier_order 6022 6022 0 0.00 0 9510 14 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 328 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 8468 136 0 0
t$perf-norm:srv_make_invnt_saldo 630 630 0 0.00 0 8206 28 0 0
t$perf-norm:srv_make_money_saldo 863 863 0 0.00 0 2193 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 11074 10914 160 1.44 0 8770 18 0 160
v_all_customers 41088 41088 0 0.00 0 62 1 0 0
v_all_suppliers 32942 32942 0 0.00 0 21 1 0 0
v_all_wares 45278 45278 0 0.00 0 141 1 0 0
v_cancel_adding_invoice 948 948 0 0.00 0 4106 11 0 0
v_cancel_client_order 3932 3932 0 0.00 0 6595 7 0 0
v_cancel_customer_prepayment 1464 1464 0 0.00 0 1741 5 0 0
v_cancel_customer_reserve 1598 1598 0 0.00 0 353 3 0 0
v_cancel_payment_to_supplier 922 922 0 0.00 0 3065 8 0 0
v_cancel_supplier_invoice 1004 990 14 1.39 0 1024 13 0 14
v_cancel_supplier_order 822 822 0 0.00 0 3876 15 0 0
v_cancel_write_off 2052 2052 0 0.00 0 6846 17 0 0
v_max_id_clo_ord 6022 6022 0 0.00 0 990 1 0 0
v_max_id_clo_res 1143 1143 0 0.00 0 3411 14 0 0
v_max_id_ord_sup 5968 5968 0 0.00 0 7576 5 0 0
v_max_non_paid_invoice 1906 1906 0 0.00 0 315 2 0 0
v_max_non_paid_realizn 4241 4241 0 0.00 0 2005 4 0 0
v_min_id_clo_ord 6022 6022 0 0.00 0 6969 9 0 0
v_min_id_clo_res 1143 1143 0 0.00 0 1833 8 0 0
v_min_id_ord_sup 5968 5968 0 0.00 0 5105 5 0 0
v_min_non_paid_invoice 6387 6387 0 0.00 0 22885 22 0 0
v_min_non_paid_realizn 7763 7763 0 0.00 0 17731 26 0 0
v_our_firm 11234 11234 0 0.00 0 26 1 0 0
v_reserve_write_off 22082 22082 0 0.00 0 6595 6 0 0
x_lock_depdocs_on_canc_invoice 11135 11135 0 0.00 0 26639 122 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 6088 342 0 0
x_make_qstorno_1000_1200 6022 6022 0 0.00 1 30022 1192 0 0
x_make_qstorno_1200_2000 5970 5970 0 0.00 2 71020 2805 0 0
x_make_qstorno_2100_3300 495476 495476 0 0.00 0 97635 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-10-2022 16:39:31 to 05-10-2022 16:39:35.

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
14:08:38 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:39 25672 8192000000 9915029856 10227007488 998786624 10.867 100 90 180 0 1028241104 6297968 23156512
14:11:21 17332 8192000000 9952333216 10273873920 1032547904 11.193 100 85 170 0 1063339152 9383776 21407472
14:12:41 10555 8192000000 9974274720 10306920448 1059270352 11.449 100 85 170 1 1090843584 10363728 21209504
14:14:01 8231 8192000000 10025237872 10370760704 1112860736 11.959 100 74 148 0 1141116160 10976256 17279168
14:15:15 5952 8192000000 10091895248 10448617472 1171578608 12.512 100 84 165 0 1203182048 13284032 18319408
14:16:25 3546 8192000000 10117909328 10489163776 1209012368 12.860 100 73 146 0 1237369424 11823024 16534032
14:17:30 4284 8192000000 10159660192 10535378944 1248016528 13.220 100 76 152 0 1277259312 13027536 16215248
14:18:36 2173 8192000000 10190161744 10573455360 1277052416 13.486 100 87 174 0 1310500464 13496528 19951520
14:19:40 1588 8192000000 10210096992 10598842368 1298354464 13.680 100 75 147 0 1328301088 13910400 16036224
14:20:42 1029 8192000000 10210643728 10605969408 1306984512 13.759 100 74 149 1 1333332208 9165264 17182432
14:21:44 3675 8192000000 10200332688 10612633600 1312746128 13.811 100 34 68 1 1331235776 11120064 7369584
14:22:50 10933 8192000000 10199889888 10618695680 1318010256 13.859 100 20 41 0 1328639584 6096864 4532464
14:24:01 9914 8192000000 10204680544 10625765376 1323720752 13.910 100 16 33 0 1333389360 6113168 3555440
14:25:13 44586 8192000000 10226210112 10644647936 1334560256 14.008 100 28 54 0 1345641616 5659008 5422352
14:27:00 6657 8192000000 10222751680 10645684224 1333615520 14.000 100 28 56 0 1345868240 5835136 6417584
14:28:09 3651 8192000000 10229261536 10646700032 1335095696 14.013 100 35 70 0 1349755648 6235200 8424752
14:29:13 67723 8192000000 10187545216 10586316800 1177385760 12.566 89 23 46 1 1187826768 4397408 6043600
14:31:21 5566 8192000000 10139747696 10541801472 1217351744 12.937 97 36 72 0 1236201392 10157872 8691776
14:32:30 5464 8192000000 10152834576 10558824448 1265148400 13.377 100 21 42 0 1276019824 5429456 5441968
14:33:35 11407 8192000000 10161468528 10568245248 1262250736 13.351 99 15 30 0 1271392144 5501232 3640176
14:34:50 30496 8192000000 10175948304 10591674368 1275866848 13.475 99 19 38 1 1294231312 13909104 4455360
14:36:21 5263 8192000000 10205129856 10615795712 1307544288 13.764 100 32 64 0 1322023072 7885648 6593136
14:37:31 9381 8192000000 10200013072 10623946752 1315882000 13.839 100 20 40 0 1326154176 5661760 4610416
14:38:41 39318 8192000000 10240087312 10659532800 1327817424 13.947 99 34 65 1 1341716832 5130032 8769376
14:40:23 51998 8192000000 10265446480 10681298944 1358553568 14.224 100 47 93 0 1377141328 8478816 10108944
14:42:20 19797 8192000000 10271768800 10694950912 1359233584 14.230 99 53 108 0 1384166112 12613216 12319312
14:43:42 4496 8192000000 10288335104 10712141824 1380254560 14.419 100 53 104 1 1407010336 14166496 12589280
14:44:50 22714 8192000000 10302251312 10722652160 1388194480 14.490 100 55 111 0 1417719296 16857824 12666992
14:46:18 10533 8192000000 10322986784 10747334656 1405187920 14.641 100 52 104 0 1436285248 18675040 12422288
14:47:36 4609 8192000000 10332772800 10756276224 1413040816 14.711 100 56 110 2 1442940336 16222368 13677152
14:48:44 22006 8192000000 10328366800 10754416640 1406406192 14.652 100 48 93 0 1441207952 23226432 11575328
14:50:17 3790 8192000000 10339290816 10759282688 1408078992 14.667 100 58 113 1 1447383440 25269728 14034720
14:51:27 3721 8192000000 10304928240 10723586048 1377873344 14.398 100 49 96 2 1417706544 28052240 11780960
14:52:35 3380 8192000000 10295690944 10696646656 1344874016 14.101 99 84 168 2 1402153376 36276320 21003040
14:53:43 6381 8192000000 10306794832 10718109696 1361391840 14.250 99 63 126 4 1408151568 31973984 14785744
14:54:56 6938 8192000000 10334618368 10742493184 1380657616 14.422 100 62 124 0 1432456848 35692864 16106368
14:56:16 2606 8192000000 10325074160 10724323328 1368303936 14.312 100 74 147 1 1424202496 37249424 18649136
14:57:25 4328 8192000000 10344631504 10754400256 1386712832 14.477 100 75 150 2 1444278432 38221744 19343856
14:58:35 5461 8192000000 10340677600 10759708672 1380528128 14.421 99 60 120 2 1438899488 43429008 14942352
14:59:44 11202 8192000000 10332172768 10760916992 1391358928 14.518 99 35 70 0 1436791568 35825568 9607072
15:00:56 12818 8192000000 10353002928 10771197952 1404275152 14.633 100 45 89 0 1455611664 39524464 11812048
15:02:13 9494 8192000000 10319132960 10749906944 1378933904 14.407 100 31 60 0 1424161632 37220160 8007568
15:03:30 6906 8192000000 10345747840 10775080960 1393769040 14.539 100 38 74 1 1449650896 45161616 10720240
15:04:39 24510 8192000000 10316604944 10767814656 1367429632 14.304 98 16 32 0 1409835504 37553216 4852656
15:06:25 25235 8192000000 10316957056 10755072000 1373542768 14.359 98 20 40 1 1415201040 35722224 5936048
15:07:54 26975 8192000000 10315361936 10765557760 1384973104 14.461 100 12 24 0 1431804048 43394592 3436352
15:09:24 16145 8192000000 10313105056 10754969600 1389610768 14.502 100 36 67 0 1411948576 14633984 7703824
15:10:48 56701 8192000000 10295559536 10742169600 1362761888 14.262 98 11 22 0 1376937280 11740416 2434976
15:12:46 93695 8192000000 10311287040 10755641344 1384219008 14.454 99 34 68 0 1400298704 8319664 7760032
15:15:24 101692 8192000000 10312182640 10767294464 1412607024 14.707 100 28 53 1 1427060848 8611856 5841968
15:18:09 27733 8192000000 10334438208 10775425024 1409874848 14.683 100 51 103 1 1434851408 12956848 12019712
15:19:41 69121 8192000000 10315740544 10778103808 1404939696 14.639 99 18 35 0 1421251664 12411344 3900624
15:21:51 70789 8192000000 10322786048 10788528128 1415446816 14.732 100 9 18 0 1429847376 12419824 1980736
15:24:03 84938 8192000000 10312689120 10776137728 1401117968 14.605 99 26 52 0 1417759856 10555712 6086176
15:26:55 66569 8192000000 10318346768 10788601856 1409620144 14.681 100 19 38 0 1433050576 18546576 4883856
15:29:10 20737 8192000000 10310433504 10774134784 1405108928 14.640 99 19 38 0 1416623408 7352080 4162400
15:30:37 74786 8192000000 10293131104 10763866112 1372476752 14.349 98 10 20 0 1387642624 12692208 2473664
15:32:59 57803 8192000000 10305850128 10777747456 1406804304 14.656 100 16 32 0 1423877344 13449120 3623920
15:35:15 65122 8192000000 10284625728 10769620992 1361743120 14.253 98 11 22 0 1395269904 30345984 3180800
15:37:21 76357 8192000000 10278562368 10732589056 1321735296 13.892 95 16 32 0 1336898304 12144768 3018240
15:39:38 16351 8192000000 10271272704 10715639808 1351618384 14.162 99 33 66 0 1368321712 9868256 6835072
15:40:58 83822 8192000000 10283822672 10733092864 1311698272 13.801 95 24 48 0 1331355376 14396448 5260656
15:43:59 58798 8192000000 10263763216 10710925312 1310805616 13.793 96 21 42 1 1330777616 15465440 4506560
15:45:57 32321 8192000000 10288316352 10734534656 1334126144 14.004 97 24 48 0 1354403040 15014224 5262672
15:47:31 49122 8192000000 10282937152 10726158336 1346017536 14.112 98 28 55 1 1366863888 15054464 5791888
15:49:27 46118 8192000000 10284873904 10728800256 1332540976 13.990 97 30 59 1 1349292656 10392192 6359488
15:51:16 76587 8192000000 10282764112 10718576640 1365895760 14.290 100 36 73 0 1380904512 6596416 8412336
15:53:33 85098 8192000000 10272209696 10728058880 1353457136 14.179 98 30 56 1 1368501456 8209104 6835216
15:56:18 37017 8192000000 10276815040 10739462144 1381208848 14.427 100 24 48 2 1394638544 7711488 5718208
15:57:58 70710 8192000000 10266628720 10727256064 1328399152 13.953 96 11 22 0 1338987472 7772208 2816112
16:00:09 79146 8192000000 10266482976 10735427584 1366285792 14.294 100 12 24 0 1380702176 11353728 3062656
16:03:38 56216 8192000000 10276168704 10742497280 1372672240 14.351 100 14 28 1 1387977536 12008608 3296688
16:05:35 59912 8192000000 10281308320 10739572736 1361169424 14.248 98 29 59 0 1382955664 14771776 7014464
16:07:35 45028 8192000000 10298106832 10749874176 1370187280 14.329 99 17 34 0 1381604080 7711680 3705120
16:09:37 23906 8192000000 10306970784 10758881280 1385654944 14.467 99 25 50 1 1399471696 8843792 4972960
16:11:01 33315 8192000000 10297563184 10747478016 1348176672 14.131 97 34 68 1 1367041872 11625968 7239232
16:12:37 63111 8192000000 10298448976 10756030464 1341154016 14.068 96 28 56 0 1362401136 15084560 6162560
16:14:48 53810 8192000000 10298562624 10747715584 1351857600 14.164 97 25 48 0 1366396560 8873232 5665728
16:16:55 97915 8192000000 10278134144 10733678592 1343535824 14.089 97 16 32 1 1355763104 8785472 3441808
16:19:38 39014 8192000000 10246082688 10688757760 1293695056 13.638 95 31 62 0 1308450736 8063264 6692416
16:21:22 55961 8192000000 10235925728 10690752512 1322513920 13.899 98 5 10 0 1331831792 8253184 1064688
16:23:19 46205 8192000000 10248800224 10702688256 1324283840 13.915 98 15 30 0 1334644864 6803248 3557776
16:25:14 86252 8192000000 10247939872 10705883136 1318108832 13.860 97 10 20 0 1332275776 12093264 2073680
16:27:41 67593 8192000000 10258017888 10708230144 1293720912 13.638 95 27 54 0 1309896624 10104704 6071008
16:29:49 56539 8192000000 10266611312 10721169408 1342538064 14.080 99 23 46 0 1359865104 11719040 5608000
16:31:55 62997 8192000000 10264672864 10708590592 1309359472 13.780 96 23 46 0 1324972368 10242592 5370304
16:33:59 81217 8192000000 10266470320 10733428736 1336817568 14.029 98 25 50 0 1356776560 14064560 5894432
16:36:25 21940 8192000000 10283049392 10723905536 1352589984 14.171 99 38 74 1 1371055728 10232768 8232976
16:37:50 22081 8192000000 10292902192 10732494848 1348701376 14.136 98 20 40 0 1363955600 10654704 4599520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-10-2022 16:39:37 to 05-10-2022 16:39:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-10-2022 16:39:53 to 05-10-2022 16:39:55.

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          252852
MON$OLDEST_ACTIVE               255062
MON$OLDEST_SNAPSHOT             255062
MON$NEXT_TRANSACTION            255062
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                       2031520
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:222: 
:223: mscs-test-161.budzdorov.ru    Wed Oct  5 16:38:46 2022
:224:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:225: 
:226: 
:227: mscs-test-161.budzdorov.ru    Wed Oct  5 16:38:49 2022
:228:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:229: 
--- end of diff output ---

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

05.10.22 16:39:56. 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-05 16:39:59 No severe PSQL-related problems occured
05.10.22 16:40:00. End of report.