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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14894340 kB
MemAvailable 30062740 kB
Buffers 0 kB
Cached 16794572 kB
SwapCached 3988 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-12-17 01:38:59
DTS_END                         2022-12-17 01:38:34.7880
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56828
TRN_ID                          239384
ATT_ID                          274
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: 9348.42 23186 1121811
customer order: creation 169.18 359 20302
customer order: refuse 14.25 349 1710
order to supplier: creation 45.12 1272 5414
order to supplier: removal 3.54 102426 425
invoice (draft): creation 44.83 3120 5379
invoice (draft): removal 55.04 817 6605
invoice accept: apply 41.43 16859 4971
invoice accept: cancel 49.93 741 5991
customer reserve: creation 3748.10 170 449772
customer reserve: removal 4884.72 57 586166
realization accept: apply 82.42 172 9890
realization accept: cancel 80.98 117 9718
payment from customer: creation 59.06 116 7087
payment from customer: removal 5.70 77 684
payment to supplier: creation 48.88 138 5866
payment to supplier: removal 3.36 71 403
service: total inventory turnovers 4.59 1023 551
service: total monetary turnovers 6.34 35 761
service: aggregate perf. data 0.93 17853 112
service: refresh index statistics 0.03 317939 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-12-2022 01:39:40 to 17-12-2022 01:39:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5346
TEST_TIME 2 3660
TEST_TIME 3 3707
TEST_TIME 4 4225
TEST_TIME 5 4356
TEST_TIME 6 4963
TEST_TIME 7 5379
TEST_TIME 8 5109
TEST_TIME 9 5166
TEST_TIME 10 5512
TEST_TIME 11 5330
TEST_TIME 12 5340
TEST_TIME 13 5581
TEST_TIME 14 5956
TEST_TIME 15 5938
TEST_TIME 16 6026
TEST_TIME 17 6074
TEST_TIME 18 6262
TEST_TIME 19 6710
TEST_TIME 20 6832
TEST_TIME 21 7047
TEST_TIME 22 7268
TEST_TIME 23 7602
TEST_TIME 24 8110
TEST_TIME 25 8206
TEST_TIME 26 8438
TEST_TIME 27 8508
TEST_TIME 28 8473
TEST_TIME 29 8643
TEST_TIME 30 9101
TEST_TIME 31 10127
TEST_TIME 32 10026
TEST_TIME 33 9862
TEST_TIME 34 9839
TEST_TIME 35 9800
TEST_TIME 36 9618
TEST_TIME 37 9761
TEST_TIME 38 9866
TEST_TIME 39 9740
TEST_TIME 40 9599
TEST_TIME 41 9707
TEST_TIME 42 9759
TEST_TIME 43 9682
TEST_TIME 44 9693
TEST_TIME 45 9664
TEST_TIME 46 9661
TEST_TIME 47 9614
TEST_TIME 48 9687
TEST_TIME 49 9807
TEST_TIME 50 9801
TEST_TIME 51 9634
TEST_TIME 52 9539
TEST_TIME 53 9453
TEST_TIME 54 9400
TEST_TIME 55 9425
TEST_TIME 56 9353
TEST_TIME 57 9366
TEST_TIME 58 9473
TEST_TIME 59 9485
TEST_TIME 60 9432
TEST_TIME 61 9335
TEST_TIME 62 9284
TEST_TIME 63 9268
TEST_TIME 64 9224
TEST_TIME 65 9260
TEST_TIME 66 9255
TEST_TIME 67 9261
TEST_TIME 68 9300
TEST_TIME 69 9282
TEST_TIME 70 9360
TEST_TIME 71 9328
TEST_TIME 72 9334
TEST_TIME 73 9393
TEST_TIME 74 9476
TEST_TIME 75 9523
TEST_TIME 76 9551
TEST_TIME 77 9550
TEST_TIME 78 9551
TEST_TIME 79 9602
TEST_TIME 80 9510
TEST_TIME 81 9493
TEST_TIME 82 9493
TEST_TIME 83 9427
TEST_TIME 84 9476
TEST_TIME 85 9413
TEST_TIME 86 9386
TEST_TIME 87 9346
TEST_TIME 88 9292
TEST_TIME 89 9288
TEST_TIME 90 9268
TEST_TIME 91 9238
TEST_TIME 92 9200
TEST_TIME 93 9213
TEST_TIME 94 9307
TEST_TIME 95 9370
TEST_TIME 96 9355
TEST_TIME 97 9330
TEST_TIME 98 9341
TEST_TIME 99 9444
TEST_TIME 100 9494
TEST_TIME 101 9478
TEST_TIME 102 9564
TEST_TIME 103 9527
TEST_TIME 104 9503
TEST_TIME 105 9486
TEST_TIME 106 9452
TEST_TIME 107 9426
TEST_TIME 108 9450
TEST_TIME 109 9434
TEST_TIME 110 9389
TEST_TIME 111 9363
TEST_TIME 112 9317
TEST_TIME 113 9323
TEST_TIME 114 9304
TEST_TIME 115 9290
TEST_TIME 116 9330
TEST_TIME 117 9305
TEST_TIME 118 9302
TEST_TIME 119 9405
TEST_TIME 120 9355

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-12-2022 01:39:44 to 17-12-2022 01:39:47.

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 1540907 1540907 0 0.00 0 38680 6 0 0
doc_list_biud 1540906 1540906 0 0.00 0 24921 28 0 0
sp_add_invoice_to_stock 5148 4971 177 3.43 24 218752 16859 0 177
sp_cancel_adding_invoice 5991 5991 0 0.00 4 49156 741 0 0
sp_cancel_client_order 1710 1710 0 0.00 4 11166 349 0 0
sp_cancel_customer_reserve 586166 586166 0 0.00 0 20093 58 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 3721 77 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 2880 71 0 0
sp_cancel_supplier_invoice 6614 6605 9 0.13 1 22824 818 0 9
sp_cancel_supplier_order 425 425 0 0.00 10 1052718 102427 0 0
sp_cancel_write_off 9718 9718 0 0.00 1 15924 118 0 0
sp_client_order 20302 20302 0 0.00 3 30943 359 0 0
sp_customer_reserve 449772 449772 0 0.00 0 103742 170 0 0
sp_fill_shopping_cart 31094 31094 0 0.00 1 15318 36 0 0
sp_fill_shopping_cart_clo_res 449763 449763 0 0.00 0 22097 14 0 0
sp_get_clo_for_invoice 4962 4962 0 0.00 0 22235 295 0 0
sp_kill_cost_storno 16796 16796 0 0.00 0 8624 52 0 0
sp_kill_qstorno_ret_qs2qd 1189080 1189080 0 0.00 0 22805 32 0 0
sp_kill_qty_storno 625466 625466 0 0.00 0 24921 68 0 0
sp_lock_selected_doc 20598 20598 0 0.00 0 8088 5 0 0
sp_make_cost_storno 27805 27805 0 0.00 0 38667 57 0 0
sp_multiply_rows_for_pdistr 27804 27804 0 0.00 0 15827 36 0 0
sp_multiply_rows_for_qdistr 20302 20302 0 0.00 0 19908 162 0 0
sp_pay_from_customer 7087 7087 0 0.00 4 14544 117 0 0
sp_pay_to_supplier 5866 5866 0 0.00 4 15017 138 0 0
sp_payment_common 12953 12953 0 0.00 1 14836 35 0 0
sp_qd_handle_on_cancel_clo 1710 1710 0 0.00 0 11155 325 0 0
sp_qd_handle_on_invoice_upd_sts 10952 10952 0 0.00 0 24920 321 0 0
sp_qd_handle_on_reserve_upd_sts 9890 9890 0 0.00 0 13359 47 0 0
sp_reserve_write_off 9890 9890 0 0.00 6 17567 172 0 0
sp_supplier_invoice 5379 5379 0 0.00 6 90677 3121 0 0
sp_supplier_order 5414 5414 0 0.00 5 54653 1272 0 0
srv_aggregate_perf_data 112 112 0 0.00 2779 156824 17853 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1036 141591 48836 0 0
srv_find_qd_qs_mism 1076168 1076168 0 0.00 0 10171 4 0 0
srv_make_invnt_saldo 551 551 0 0.00 41 67895 1023 0 0
srv_make_money_saldo 761 761 0 0.00 1 10008 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 218401 417536 317939 0 0
t$perf-abend:sp_add_invoice_to_stock 177 177 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 4972 4972 0 0.00 0 12826 152 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 18036 104 0 0
t$perf-norm:sp_cancel_client_order 1710 1710 0 0.00 0 8663 19 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 5098 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 303 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 707 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 1949 21 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 31001 1860 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 2601 12 0 0
t$perf-norm:sp_client_order 20302 20302 0 0.00 0 15869 17 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 2229 5 0 0
t$perf-norm:sp_pay_from_customer 7087 7087 0 0.00 0 4928 14 0 0
t$perf-norm:sp_pay_to_supplier 5866 5866 0 0.00 0 6246 12 0 0
t$perf-norm:sp_reserve_write_off 9890 9890 0 0.00 0 8221 11 0 0
t$perf-norm:sp_supplier_invoice 5379 5379 0 0.00 0 7035 21 0 0
t$perf-norm:sp_supplier_order 5414 5414 0 0.00 0 13869 20 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 1023 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 10715 203 0 0
t$perf-norm:srv_make_invnt_saldo 551 551 0 0.00 0 4799 33 0 0
t$perf-norm:srv_make_money_saldo 761 761 0 0.00 0 4460 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10276 9922 354 3.44 0 14101 17 0 354
v_all_customers 37390 37390 0 0.00 0 17 0 0 0
v_all_suppliers 30190 30190 0 0.00 0 89 1 0 0
v_all_wares 40604 40604 0 0.00 0 38 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 3049 10 0 0
v_cancel_client_order 3420 3420 0 0.00 0 3887 6 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 3278 11 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 2514 8 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 2859 8 0 0
v_cancel_supplier_invoice 882 864 18 2.04 0 1735 20 0 18
v_cancel_supplier_order 850 850 0 0.00 0 2085 11 0 0
v_cancel_write_off 2056 2056 0 0.00 0 3076 15 0 0
v_max_id_clo_ord 5414 5414 0 0.00 0 455 1 0 0
v_max_id_clo_res 971 971 0 0.00 0 3003 20 0 0
v_max_id_ord_sup 5378 5378 0 0.00 0 6059 6 0 0
v_max_non_paid_invoice 1563 1563 0 0.00 0 4358 5 0 0
v_max_non_paid_realizn 3668 3668 0 0.00 0 1184 4 0 0
v_min_id_clo_ord 5414 5414 0 0.00 0 3241 6 0 0
v_min_id_clo_res 971 971 0 0.00 0 2928 11 0 0
v_min_id_ord_sup 5378 5378 0 0.00 0 4560 7 0 0
v_min_non_paid_invoice 5866 5866 0 0.00 0 14829 30 0 0
v_min_non_paid_realizn 7087 7087 0 0.00 0 12849 26 0 0
v_our_firm 10052 10052 0 0.00 0 15 1 0 0
v_reserve_write_off 19780 19780 0 0.00 0 2543 5 0 0
x_lock_depdocs_on_canc_invoice 12164 12164 0 0.00 0 15185 105 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 9034 393 0 0
x_make_qstorno_1000_1200 5414 5414 0 0.00 1 54639 1201 0 0
x_make_qstorno_1200_2000 5379 5379 0 0.00 3 90666 2988 0 0
x_make_qstorno_2100_3300 449772 449772 0 0.00 0 103660 153 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 17-12-2022 01:39:48 to 17-12-2022 01:39:53.

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:38 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:39 30101 8192000000 9879548368 10186350592 962801840 10.516 100 91 182 0 993842144 6354352 24685952
23:11:16 18601 8192000000 9925068064 10238930944 1003288224 10.910 100 93 186 0 1036452064 8674336 24489504
23:12:40 5841 8192000000 9942851280 10269298688 1030738784 11.176 100 77 154 0 1060455792 9968544 19748464
23:13:55 9960 8192000000 10011859360 10351206400 1096389632 11.803 100 85 170 0 1128445056 11903632 20151792
23:15:08 6591 8192000000 10055146416 10408542208 1142950752 12.243 100 82 162 0 1174696400 12387360 19358288
23:16:18 4352 8192000000 10099764752 10462056448 1190156128 12.685 100 70 138 2 1217134368 11923056 15055184
23:17:25 2246 8192000000 10149831872 10520059904 1241601152 13.161 100 78 156 1 1269951536 11339376 17011008
23:18:30 1140 8192000000 10184250416 10563919872 1266288816 13.388 100 91 178 0 1302783504 16337376 20157312
23:19:33 1089 8192000000 10200507984 10583453696 1286629264 13.574 100 82 163 3 1318934432 13721792 18583376
23:20:35 2206 8192000000 10208828512 10605834240 1307712752 13.765 100 64 125 0 1329985184 7716608 14555824
23:21:39 5897 8192000000 10200190128 10611712000 1314348448 13.826 100 29 58 1 1332347152 12111264 5887440
23:22:46 2588 8192000000 10218752144 10626875392 1320731936 13.883 100 35 69 2 1336126976 8200368 7194672
23:23:50 65603 8192000000 10212425024 10634145792 1324498704 13.917 100 34 68 1 1338013632 5786176 7728752
23:25:56 5303 8192000000 10224872592 10646880256 1332145008 13.987 100 26 53 0 1348199200 10366032 5688160
23:27:02 8749 8192000000 10224105184 10647769088 1336584816 14.027 100 24 48 0 1352660304 10958912 5116576
23:28:11 10032 8192000000 10195592496 10613592064 1281048288 13.523 97 20 40 1 1295371888 10444128 3879472
23:29:24 29345 8192000000 10190198160 10594906112 1245814432 13.200 95 35 70 0 1259893792 5998848 8080512
23:30:55 4665 8192000000 10141689744 10531274752 1185948576 12.646 93 34 68 1 1202690800 9391936 7350288
23:32:00 8805 8192000000 10122441408 10521980928 1213366288 12.900 98 20 40 0 1224197712 5994736 4836688
23:33:09 11892 8192000000 10147727296 10550194176 1235476480 13.105 97 18 35 0 1244663936 5123488 4063968
23:34:22 33237 8192000000 10172626416 10580713472 1255954400 13.293 98 28 54 0 1267603808 5559168 6090240
23:35:59 5228 8192000000 10186974864 10601373696 1300710928 13.702 100 18 36 0 1310234720 5019568 4504224
23:37:04 13825 8192000000 10197330688 10612457472 1306141120 13.751 100 15 27 0 1314327952 5304240 2882592
23:38:22 8232 8192000000 10222636720 10636644352 1326176224 13.933 100 31 59 0 1338517008 6339168 6001616
23:39:39 24137 8192000000 10247455472 10663682048 1344507248 14.098 100 38 74 1 1362774832 9671792 8595792
23:41:07 31225 8192000000 10250008816 10675085312 1353401280 14.178 100 29 58 0 1370465264 10524080 6539904
23:43:00 18065 8192000000 10268320320 10694742016 1366015600 14.291 100 29 56 0 1382861312 10478816 6366896
23:44:24 9850 8192000000 10288745104 10714718208 1381323184 14.428 100 47 94 1 1406389008 14138496 10927328
23:46:29 31580 8192000000 10315539168 10739691520 1373824496 14.361 98 42 84 1 1397730752 14204544 9701712
23:48:05 20172 8192000000 10335884672 10762805248 1399910960 14.594 99 43 86 0 1435296848 25172224 10213664
23:49:31 16641 8192000000 10328207936 10758643712 1402596368 14.618 99 45 90 0 1433379536 20400240 10382928
23:50:51 2194 8192000000 10317848256 10724589568 1380690784 14.423 98 67 130 1 1424694416 26754640 17248992
23:52:00 2166 8192000000 10347590176 10764980224 1410068400 14.685 100 60 118 1 1452716512 27845072 14803040
23:53:08 3057 8192000000 10357254176 10778050560 1409354368 14.678 99 68 136 1 1458901136 33167680 16379088
23:54:16 2679 8192000000 10369521760 10790809600 1423448512 14.803 100 56 111 0 1473707152 36195040 14063600
23:55:37 7372 8192000000 10360253568 10781073408 1414012832 14.720 100 61 119 0 1469021424 39467424 15541168
23:56:50 5127 8192000000 10358667136 10776584192 1393828528 14.540 98 60 120 0 1454080464 44063872 16188064
23:58:01 3785 8192000000 10358112368 10776682496 1382846272 14.442 98 60 120 1 1445157616 46439120 15872224
23:59:10 3393 8192000000 10375988976 10787102720 1407889488 14.665 100 60 120 0 1475200848 51590464 15720896
00:00:23 1035 8192000000 10362107520 10779701248 1399176272 14.588 100 65 132 0 1468398176 52216000 17005904
00:01:31 2161 8192000000 10365155552 10775506944 1386705408 14.476 99 87 170 1 1465146608 56256992 22184208
00:02:38 23225 8192000000 10320258656 10755575808 1359475936 14.233 98 28 56 0 1415312544 47804432 8032176
00:04:07 16613 8192000000 10323916304 10743414784 1369778400 14.325 100 48 96 0 1429936656 46761824 13396432
00:05:26 28301 8192000000 10294773088 10723897344 1311643680 13.801 96 25 50 1 1362944544 44584480 6716384
00:07:02 27395 8192000000 10289257312 10729091072 1341958704 14.075 100 24 48 1 1405635120 56465824 7210592
00:08:32 40273 8192000000 10315730176 10749693952 1337758064 14.037 99 26 52 1 1396068496 50627312 7683120
00:10:17 40418 8192000000 10294113216 10733932544 1363473952 14.269 99 38 76 0 1390398576 18695824 8228800
00:11:58 94025 8192000000 10280524976 10738561024 1342418400 14.079 98 14 26 0 1357619648 12763488 2437760
00:14:51 94936 8192000000 10271160448 10716184576 1328518240 13.954 97 17 35 0 1357188368 24773728 3896400
00:17:27 50366 8192000000 10287352208 10720452608 1342409808 14.079 98 26 50 0 1360720480 12337456 5973216
00:19:20 34477 8192000000 10300221568 10739658752 1379277808 14.410 100 21 41 0 1398170544 14328864 4563872
00:20:59 110332 8192000000 10296878864 10737897472 1373998160 14.363 99 3 6 0 1389774656 15333328 443168
00:23:51 116173 8192000000 10312922560 10754891776 1378928032 14.407 98 9 18 0 1392476016 11664560 1883424
00:26:48 101843 8192000000 10313049456 10776891392 1389370304 14.500 99 18 36 1 1408712256 15802816 3539136
00:29:35 100282 8192000000 10322891840 10762919936 1387152688 14.480 99 32 64 0 1401868832 7439168 7276976
00:32:17 128089 8192000000 10336289840 10790424576 1416920224 14.745 100 33 66 0 1441777552 17258368 7598960
00:35:31 29037 8192000000 10334874688 10793222144 1411905216 14.701 100 23 46 0 1436612336 19381872 5325248
00:37:08 57476 8192000000 10317652080 10785554432 1412748576 14.708 100 8 16 0 1425143584 10630736 1764272
00:39:10 26440 8192000000 10291042176 10747973632 1379442256 14.412 98 8 16 0 1386445216 5061200 1941760
00:40:47 49770 8192000000 10296796032 10767761408 1390733024 14.512 99 7 14 0 1400629488 8330608 1565856
00:43:06 53429 8192000000 10271703008 10741600256 1346838112 14.119 97 12 24 0 1357642432 7951216 2853104
00:45:12 6570 8192000000 10304120592 10756329472 1398411504 14.581 100 45 90 1 1413906160 5626832 9867824
00:46:27 88010 8192000000 10276986400 10733338624 1296028448 13.659 93 15 30 0 1315795904 16792416 2975040
00:48:56 64369 8192000000 10295708592 10744078336 1357473536 14.215 98 21 42 0 1373128656 10755792 4899328
00:51:16 56682 8192000000 10247668144 10713890816 1317998544 13.859 96 18 36 0 1342840416 20520768 4321104
00:53:18 46953 8192000000 10274161664 10720915456 1350629568 14.153 99 31 62 1 1369185152 11927248 6628336
00:55:07 44388 8192000000 10264904960 10711277568 1332406304 13.989 98 21 42 0 1347574096 10582160 4585632
00:56:52 116991 8192000000 10272086512 10728185856 1295795632 13.657 94 19 38 1 1317626368 17698080 4132656
01:00:14 83855 8192000000 10266486656 10717429760 1343065744 14.085 98 24 46 0 1359612576 11381120 5165712
01:02:38 95741 8192000000 10268950416 10729914368 1348441424 14.133 98 10 21 0 1364556384 13532384 2582576
01:05:14 81868 8192000000 10247227792 10706395136 1293344336 13.635 94 15 30 0 1307849808 10761984 3743488
01:07:45 30929 8192000000 10265335296 10721800192 1343696928 14.091 99 18 36 1 1363096928 15388336 4011664
01:09:21 55099 8192000000 10291307424 10746486784 1353778512 14.181 98 21 41 0 1367034544 8395024 4861008
01:11:20 56499 8192000000 10291677968 10747564032 1365148912 14.284 98 17 33 0 1382966304 14018512 3798880
01:13:18 35785 8192000000 10279630832 10738147328 1357484352 14.215 98 13 26 1 1375141904 14350448 3307104
01:14:54 46359 8192000000 10316841872 10768719872 1401066352 14.604 100 35 70 0 1424702096 15458016 8177728
01:16:41 75442 8192000000 10299492512 10772447232 1386662528 14.476 99 15 30 0 1406134304 15815856 3655920
01:19:02 141591 8192000000 10309310960 10780495872 1347786672 14.128 96 6 12 0 1373592672 24563104 1242896
01:22:24 17233 8192000000 10322884928 10760732672 1391803552 14.522 100 52 102 1 1415380384 12698576 10878256
01:23:41 90824 8192000000 10311883216 10755235840 1378372576 14.402 99 41 82 1 1409376720 21441472 9562672
01:26:30 33533 8192000000 10290657392 10767966208 1397284656 14.571 100 5 10 0 1413709776 15495296 929824
01:28:07 79430 8192000000 10277940176 10747949056 1367212320 14.302 98 11 22 0 1378067744 8258192 2597232
01:30:27 92196 8192000000 10261719088 10726674432 1294466368 13.645 94 11 22 0 1303843136 6798800 2577968
01:33:00 55765 8192000000 10276618096 10731335680 1339565648 14.053 97 19 36 0 1354101456 10773888 3761920
01:35:06 53031 8192000000 10276438128 10723942400 1334031968 14.004 97 23 46 0 1348921600 8999696 5889936
01:36:59 71130 8192000000 10287961712 10756546560 1370334720 14.330 100 17 35 0 1393364672 19095216 3934736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-12-2022 01:39:54 to 17-12-2022 01:40:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 177 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 17-12-2022 01:40:11 to 17-12-2022 01:40:13.

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          255050
MON$OLDEST_ACTIVE               255051
MON$OLDEST_SNAPSHOT             255051
MON$NEXT_TRANSACTION            255051
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                       2045024
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
--- end of diff output ---

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

17.12.22 01:40:14. 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-12-17 01:40:16 No severe PSQL-related problems occured
17.12.22 01:40:18. End of report.