Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.03.2023 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 15038292 kB
MemAvailable 30385920 kB
Buffers 0 kB
Cached 16647212 kB
SwapCached 3028 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 2023-03-08 01:38:44
DTS_END                         2023-03-08 01:38:44.2430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58328
TRN_ID                          246597
ATT_ID                          728
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: 9887.60 19763 1186512
customer order: creation 180.22 348 21626
customer order: refuse 15.44 345 1853
order to supplier: creation 48.58 1277 5829
order to supplier: removal 3.63 108273 436
invoice (draft): creation 47.67 3127 5720
invoice (draft): removal 57.47 774 6896
invoice accept: apply 42.35 17157 5082
invoice accept: cancel 52.39 771 6287
customer reserve: creation 3972.48 168 476698
customer reserve: removal 5152.18 60 618262
realization accept: apply 87.69 175 10523
realization accept: cancel 91.89 108 11027
payment from customer: creation 61.75 100 7410
payment from customer: removal 5.80 82 696
payment to supplier: creation 51.97 115 6236
payment to supplier: removal 3.28 62 393
service: total inventory turnovers 5.08 1100 610
service: total monetary turnovers 6.93 29 831
service: aggregate perf. data 0.78 25417 93
service: refresh index statistics 0.03 235769 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-03-2023 01:39:28 to 08-03-2023 01:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3135
TEST_TIME 2 3731
TEST_TIME 3 4836
TEST_TIME 4 4832
TEST_TIME 5 5078
TEST_TIME 6 4820
TEST_TIME 7 5254
TEST_TIME 8 5074
TEST_TIME 9 5855
TEST_TIME 10 6012
TEST_TIME 11 6114
TEST_TIME 12 6284
TEST_TIME 13 6301
TEST_TIME 14 6307
TEST_TIME 15 6541
TEST_TIME 16 6683
TEST_TIME 17 6874
TEST_TIME 18 6763
TEST_TIME 19 6980
TEST_TIME 20 7040
TEST_TIME 21 7101
TEST_TIME 22 7571
TEST_TIME 23 7841
TEST_TIME 24 7903
TEST_TIME 25 8230
TEST_TIME 26 9025
TEST_TIME 27 9832
TEST_TIME 28 10282
TEST_TIME 29 11003
TEST_TIME 30 11326
TEST_TIME 31 11225
TEST_TIME 32 10983
TEST_TIME 33 10972
TEST_TIME 34 11078
TEST_TIME 35 11161
TEST_TIME 36 11084
TEST_TIME 37 11044
TEST_TIME 38 11114
TEST_TIME 39 10916
TEST_TIME 40 10760
TEST_TIME 41 10626
TEST_TIME 42 10632
TEST_TIME 43 10726
TEST_TIME 44 10601
TEST_TIME 45 10594
TEST_TIME 46 10498
TEST_TIME 47 10492
TEST_TIME 48 10517
TEST_TIME 49 10440
TEST_TIME 50 10337
TEST_TIME 51 10333
TEST_TIME 52 10275
TEST_TIME 53 10149
TEST_TIME 54 10015
TEST_TIME 55 10053
TEST_TIME 56 10108
TEST_TIME 57 10055
TEST_TIME 58 10098
TEST_TIME 59 10096
TEST_TIME 60 10075
TEST_TIME 61 10072
TEST_TIME 62 10253
TEST_TIME 63 10252
TEST_TIME 64 10364
TEST_TIME 65 10297
TEST_TIME 66 10330
TEST_TIME 67 10460
TEST_TIME 68 10426
TEST_TIME 69 10450
TEST_TIME 70 10413
TEST_TIME 71 10540
TEST_TIME 72 10438
TEST_TIME 73 10373
TEST_TIME 74 10282
TEST_TIME 75 10254
TEST_TIME 76 10285
TEST_TIME 77 10224
TEST_TIME 78 10226
TEST_TIME 79 10202
TEST_TIME 80 10114
TEST_TIME 81 10081
TEST_TIME 82 10013
TEST_TIME 83 9959
TEST_TIME 84 9973
TEST_TIME 85 9920
TEST_TIME 86 10006
TEST_TIME 87 10041
TEST_TIME 88 10075
TEST_TIME 89 10045
TEST_TIME 90 10032
TEST_TIME 91 10153
TEST_TIME 92 10129
TEST_TIME 93 10145
TEST_TIME 94 10228
TEST_TIME 95 10299
TEST_TIME 96 10263
TEST_TIME 97 10215
TEST_TIME 98 10150
TEST_TIME 99 10114
TEST_TIME 100 10130
TEST_TIME 101 10073
TEST_TIME 102 10072
TEST_TIME 103 10061
TEST_TIME 104 10039
TEST_TIME 105 10062
TEST_TIME 106 10014
TEST_TIME 107 9966
TEST_TIME 108 9943
TEST_TIME 109 9912
TEST_TIME 110 9863
TEST_TIME 111 9861
TEST_TIME 112 9831
TEST_TIME 113 9854
TEST_TIME 114 9883
TEST_TIME 115 9899
TEST_TIME 116 9894
TEST_TIME 117 9923
TEST_TIME 118 9920
TEST_TIME 119 9903
TEST_TIME 120 9890

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-03-2023 01:39:32 to 08-03-2023 01:39:35.

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 1629407 1629407 0 0.00 0 19255 6 0 0
doc_list_biud 1629407 1629407 0 0.00 0 101814 29 0 0
sp_add_invoice_to_stock 5229 5082 147 2.81 17 148013 17158 0 147
sp_cancel_adding_invoice 6287 6287 0 0.00 4 69077 772 0 0
sp_cancel_client_order 1853 1853 0 0.00 3 15248 345 0 0
sp_cancel_customer_reserve 618262 618262 0 0.00 0 19400 60 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 3 8955 83 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 2033 63 0 0
sp_cancel_supplier_invoice 6908 6896 12 0.17 1 101819 774 0 12
sp_cancel_supplier_order 436 436 0 0.00 5 1098462 108273 0 0
sp_cancel_write_off 11027 11027 0 0.00 1 19695 109 0 0
sp_client_order 21626 21626 0 0.00 3 17601 349 0 0
sp_customer_reserve 476698 476698 0 0.00 0 35396 168 0 0
sp_fill_shopping_cart 33172 33172 0 0.00 1 16784 39 0 0
sp_fill_shopping_cart_clo_res 476694 476694 0 0.00 0 13019 12 0 0
sp_get_clo_for_invoice 5075 5075 0 0.00 0 19374 275 0 0
sp_kill_cost_storno 18403 18403 0 0.00 0 10474 54 0 0
sp_kill_qstorno_ret_qs2qd 1254883 1254883 0 0.00 0 101812 32 0 0
sp_kill_qty_storno 660359 660359 0 0.00 0 101813 70 0 0
sp_lock_selected_doc 21607 21607 0 0.00 0 6330 5 0 0
sp_make_cost_storno 29244 29244 0 0.00 0 19238 50 0 0
sp_multiply_rows_for_pdistr 29244 29244 0 0.00 0 11249 25 0 0
sp_multiply_rows_for_qdistr 21626 21626 0 0.00 0 11240 158 0 0
sp_pay_from_customer 7410 7410 0 0.00 4 13207 101 0 0
sp_pay_to_supplier 6236 6236 0 0.00 4 11806 115 0 0
sp_payment_common 13646 13646 0 0.00 1 13202 25 0 0
sp_qd_handle_on_cancel_clo 1853 1853 0 0.00 0 15240 315 0 0
sp_qd_handle_on_invoice_upd_sts 11362 11362 0 0.00 0 17439 324 0 0
sp_qd_handle_on_reserve_upd_sts 10523 10523 0 0.00 0 13405 57 0 0
sp_reserve_write_off 10523 10523 0 0.00 6 13516 175 0 0
sp_supplier_invoice 5720 5720 0 0.00 5 89028 3128 0 0
sp_supplier_order 5829 5829 0 0.00 4 93252 1278 0 0
srv_aggregate_perf_data 93 93 0 0.00 2910 662739 25417 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1645 127494 41031 0 0
srv_find_qd_qs_mism 1137519 1137519 0 0.00 0 8580 5 0 0
srv_make_invnt_saldo 610 610 0 0.00 48 114006 1101 0 0
srv_make_money_saldo 831 831 0 0.00 1 5626 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 52155 371210 235770 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5082 5082 0 0.00 0 13921 156 0 0
t$perf-norm:sp_cancel_adding_invoice 457 457 0 0.00 0 4211 111 0 0
t$perf-norm:sp_cancel_client_order 1853 1853 0 0.00 0 3773 15 0 0
t$perf-norm:sp_cancel_customer_reserve 741 741 0 0.00 0 1078 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 501 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 495 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 561 13 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 23200 1807 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 2711 11 0 0
t$perf-norm:sp_client_order 21626 21626 0 0.00 0 7310 17 0 0
t$perf-norm:sp_customer_reserve 1003 1003 0 0.00 0 5902 17 0 0
t$perf-norm:sp_pay_from_customer 7410 7410 0 0.00 0 10581 14 0 0
t$perf-norm:sp_pay_to_supplier 6236 6236 0 0.00 0 8460 10 0 0
t$perf-norm:sp_reserve_write_off 10523 10523 0 0.00 0 14619 12 0 0
t$perf-norm:sp_supplier_invoice 5720 5720 0 0.00 0 7825 17 0 0
t$perf-norm:sp_supplier_order 5829 5829 0 0.00 0 14711 18 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 31 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 133 4 0 0
t$perf-norm:srv_make_invnt_saldo 610 610 0 0.00 0 2893 23 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 1452 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 5 0 0
v_add_invoice_to_stock 10444 10150 294 2.81 0 9825 19 0 294
v_all_customers 39722 39722 0 0.00 0 52 1 0 0
v_all_suppliers 32744 32744 0 0.00 0 23 1 0 0
v_all_wares 43252 43252 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 914 914 0 0.00 0 2197 11 0 0
v_cancel_client_order 3706 3706 0 0.00 0 2171 6 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 1630 7 0 0
v_cancel_customer_reserve 1482 1482 0 0.00 0 2319 16 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 2017 7 0 0
v_cancel_supplier_invoice 876 852 24 2.73 0 7766 21 0 24
v_cancel_supplier_order 872 872 0 0.00 0 2247 7 0 0
v_cancel_write_off 2014 2014 0 0.00 0 5371 14 0 0
v_max_id_clo_ord 5829 5829 0 0.00 0 5873 4 0 0
v_max_id_clo_res 1003 1003 0 0.00 0 3287 17 0 0
v_max_id_ord_sup 5717 5717 0 0.00 0 9160 7 0 0
v_max_non_paid_invoice 1410 1410 0 0.00 0 2326 6 0 0
v_max_non_paid_realizn 3860 3860 0 0.00 0 1841 5 0 0
v_min_id_clo_ord 5829 5829 0 0.00 0 2518 6 0 0
v_min_id_clo_res 1003 1003 0 0.00 0 2413 8 0 0
v_min_id_ord_sup 5717 5717 0 0.00 0 6254 10 0 0
v_min_non_paid_invoice 6236 6236 0 0.00 0 8551 15 0 0
v_min_non_paid_realizn 7410 7410 0 0.00 0 13199 20 0 0
v_our_firm 10630 10630 0 0.00 0 213 1 0 0
v_reserve_write_off 21046 21046 0 0.00 0 9832 7 0 0
x_lock_depdocs_on_canc_invoice 12757 12757 0 0.00 0 12350 107 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 5446 310 0 0
x_make_qstorno_1000_1200 5829 5829 0 0.00 1 93239 1208 0 0
x_make_qstorno_1200_2000 5720 5720 0 0.00 1 89013 2976 0 0
x_make_qstorno_2100_3300 476698 476698 0 0.00 0 35394 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-03-2023 01:39:36 to 08-03-2023 01:39:40.

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:47 7 8192000000 8798879696 8810168320 5307904 0.064 2 1 2 0 5407504 56816 42784
23:09:48 32425 8192000000 9936330176 10251571200 1005530288 10.932 100 87 171 0 1038037376 10558800 21948288
23:11:31 15379 8192000000 9968933920 10297458688 1054153744 11.400 100 84 168 0 1083328624 8362944 20811936
23:12:51 14951 8192000000 10004709200 10338181120 1082730960 11.673 100 87 174 0 1114278624 10284464 21263200
23:14:13 8649 8192000000 10040343024 10392301568 1134308832 12.162 100 77 153 0 1162907088 11197264 17400992
23:15:23 5266 8192000000 10094592464 10452443136 1180423440 12.594 100 81 162 1 1211828752 13043056 18362256
23:16:31 2802 8192000000 10128735744 10498777088 1213717536 12.904 100 77 154 1 1244140432 13411040 17011856
23:17:35 7190 8192000000 10159954080 10534764544 1246386816 13.205 100 80 157 0 1277136112 13538112 17211184
23:18:45 1231 8192000000 10194888576 10573873152 1275290768 13.470 100 81 161 1 1307899312 15270976 17337568
23:19:47 3302 8192000000 10191370032 10584481792 1289695744 13.601 100 66 132 1 1317546944 13830928 14020272
23:20:51 6103 8192000000 10190471152 10599448576 1302268960 13.716 100 36 72 0 1317481520 8046880 7165680
23:22:00 8182 8192000000 10195175328 10606157824 1307282784 13.761 100 19 40 0 1317910880 5989968 4638128
23:23:09 41472 8192000000 10217896064 10624798720 1319459312 13.872 100 38 72 1 1332427440 4488592 8479536
23:24:51 5478 8192000000 10223682832 10636009472 1326559216 13.936 100 26 52 0 1338454656 5582672 6312768
23:25:58 10160 8192000000 10218034880 10641707008 1333146256 13.996 100 16 32 0 1342427360 5924000 3357104
23:27:10 51541 8192000000 10227024560 10648940544 1336415968 14.025 100 35 69 0 1350190112 5339584 8434560
23:29:06 8391 8192000000 10196628448 10611265536 1279490480 13.508 97 22 44 1 1290086224 5467632 5128112
23:30:15 8648 8192000000 10157224720 10562375680 1224677472 13.005 95 26 52 0 1236488000 5306192 6504336
23:31:24 11587 8192000000 10148731392 10544275456 1215521616 12.920 96 19 36 0 1225232112 5646944 4063552
23:32:38 28245 8192000000 10116873344 10517733376 1209109440 12.861 96 47 90 0 1225921792 4976880 11835472
23:34:07 5864 8192000000 10167408560 10568110080 1260703312 13.336 99 35 71 0 1274909472 6786672 7419488
23:35:15 9676 8192000000 10177493296 10588372992 1289148608 13.596 100 21 43 0 1300104160 5349776 5605776
23:36:25 8081 8192000000 10194545008 10602672128 1300071696 13.696 100 22 45 2 1309832752 4992960 4768096
23:37:35 34313 8192000000 10208775280 10618056704 1307232096 13.761 100 35 70 1 1320622672 5510384 7880192
23:39:12 5707 8192000000 10238086608 10645471232 1332796592 13.992 100 46 91 1 1351467040 7908608 10761840
23:40:22 5076 8192000000 10255155712 10662805504 1346786912 14.119 100 56 111 2 1369741024 11019344 11934768
23:41:32 26041 8192000000 10253466096 10674679808 1355439472 14.196 100 30 60 0 1373327648 10848784 7039392
23:43:05 35920 8192000000 10281019008 10703441920 1370805200 14.334 100 37 74 0 1395718576 16576784 8336592
23:44:48 24090 8192000000 10284848208 10712051712 1376489984 14.385 100 35 68 0 1404275248 18980368 8804896
23:46:22 48568 8192000000 10318306128 10746564608 1390842016 14.513 100 39 75 0 1423189472 22430704 9916752
23:48:19 5501 8192000000 10334399152 10760589312 1409552336 14.680 100 51 102 3 1444308208 22711776 12044096
23:49:30 3836 8192000000 10360052288 10781913088 1421755408 14.788 100 71 140 1 1470576400 31787936 17033056
23:50:42 4546 8192000000 10360803344 10777772032 1418186640 14.757 100 73 143 1 1469201632 33479856 17535136
23:51:51 12134 8192000000 10363789072 10792861696 1422998416 14.799 100 47 94 0 1479224160 44643776 11581968
23:53:06 1645 8192000000 10369025936 10779680768 1408279392 14.669 100 83 165 0 1471668448 43078112 20310944
23:54:13 3819 8192000000 10367491840 10776690688 1406844912 14.656 99 75 148 0 1471462512 46714048 17903552
23:55:23 2923 8192000000 10373566992 10784247808 1410490544 14.688 100 75 145 0 1473984160 45476816 18016800
23:56:33 1822 8192000000 10372876624 10784759808 1408278624 14.669 100 78 156 0 1478747312 49844800 20623888
23:57:43 4549 8192000000 10346401952 10754146304 1367706320 14.306 99 79 156 2 1444191776 56233360 20252096
23:58:57 2919 8192000000 10363998176 10777231360 1392654784 14.530 100 68 138 0 1467566352 55955936 18955632
00:00:05 2004 8192000000 10386862064 10798182400 1402228208 14.615 100 70 138 2 1490999056 70120464 18650384
00:01:19 6265 8192000000 10366295152 10785861632 1388015664 14.488 100 45 90 0 1471641632 70629328 12996640
00:02:38 16198 8192000000 10359853152 10780270592 1370956640 14.336 99 48 96 0 1454996144 69987312 14052192
00:03:55 9111 8192000000 10358329840 10782224384 1362689968 14.262 98 35 69 0 1447507216 74653632 10163616
00:05:12 16267 8192000000 10320397504 10768207872 1353276976 14.177 100 25 50 0 1432759040 71579872 7902192
00:06:50 9556 8192000000 10284729008 10734002176 1330109040 13.968 99 22 44 0 1380700000 44838608 5752352
00:08:21 127494 8192000000 10251962272 10686595072 1255718368 13.291 93 14 30 0 1274742128 15953760 3070000
00:11:43 68431 8192000000 10265656816 10710261760 1293550512 13.637 95 13 24 0 1306674688 10818944 2305232
00:13:53 124328 8192000000 10299413632 10750283776 1373007920 14.354 100 15 30 0 1397362976 21075936 3279120
00:16:58 92960 8192000000 10306385408 10745778176 1382082352 14.435 100 29 56 1 1404545232 15515760 6947120
00:19:40 90181 8192000000 10307830800 10758995968 1386862416 14.478 100 29 56 0 1410817856 17258736 6696704
00:22:20 41872 8192000000 10293284176 10747920384 1389296224 14.500 100 18 36 0 1400978784 7632288 4050272
00:24:03 25967 8192000000 10289814096 10744684544 1388802576 14.495 100 22 44 0 1401361072 7529312 5029184
00:25:38 16737 8192000000 10319376528 10759872512 1397810016 14.575 100 65 123 2 1418748736 7838768 13099952
00:27:04 33954 8192000000 10310474416 10772963328 1404937504 14.639 100 14 28 0 1416543744 8414976 3191264
00:28:39 27793 8192000000 10316787312 10784735232 1413928096 14.719 100 10 20 0 1431711056 15827904 1955056
00:30:08 29755 8192000000 10324710224 10791690240 1420192864 14.774 100 22 44 0 1435372944 10064800 5115280
00:31:41 54981 8192000000 10339903936 10804846592 1426772400 14.833 100 23 43 0 1451161456 19095200 5293856
00:33:40 35088 8192000000 10332927312 10811260928 1421769200 14.788 100 27 54 0 1445289280 16365216 7154864
00:35:16 78480 8192000000 10294578400 10765799424 1330922592 13.975 94 18 36 2 1347102944 12156704 4023648
00:37:41 18019 8192000000 10309518160 10767679488 1389842560 14.504 99 30 60 0 1404777248 8385664 6549024
00:39:06 84788 8192000000 10290878512 10762997760 1357043840 14.211 97 17 34 0 1378051632 17063376 3944416
00:41:31 64568 8192000000 10267475792 10713010176 1281774528 13.529 93 10 20 0 1296228480 12484736 1969216
00:43:49 24116 8192000000 10311726832 10749968384 1365410608 14.286 99 55 105 0 1387893024 10777056 11705360
00:45:19 57183 8192000000 10272679472 10732740608 1328469312 13.953 97 9 18 1 1344739296 14477376 1792608
00:47:17 25804 8192000000 10256101568 10699030528 1299824992 13.694 96 22 43 0 1313201936 8781248 4595696
00:48:54 46603 8192000000 10255475328 10701594624 1316373888 13.844 99 30 56 0 1340074432 16979968 6720576
00:50:47 107986 8192000000 10258079616 10702520320 1338239584 14.042 99 29 59 0 1357432128 12539744 6652800
00:54:02 34864 8192000000 10231904224 10682900480 1316852112 13.848 98 15 30 0 1331853088 11330688 3670288
00:55:41 65004 8192000000 10229282512 10678128640 1271499936 13.435 95 11 22 0 1283452880 9048848 2904096
00:57:52 60741 8192000000 10229873456 10690625536 1329101600 13.959 99 6 12 0 1337802656 7504080 1196976
00:59:53 54821 8192000000 10252240704 10710196224 1335498640 14.017 99 8 16 0 1345629296 8828192 1302464
01:01:48 55721 8192000000 10270459776 10737704960 1359075392 14.229 100 9 18 0 1377863616 16679840 2108384
01:03:55 67521 8192000000 10275612400 10741149696 1340986480 14.066 98 15 30 0 1363623056 18941056 3695520
01:06:06 46532 8192000000 10271554960 10733604864 1359331392 14.231 99 15 30 0 1377340032 14920544 3088096
01:07:56 17657 8192000000 10304101840 10747060224 1378399584 14.402 100 35 69 0 1395977104 10423200 7154320
01:09:16 61771 8192000000 10301976640 10759237632 1388352272 14.491 100 14 26 0 1404893056 13863840 2676944
01:11:18 25209 8192000000 10319689024 10769272832 1400801808 14.602 100 34 68 0 1417141760 8954736 7385216
01:12:49 90598 8192000000 10307680640 10779267072 1363399808 14.268 97 6 12 0 1380267760 15917984 949968
01:15:20 122197 8192000000 10308918736 10768375808 1391297888 14.517 99 23 46 0 1410928608 14098400 5532320
01:18:22 17986 8192000000 10311924240 10768101376 1397435904 14.572 99 44 87 1 1417144928 9406416 10302608
01:20:00 59514 8192000000 10297603088 10770907136 1399429392 14.590 100 11 22 0 1413964688 11488864 3046432
01:22:01 63029 8192000000 10287116576 10752339968 1319025792 13.868 94 19 35 3 1334155232 11097728 4031712
01:24:04 63917 8192000000 10260927824 10717564928 1336912960 14.030 98 23 46 1 1352187952 9773856 5501136
01:26:11 50448 8192000000 10260143680 10717478912 1347513648 14.125 99 16 32 0 1361043136 9778240 3751248
01:28:04 59219 8192000000 10263753872 10713886720 1290964256 13.613 94 11 22 0 1305497792 12065568 2467968
01:30:33 46649 8192000000 10273310160 10722299904 1347605744 14.126 98 19 38 0 1365987536 13924656 4457136
01:32:21 36406 8192000000 10273600960 10712936448 1315348272 13.835 96 31 62 0 1336453408 13199424 7905712
01:34:00 47745 8192000000 10267007888 10708758528 1318646720 13.864 97 22 42 0 1333694608 10325296 4722592
01:35:50 54071 8192000000 10261393632 10700451840 1300258176 13.698 96 28 56 0 1318331632 12173200 5900256
01:37:47 21774 8192000000 10280754768 10715287552 1353606464 14.180 100 48 96 1 1376552032 12812224 10133344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-03-2023 01:39:42 to 08-03-2023 01:39:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-03-2023 01:39:59 to 08-03-2023 01:40:01.

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          242919
MON$OLDEST_ACTIVE               246752
MON$OLDEST_SNAPSHOT             246752
MON$NEXT_TRANSACTION            246752
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                       2050656
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:1157: 
:1158: mscs-test-161.budzdorov.ru    Wed Mar  8 01:38:49 2023
:1159:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1160: 
:1161: 
:1162: mscs-test-161.budzdorov.ru    Wed Mar  8 01:38:49 2023
:1163:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1164: 
:1165: 
:1166: mscs-test-161.budzdorov.ru    Wed Mar  8 01:38:49 2023
:1167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1168: 
:1169: 
:1170: mscs-test-161.budzdorov.ru    Wed Mar  8 01:38:49 2023
:1171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1172: 
:1173: 
:1174: mscs-test-161.budzdorov.ru    Wed Mar  8 01:38:49 2023
:1175:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1176: 
--- end of diff output ---

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

08.03.23 01:40:02. 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
2023-03-08 01:40:04 No severe PSQL-related problems occured
08.03.23 01:40:05. End of report.