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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16311996 kB
MemAvailable 31270720 kB
Buffers 0 kB
Cached 15436872 kB
SwapCached 2484 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.10
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-06-21 22:38:18
DTS_END                         2022-06-21 22:38:18.8970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50962
TRN_ID                          239417
ATT_ID                          678
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: 9028.24 23777 1083389
customer order: creation 169.28 343 20313
customer order: refuse 15.00 378 1800
order to supplier: creation 45.99 1405 5519
order to supplier: removal 3.48 100250 417
invoice (draft): creation 45.16 3680 5419
invoice (draft): removal 51.59 830 6191
invoice accept: apply 40.91 18036 4909
invoice accept: cancel 46.12 854 5534
customer reserve: creation 3756.99 178 450839
customer reserve: removal 4562.02 59 547442
realization accept: apply 82.46 201 9895
realization accept: cancel 82.23 118 9867
payment from customer: creation 58.75 128 7050
payment from customer: removal 5.72 102 686
payment to supplier: creation 46.94 126 5633
payment to supplier: removal 3.28 78 394
service: total inventory turnovers 4.51 1490 541
service: total monetary turnovers 7.12 37 854
service: aggregate perf. data 0.68 27871 82
service: refresh index statistics 0.03 319383 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-06-2022 22:40:08 to 21-06-2022 22:40:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4885
TEST_TIME 2 5720
TEST_TIME 3 5622
TEST_TIME 4 5667
TEST_TIME 5 5566
TEST_TIME 6 5282
TEST_TIME 7 5866
TEST_TIME 8 5816
TEST_TIME 9 6433
TEST_TIME 10 6770
TEST_TIME 11 6922
TEST_TIME 12 6979
TEST_TIME 13 7123
TEST_TIME 14 7404
TEST_TIME 15 7520
TEST_TIME 16 7480
TEST_TIME 17 7811
TEST_TIME 18 8072
TEST_TIME 19 8200
TEST_TIME 20 8236
TEST_TIME 21 8635
TEST_TIME 22 9133
TEST_TIME 23 9211
TEST_TIME 24 9237
TEST_TIME 25 9454
TEST_TIME 26 9771
TEST_TIME 27 9955
TEST_TIME 28 10449
TEST_TIME 29 10972
TEST_TIME 30 10724
TEST_TIME 31 10664
TEST_TIME 32 10402
TEST_TIME 33 10267
TEST_TIME 34 10256
TEST_TIME 35 10144
TEST_TIME 36 10241
TEST_TIME 37 10084
TEST_TIME 38 9962
TEST_TIME 39 9896
TEST_TIME 40 9898
TEST_TIME 41 9884
TEST_TIME 42 9785
TEST_TIME 43 9687
TEST_TIME 44 9694
TEST_TIME 45 9613
TEST_TIME 46 9588
TEST_TIME 47 9498
TEST_TIME 48 9394
TEST_TIME 49 9401
TEST_TIME 50 9451
TEST_TIME 51 9415
TEST_TIME 52 9275
TEST_TIME 53 9388
TEST_TIME 54 9328
TEST_TIME 55 9337
TEST_TIME 56 9405
TEST_TIME 57 9391
TEST_TIME 58 9460
TEST_TIME 59 9439
TEST_TIME 60 9410
TEST_TIME 61 9448
TEST_TIME 62 9541
TEST_TIME 63 9574
TEST_TIME 64 9612
TEST_TIME 65 9728
TEST_TIME 66 9799
TEST_TIME 67 9794
TEST_TIME 68 9741
TEST_TIME 69 9633
TEST_TIME 70 9516
TEST_TIME 71 9545
TEST_TIME 72 9509
TEST_TIME 73 9440
TEST_TIME 74 9380
TEST_TIME 75 9332
TEST_TIME 76 9289
TEST_TIME 77 9221
TEST_TIME 78 9177
TEST_TIME 79 9326
TEST_TIME 80 9283
TEST_TIME 81 9322
TEST_TIME 82 9263
TEST_TIME 83 9317
TEST_TIME 84 9358
TEST_TIME 85 9352
TEST_TIME 86 9315
TEST_TIME 87 9312
TEST_TIME 88 9375
TEST_TIME 89 9382
TEST_TIME 90 9368
TEST_TIME 91 9403
TEST_TIME 92 9412
TEST_TIME 93 9363
TEST_TIME 94 9288
TEST_TIME 95 9223
TEST_TIME 96 9253
TEST_TIME 97 9247
TEST_TIME 98 9186
TEST_TIME 99 9160
TEST_TIME 100 9133
TEST_TIME 101 9131
TEST_TIME 102 9130
TEST_TIME 103 9148
TEST_TIME 104 9163
TEST_TIME 105 9139
TEST_TIME 106 9253
TEST_TIME 107 9234
TEST_TIME 108 9229
TEST_TIME 109 9243
TEST_TIME 110 9222
TEST_TIME 111 9238
TEST_TIME 112 9278
TEST_TIME 113 9293
TEST_TIME 114 9301
TEST_TIME 115 9225
TEST_TIME 116 9166
TEST_TIME 117 9157
TEST_TIME 118 9120
TEST_TIME 119 9098
TEST_TIME 120 9031

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-06-2022 22:40:12 to 21-06-2022 22:40:15.

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 1500982 1500982 0 0.00 0 23116 6 0 0
doc_list_biud 1500982 1500982 0 0.00 0 52097 28 0 0
sp_add_invoice_to_stock 5051 4909 142 2.81 44 187945 18036 0 142
sp_cancel_adding_invoice 5534 5534 0 0.00 5 98280 855 0 0
sp_cancel_client_order 1800 1800 0 0.00 4 13269 379 0 0
sp_cancel_customer_reserve 547442 547442 0 0.00 1 21220 60 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 2 8645 102 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 2 2487 78 0 0
sp_cancel_supplier_invoice 6200 6191 9 0.14 2 52105 830 0 9
sp_cancel_supplier_order 417 417 0 0.00 6 969475 100250 0 0
sp_cancel_write_off 9867 9867 0 0.00 1 8682 118 0 0
sp_client_order 20313 20313 0 0.00 3 21228 343 0 0
sp_customer_reserve 450839 450839 0 0.00 0 39384 179 0 0
sp_fill_shopping_cart 31232 31232 0 0.00 1 20162 42 0 0
sp_fill_shopping_cart_clo_res 450834 450834 0 0.00 0 12124 13 0 0
sp_get_clo_for_invoice 4897 4897 0 0.00 0 30900 325 0 0
sp_kill_cost_storno 16481 16481 0 0.00 0 10775 59 0 0
sp_kill_qstorno_ret_qs2qd 1111359 1111359 0 0.00 0 52093 33 0 0
sp_kill_qty_storno 586042 586042 0 0.00 0 52095 71 0 0
sp_lock_selected_doc 20555 20555 0 0.00 0 4878 3 0 0
sp_make_cost_storno 27474 27474 0 0.00 0 23114 55 0 0
sp_multiply_rows_for_pdistr 27474 27474 0 0.00 0 6981 30 0 0
sp_multiply_rows_for_qdistr 20313 20313 0 0.00 0 10736 136 0 0
sp_pay_from_customer 7050 7050 0 0.00 4 30733 129 0 0
sp_pay_to_supplier 5633 5633 0 0.00 5 23120 126 0 0
sp_payment_common 12683 12683 0 0.00 1 30729 30 0 0
sp_qd_handle_on_cancel_clo 1800 1800 0 0.00 0 13261 351 0 0
sp_qd_handle_on_invoice_upd_sts 10430 10430 0 0.00 0 15542 335 0 0
sp_qd_handle_on_reserve_upd_sts 9895 9895 0 0.00 0 18933 84 0 0
sp_reserve_write_off 9895 9895 0 0.00 6 18946 202 0 0
sp_supplier_invoice 5419 5419 0 0.00 6 78147 3681 0 0
sp_supplier_order 5519 5519 0 0.00 5 55123 1405 0 0
srv_aggregate_perf_data 82 82 0 0.00 2965 463552 27871 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1709 195390 48386 0 0
srv_find_qd_qs_mism 1036642 1036642 0 0.00 0 15215 6 0 0
srv_make_invnt_saldo 541 541 0 0.00 23 78601 1491 0 0
srv_make_money_saldo 854 854 0 0.00 0 8745 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 171366 507755 319383 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4909 4909 0 0.00 0 21614 179 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 8354 119 0 0
t$perf-norm:sp_cancel_client_order 1800 1800 0 0.00 0 2029 10 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 10557 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 3886 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 12178 38 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 4445 32 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 25679 1682 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 3623 7 0 0
t$perf-norm:sp_client_order 20313 20313 0 0.00 0 9686 22 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 12018 20 0 0
t$perf-norm:sp_pay_from_customer 7050 7050 0 0.00 0 21704 18 0 0
t$perf-norm:sp_pay_to_supplier 5633 5633 0 0.00 0 10981 12 0 0
t$perf-norm:sp_reserve_write_off 9895 9895 0 0.00 0 17119 17 0 0
t$perf-norm:sp_supplier_invoice 5419 5419 0 0.00 0 10308 21 0 0
t$perf-norm:sp_supplier_order 5519 5519 0 0.00 0 11100 24 0 0
t$perf-norm:srv_aggregate_perf_data 82 82 0 0.00 0 355 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 10749 180 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 2294 28 0 0
t$perf-norm:srv_make_money_saldo 854 854 0 0.00 0 3718 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10076 9792 284 2.81 0 8399 20 0 284
v_all_customers 36944 36944 0 0.00 0 13 1 0 0
v_all_suppliers 30152 30152 0 0.00 0 93 1 0 0
v_all_wares 40626 40626 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 2012 12 0 0
v_cancel_client_order 3600 3600 0 0.00 0 5561 9 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 4597 15 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 2677 5 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 1569 5 0 0
v_cancel_supplier_invoice 866 848 18 2.07 0 207 13 0 18
v_cancel_supplier_order 834 834 0 0.00 0 1387 6 0 0
v_cancel_write_off 1884 1884 0 0.00 0 4043 17 0 0
v_max_id_clo_ord 5519 5519 0 0.00 0 2463 3 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 6818 21 0 0
v_max_id_ord_sup 5400 5400 0 0.00 0 3527 3 0 0
v_max_non_paid_invoice 1476 1476 0 0.00 0 1427 5 0 0
v_max_non_paid_realizn 3791 3791 0 0.00 0 3670 5 0 0
v_min_id_clo_ord 5519 5519 0 0.00 0 5140 6 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 5176 15 0 0
v_min_id_ord_sup 5400 5400 0 0.00 0 3911 4 0 0
v_min_non_paid_invoice 5633 5633 0 0.00 0 7368 15 0 0
v_min_non_paid_realizn 7050 7050 0 0.00 0 30725 30 0 0
v_our_firm 10200 10200 0 0.00 0 16 1 0 0
v_reserve_write_off 19790 19790 0 0.00 0 6557 8 0 0
x_lock_depdocs_on_canc_invoice 11301 11301 0 0.00 0 14787 128 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 7747 388 0 0
x_make_qstorno_1000_1200 5519 5519 0 0.00 1 55118 1324 0 0
x_make_qstorno_1200_2000 5419 5419 0 0.00 3 78139 3509 0 0
x_make_qstorno_2100_3300 450839 450839 0 0.00 0 39383 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-06-2022 22:40:16 to 21-06-2022 22:40:20.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:22 9 8192000000 8801917600 8814170112 7670864 0.093 3 1 2 0 7771824 58176 42784
20:09:25 29325 8192000000 9888942464 10197430272 969787552 10.585 100 87 175 1 999312624 6408112 23116960
20:10:59 23122 8192000000 9936841664 10259238912 1020464368 11.076 100 87 175 0 1051891392 8546176 22880848
20:12:29 22147 8192000000 9975723664 10307514368 1055989776 11.418 100 85 170 0 1088115648 10031584 22094288
20:13:55 12600 8192000000 10035070160 10383106048 1124169664 12.066 100 77 154 0 1153962240 11581040 18211536
20:15:11 7788 8192000000 10088042544 10447585280 1176967872 12.562 100 86 172 1 1209569472 12801296 19800304
20:16:26 4112 8192000000 10136831072 10509299712 1225523424 13.013 100 79 156 0 1255001760 12490352 16987984
20:17:31 5902 8192000000 10175113152 10550657024 1263254464 13.360 100 80 161 0 1294262640 13416176 17592000
20:18:40 1683 8192000000 10207560624 10593603584 1295143648 13.651 100 86 172 2 1327642752 12696272 19802832
20:19:44 12577 8192000000 10200380800 10604318720 1306682608 13.756 100 51 100 1 1326585920 7440816 12462496
20:20:58 6149 8192000000 10208379648 10623070208 1320998528 13.886 100 25 50 0 1334220768 7875520 5346720
20:22:07 6774 8192000000 10219513168 10634199040 1330324848 13.970 100 22 44 0 1341711296 6479552 4906896
20:23:18 50747 8192000000 10224345904 10646102016 1333829360 14.002 100 26 52 2 1345517920 5809216 5879344
20:25:10 6717 8192000000 10229155792 10654871552 1340254288 14.060 100 20 40 0 1350883376 6145008 4484080
20:26:17 7642 8192000000 10245120160 10667077632 1347926976 14.129 100 23 46 0 1363119056 10025392 5166688
20:27:28 48315 8192000000 10199404096 10607820800 1257896976 13.311 94 28 56 2 1269494256 4877968 6719312
20:29:17 4969 8192000000 10212511072 10621538304 1310975280 13.795 100 38 74 0 1325202432 6492256 7734896
20:30:22 8019 8192000000 10173495648 10581573632 1269149264 13.414 98 25 50 1 1281078816 5555824 6373728
20:31:33 7316 8192000000 10141962112 10540175360 1236468880 13.114 99 29 58 1 1253667280 9533536 7664864
20:32:45 57280 8192000000 10147450464 10558701568 1229332592 13.048 97 21 42 1 1239469552 5247296 4889664
20:34:43 9848 8192000000 10184474736 10595504128 1290392288 13.608 100 20 41 0 1301084608 5542368 5149952
20:35:53 11108 8192000000 10193828752 10611863552 1302880592 13.721 100 14 28 0 1311712240 5443584 3388064
20:37:06 7575 8192000000 10199309520 10617569280 1307554240 13.764 100 20 40 2 1318520672 5798800 5167632
20:38:18 18964 8192000000 10227249488 10638192640 1324289072 13.916 100 43 86 2 1340389392 5769936 10330384
20:39:46 22800 8192000000 10256750256 10670055424 1349636768 14.144 100 44 88 0 1370424096 11280960 9506368
20:41:12 10937 8192000000 10265995696 10689560576 1362665296 14.261 100 49 98 0 1385911072 11999744 11246032
20:42:28 16878 8192000000 10287998208 10713473024 1378181040 14.400 100 53 105 1 1403727584 12899024 12647520
20:43:59 23426 8192000000 10303582992 10733371392 1390932288 14.514 100 43 86 3 1416819520 14564000 11323232
20:45:24 14622 8192000000 10307205152 10741227520 1395342480 14.554 100 47 94 3 1427022192 19914768 11764944
20:46:47 4171 8192000000 10326133520 10752495616 1402419856 14.617 100 59 118 1 1440800704 24195088 14185760
20:47:57 6409 8192000000 10327465568 10756755456 1392989264 14.533 99 58 116 0 1433370528 26753504 13627760
20:49:09 5745 8192000000 10325117312 10751303680 1378259824 14.401 98 50 99 1 1414832896 25002512 11570560
20:50:22 4221 8192000000 10346555744 10760921088 1404720480 14.637 100 73 142 1 1453115424 30424448 17970496
20:51:30 9307 8192000000 10327479728 10741583872 1378973888 14.407 100 76 154 0 1434546368 36889216 18683264
20:52:48 1709 8192000000 10322574672 10736074752 1378187760 14.400 100 71 142 0 1428913696 33400448 17325488
20:53:53 4336 8192000000 10331365440 10734854144 1371467216 14.340 100 79 159 0 1427156944 35754384 19935344
20:55:02 2862 8192000000 10355476688 10763280384 1388496192 14.492 100 79 155 0 1452896864 44455456 19945216
20:56:10 3512 8192000000 10349014976 10758889472 1379812416 14.415 99 80 160 2 1439913696 39813664 20287616
20:57:16 6655 8192000000 10349547344 10766561280 1386083040 14.471 99 77 155 2 1447478048 40901536 20493472
20:58:27 3580 8192000000 10366334176 10776412160 1399506816 14.591 100 82 163 0 1468517584 47952960 21057808
20:59:37 7455 8192000000 10354853872 10793586688 1407779744 14.664 100 36 72 1 1460384896 42787376 9817776
21:00:46 16354 8192000000 10322934576 10761441280 1378863632 14.406 99 26 52 0 1422815776 37023200 6928944
21:02:30 11924 8192000000 10341399104 10766135296 1390149328 14.507 100 44 88 2 1436112112 34093232 11869552
21:03:48 17578 8192000000 10342948992 10781745152 1399208752 14.588 100 30 60 0 1450051632 42617344 8225536
21:05:11 37440 8192000000 10287818608 10729463808 1338165056 14.041 97 20 40 2 1380617824 36574288 5878480
21:06:52 15962 8192000000 10287169712 10734301184 1366665872 14.297 100 31 62 1 1401573024 26974912 7932240
21:08:10 50726 8192000000 10295353168 10736283648 1366299408 14.294 99 28 58 1 1387563264 15058256 6205600
21:10:01 46432 8192000000 10301285760 10741669888 1389312112 14.500 100 31 62 0 1411552480 15176912 7063456
21:11:50 101394 8192000000 10283807808 10745901056 1357454224 14.214 97 33 67 1 1380586416 15837776 7294416
21:15:09 42295 8192000000 10275778128 10734538752 1366790096 14.298 99 10 20 0 1380090944 11362240 1938608
21:16:52 52925 8192000000 10295299264 10745253888 1378315200 14.401 99 19 38 0 1394651600 12356080 3980320
21:18:52 67103 8192000000 10290998032 10751127552 1380609568 14.422 99 14 28 1 1396776112 13081120 3085424
21:21:00 75582 8192000000 10291699312 10744684544 1372285120 14.348 98 11 22 0 1386593312 10970080 3338112
21:23:17 32611 8192000000 10297295488 10756026368 1390543984 14.511 99 44 86 1 1413615040 13676576 9394480
21:24:51 26076 8192000000 10291804208 10741809152 1388321440 14.491 100 20 40 0 1401940240 8818256 4800544
21:26:23 35484 8192000000 10308739632 10750783488 1383595056 14.449 99 42 84 1 1404439584 11021488 9823040
21:28:23 95630 8192000000 10282341872 10757361664 1378404304 14.402 99 8 16 0 1393432320 13074800 1953216
21:31:39 58925 8192000000 10296757520 10746974208 1369734816 14.325 98 25 50 2 1387106224 11690848 5680560
21:33:43 71119 8192000000 10286313376 10746900480 1361862512 14.254 98 17 35 0 1375087040 9279168 3945360
21:35:54 71926 8192000000 10282041456 10741448704 1315428304 13.835 95 18 37 1 1330245808 11091520 3725984
21:38:07 45318 8192000000 10269276976 10713698304 1316645504 13.846 96 31 62 2 1334576240 11396896 6533840
21:39:55 115695 8192000000 10272465520 10724323328 1317944384 13.858 96 16 32 1 1337463488 15959792 3559312
21:42:50 143133 8192000000 10261769216 10717687808 1265576720 13.381 93 9 19 1 1285374032 18054944 1742368
21:46:14 74032 8192000000 10269420672 10705256448 1346837104 14.119 99 33 65 1 1361689632 7259216 7593312
21:48:53 34565 8192000000 10273310800 10718851072 1358053824 14.220 99 40 80 2 1377089232 9970864 9064544
21:50:28 22940 8192000000 10262901984 10721484800 1371465120 14.340 100 11 22 0 1382114208 7818496 2830592
21:51:51 57084 8192000000 10281571360 10738798592 1372152240 14.346 99 19 38 1 1389716864 13100128 4464496
21:53:48 71048 8192000000 10286840480 10742460416 1378619952 14.404 100 18 36 0 1397154656 13828944 4705760
21:56:00 86304 8192000000 10297190016 10757705728 1369495008 14.323 99 21 42 1 1404683744 30173376 5015360
21:58:30 81046 8192000000 10290435136 10753187840 1382529840 14.439 100 10 20 0 1398333456 13925600 1878016
22:00:51 32571 8192000000 10285163184 10735972352 1342419408 14.079 97 37 74 0 1360434032 9993472 8021152
22:02:25 55778 8192000000 10281579808 10728460288 1349970336 14.147 98 22 43 1 1363837888 8984512 4883040
22:04:28 56185 8192000000 10280547504 10726383616 1348550912 14.134 98 30 60 0 1366855184 11861168 6443104
22:06:28 73473 8192000000 10286378272 10737528832 1361078976 14.247 99 15 30 1 1378609040 14608000 2922064
22:08:41 80930 8192000000 10286623184 10743431168 1383967696 14.452 100 15 30 0 1400053776 13066960 3019120
22:11:03 136896 8192000000 10293410352 10748563456 1352798688 14.173 97 23 46 0 1366843760 8455216 5589856
22:14:23 64675 8192000000 10277197408 10744684544 1378620144 14.404 99 14 26 1 1396864528 15007008 3237376
22:16:33 71156 8192000000 10287568832 10744221696 1363699344 14.271 98 16 32 0 1379750560 12329888 3721328
22:19:10 77717 8192000000 10285192112 10755059712 1376972720 14.389 99 12 24 1 1390431232 10257072 3201440
22:21:28 35014 8192000000 10286741072 10732834816 1351610448 14.162 97 16 32 1 1363426240 8215952 3599840
22:23:10 74687 8192000000 10273865792 10725588992 1316080896 13.841 95 25 50 2 1336694672 14733136 5880640
22:25:34 76753 8192000000 10267652784 10718429184 1301303920 13.707 95 15 30 0 1314322416 9516928 3501568
22:28:00 64590 8192000000 10295003808 10731667456 1338560736 14.044 97 31 62 0 1359021536 13386064 7074736
22:30:13 195389 8192000000 10241571536 10690510848 1226737376 13.024 90 4 8 0 1248697632 21158816 801440
22:35:04 77846 8192000000 10243517024 10701930496 1318628960 13.864 98 15 30 0 1334576048 11906208 4040880

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-06-2022 22:40:22 to 21-06-2022 22:40:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

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

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          238769
MON$OLDEST_ACTIVE               239563
MON$OLDEST_SNAPSHOT             239563
MON$NEXT_TRANSACTION            239563
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                       2027936
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:244: 
:245: mscs-test-161.budzdorov.ru    Tue Jun 21 22:38:25 2022
:246:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:247: 
--- end of diff output ---

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

21.06.22 22:40:41. 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-06-21 22:40:43 No severe PSQL-related problems occured
21.06.22 22:40:45. End of report.