Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.02.2023 16:42
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 15472588 kB
MemAvailable 30419184 kB
Buffers 0 kB
Cached 16220176 kB
SwapCached 3544 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-02-09 16:42:41
DTS_END                         2023-02-09 16:42:20.8170
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/45736
TRN_ID                          242969
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: 9520.13 24342 1142416
customer order: creation 176.05 381 21126
customer order: refuse 14.68 362 1762
order to supplier: creation 46.55 1296 5586
order to supplier: removal 3.63 98836 435
invoice (draft): creation 45.98 3120 5517
invoice (draft): removal 53.91 762 6469
invoice accept: apply 42.28 17240 5073
invoice accept: cancel 48.91 806 5869
customer reserve: creation 3986.30 167 478356
customer reserve: removal 4799.16 58 575899
realization accept: apply 84.61 187 10153
realization accept: cancel 85.23 117 10227
payment from customer: creation 60.65 106 7278
payment from customer: removal 6.10 84 732
payment to supplier: creation 51.10 128 6132
payment to supplier: removal 3.31 50 397
service: total inventory turnovers 4.47 1011 537
service: total monetary turnovers 6.24 41 749
service: aggregate perf. data 0.96 13684 115
service: refresh index statistics 0.03 348406 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-02-2023 16:43:20 to 09-02-2023 16:43:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3686
TEST_TIME 2 3883
TEST_TIME 3 3601
TEST_TIME 4 4170
TEST_TIME 5 4099
TEST_TIME 6 4276
TEST_TIME 7 4650
TEST_TIME 8 5135
TEST_TIME 9 5282
TEST_TIME 10 5475
TEST_TIME 11 5517
TEST_TIME 12 5902
TEST_TIME 13 6202
TEST_TIME 14 6464
TEST_TIME 15 6670
TEST_TIME 16 7512
TEST_TIME 17 8139
TEST_TIME 18 8300
TEST_TIME 19 8650
TEST_TIME 20 8923
TEST_TIME 21 9206
TEST_TIME 22 9961
TEST_TIME 23 10468
TEST_TIME 24 10292
TEST_TIME 25 10055
TEST_TIME 26 10998
TEST_TIME 27 11102
TEST_TIME 28 10974
TEST_TIME 29 10834
TEST_TIME 30 10522
TEST_TIME 31 10374
TEST_TIME 32 10373
TEST_TIME 33 10269
TEST_TIME 34 10205
TEST_TIME 35 10093
TEST_TIME 36 10194
TEST_TIME 37 10076
TEST_TIME 38 9966
TEST_TIME 39 9935
TEST_TIME 40 9813
TEST_TIME 41 9820
TEST_TIME 42 9961
TEST_TIME 43 9921
TEST_TIME 44 10136
TEST_TIME 45 10029
TEST_TIME 46 10022
TEST_TIME 47 10222
TEST_TIME 48 10130
TEST_TIME 49 10067
TEST_TIME 50 10163
TEST_TIME 51 10142
TEST_TIME 52 10068
TEST_TIME 53 10208
TEST_TIME 54 10358
TEST_TIME 55 10235
TEST_TIME 56 10182
TEST_TIME 57 10099
TEST_TIME 58 10034
TEST_TIME 59 10059
TEST_TIME 60 9984
TEST_TIME 61 9912
TEST_TIME 62 9910
TEST_TIME 63 9782
TEST_TIME 64 9739
TEST_TIME 65 9774
TEST_TIME 66 9810
TEST_TIME 67 9811
TEST_TIME 68 9770
TEST_TIME 69 9813
TEST_TIME 70 9800
TEST_TIME 71 9845
TEST_TIME 72 9850
TEST_TIME 73 9836
TEST_TIME 74 9965
TEST_TIME 75 9946
TEST_TIME 76 9952
TEST_TIME 77 9941
TEST_TIME 78 9958
TEST_TIME 79 9867
TEST_TIME 80 9850
TEST_TIME 81 9787
TEST_TIME 82 9812
TEST_TIME 83 9731
TEST_TIME 84 9736
TEST_TIME 85 9710
TEST_TIME 86 9658
TEST_TIME 87 9667
TEST_TIME 88 9630
TEST_TIME 89 9616
TEST_TIME 90 9582
TEST_TIME 91 9539
TEST_TIME 92 9610
TEST_TIME 93 9629
TEST_TIME 94 9644
TEST_TIME 95 9709
TEST_TIME 96 9690
TEST_TIME 97 9722
TEST_TIME 98 9716
TEST_TIME 99 9722
TEST_TIME 100 9710
TEST_TIME 101 9788
TEST_TIME 102 9770
TEST_TIME 103 9750
TEST_TIME 104 9741
TEST_TIME 105 9681
TEST_TIME 106 9700
TEST_TIME 107 9671
TEST_TIME 108 9655
TEST_TIME 109 9604
TEST_TIME 110 9568
TEST_TIME 111 9553
TEST_TIME 112 9522
TEST_TIME 113 9491
TEST_TIME 114 9521
TEST_TIME 115 9498
TEST_TIME 116 9485
TEST_TIME 117 9562
TEST_TIME 118 9555
TEST_TIME 119 9574
TEST_TIME 120 9523

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-02-2023 16:43:24 to 09-02-2023 16:43:27.

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 1584869 1584869 0 0.00 0 30800 6 0 0
doc_list_biud 1584869 1584869 0 0.00 0 56209 27 0 0
sp_add_invoice_to_stock 5207 5073 134 2.57 27 156674 17240 0 134
sp_cancel_adding_invoice 5869 5869 0 0.00 3 54803 807 0 0
sp_cancel_client_order 1762 1762 0 0.00 4 10203 362 0 0
sp_cancel_customer_reserve 575899 575899 0 0.00 0 56213 59 0 0
sp_cancel_pay_from_customer 732 732 0 0.00 3 4765 84 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 1261 50 0 0
sp_cancel_supplier_invoice 6483 6469 14 0.21 2 14464 762 0 14
sp_cancel_supplier_order 435 435 0 0.00 7 1102753 98836 0 0
sp_cancel_write_off 10227 10227 0 0.00 1 22184 118 0 0
sp_client_order 21126 21126 0 0.00 3 68934 382 0 0
sp_customer_reserve 478356 478356 0 0.00 0 131948 167 0 0
sp_fill_shopping_cart 32226 32226 0 0.00 1 17024 37 0 0
sp_fill_shopping_cart_clo_res 478345 478345 0 0.00 0 12865 14 0 0
sp_get_clo_for_invoice 5062 5062 0 0.00 0 27392 310 0 0
sp_kill_cost_storno 17225 17225 0 0.00 0 5664 52 0 0
sp_kill_qstorno_ret_qs2qd 1168929 1168929 0 0.00 0 56207 32 0 0
sp_kill_qty_storno 615876 615876 0 0.00 0 56209 69 0 0
sp_lock_selected_doc 21104 21104 0 0.00 0 8117 8 0 0
sp_make_cost_storno 28625 28625 0 0.00 0 30790 50 0 0
sp_multiply_rows_for_pdistr 28625 28625 0 0.00 0 9428 27 0 0
sp_multiply_rows_for_qdistr 21126 21126 0 0.00 0 14378 167 0 0
sp_pay_from_customer 7278 7278 0 0.00 4 17177 106 0 0
sp_pay_to_supplier 6132 6132 0 0.00 4 15518 129 0 0
sp_payment_common 13410 13410 0 0.00 1 13725 26 0 0
sp_qd_handle_on_cancel_clo 1762 1762 0 0.00 0 10194 330 0 0
sp_qd_handle_on_invoice_upd_sts 10931 10931 0 0.00 0 16120 337 0 0
sp_qd_handle_on_reserve_upd_sts 10153 10153 0 0.00 0 22977 68 0 0
sp_reserve_write_off 10153 10153 0 0.00 6 23009 187 0 0
sp_supplier_invoice 5517 5517 0 0.00 5 60240 3121 0 0
sp_supplier_order 5586 5586 0 0.00 5 38167 1296 0 0
srv_aggregate_perf_data 115 115 0 0.00 2834 56960 13685 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1169 159788 42051 0 0
srv_find_qd_qs_mism 1093105 1093105 0 0.00 0 22179 5 0 0
srv_make_invnt_saldo 537 537 0 0.00 29 30402 1012 0 0
srv_make_money_saldo 749 749 0 0.00 0 6777 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 262759 440733 348407 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5074 5074 0 0.00 0 11782 151 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 4312 99 0 0
t$perf-norm:sp_cancel_client_order 1762 1762 0 0.00 0 828 8 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 4421 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 732 732 0 0.00 0 5839 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 597 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 878 9 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 17711 1351 0 0
t$perf-norm:sp_cancel_write_off 1067 1067 0 0.00 0 1477 8 0 0
t$perf-norm:sp_client_order 21126 21126 0 0.00 0 10295 16 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 729 3 0 0
t$perf-norm:sp_pay_from_customer 7278 7278 0 0.00 0 5791 12 0 0
t$perf-norm:sp_pay_to_supplier 6132 6132 0 0.00 0 8228 7 0 0
t$perf-norm:sp_reserve_write_off 10153 10153 0 0.00 0 8172 11 0 0
t$perf-norm:sp_supplier_invoice 5517 5517 0 0.00 0 6572 16 0 0
t$perf-norm:sp_supplier_order 5586 5586 0 0.00 0 3165 12 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 496 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 555 9 0 0
t$perf-norm:srv_make_invnt_saldo 537 537 0 0.00 0 7277 35 0 0
t$perf-norm:srv_make_money_saldo 749 749 0 0.00 0 6546 31 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10392 10124 268 2.57 0 5967 17 0 268
v_all_customers 38724 38724 0 0.00 0 17 1 0 0
v_all_suppliers 31636 31636 0 0.00 0 82 1 0 0
v_all_wares 42248 42248 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 97 6 0 0
v_cancel_client_order 3524 3524 0 0.00 0 5647 6 0 0
v_cancel_customer_prepayment 1464 1464 0 0.00 0 4138 10 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 220 3 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 123 3 0 0
v_cancel_supplier_invoice 834 806 28 3.35 0 301 12 0 28
v_cancel_supplier_order 870 870 0 0.00 0 4284 11 0 0
v_cancel_write_off 2134 2134 0 0.00 0 4299 14 0 0
v_max_id_clo_ord 5585 5585 0 0.00 0 3482 2 0 0
v_max_id_clo_res 963 963 0 0.00 0 2230 12 0 0
v_max_id_ord_sup 5517 5517 0 0.00 0 7448 8 0 0
v_max_non_paid_invoice 1416 1416 0 0.00 0 948 4 0 0
v_max_non_paid_realizn 3738 3738 0 0.00 0 7814 9 0 0
v_min_id_clo_ord 5585 5585 0 0.00 0 3780 6 0 0
v_min_id_clo_res 963 963 0 0.00 0 5297 25 0 0
v_min_id_ord_sup 5517 5517 0 0.00 0 7078 5 0 0
v_min_non_paid_invoice 6132 6132 0 0.00 0 7597 17 0 0
v_min_non_paid_realizn 7278 7278 0 0.00 0 9360 18 0 0
v_our_firm 10604 10604 0 0.00 0 20 1 0 0
v_reserve_write_off 20306 20306 0 0.00 0 5716 8 0 0
x_lock_depdocs_on_canc_invoice 11935 11935 0 0.00 0 11971 129 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 8129 365 0 0
x_make_qstorno_1000_1200 5586 5586 0 0.00 1 38122 1225 0 0
x_make_qstorno_1200_2000 5517 5517 0 0.00 2 60233 2979 0 0
x_make_qstorno_2100_3300 478356 478356 0 0.00 0 131946 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-02-2023 16:43:28 to 09-02-2023 16:43:32.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:12:24 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:24 32135 8192000000 9926422368 10243166208 1003572304 10.913 100 89 178 0 1032239904 6110768 22556832
14:15:01 16326 8192000000 9965864176 10291032064 1045129728 11.314 100 92 184 0 1077287728 8628144 23529856
14:16:23 13851 8192000000 9981363680 10316869632 1065871056 11.513 100 86 172 0 1097685456 10590496 21223904
14:17:38 8751 8192000000 10030686288 10379497472 1118351840 12.011 100 80 159 0 1147724864 10929712 18443312
14:18:50 6238 8192000000 10090484416 10445156352 1170701744 12.503 100 81 159 1 1200644192 11878640 18063808
14:19:59 4783 8192000000 10132526784 10501464064 1220781200 12.969 100 79 159 1 1251155040 12864176 17509664
14:21:06 2605 8192000000 10162354544 10542673920 1249756880 13.236 100 77 154 1 1280375872 13411568 17207424
14:22:10 1542 8192000000 10194450496 10574151680 1277324784 13.489 100 83 158 1 1306483136 12451440 16706912
14:23:14 1171 8192000000 10221040912 10606759936 1302451968 13.718 100 90 180 0 1337034848 14253392 20329488
14:24:16 15186 8192000000 10220236512 10620116992 1312713504 13.811 100 54 108 0 1332965824 7557344 12694976
14:25:32 7868 8192000000 10206325904 10623877120 1321478688 13.890 100 21 40 0 1332354528 6650672 4225168
14:26:40 11302 8192000000 10213037696 10629984256 1323967952 13.913 100 16 31 0 1333091200 5862112 3261136
14:27:53 70913 8192000000 10232361712 10644246528 1332567120 13.990 100 42 82 2 1347526064 5132576 9826368
14:30:04 12397 8192000000 10228388000 10652078080 1334960416 14.012 100 20 42 1 1345505072 5949904 4594752
14:31:17 12250 8192000000 10221922864 10647293952 1324138528 13.914 99 20 40 0 1334727792 5417760 5171504
14:32:30 15167 8192000000 10234368176 10644496384 1331323088 13.979 100 44 93 0 1347416816 6146608 9947120
14:33:46 4639 8192000000 10165875648 10577289216 1267622000 13.400 98 28 55 0 1279931360 6087696 6221664
14:34:57 7603 8192000000 10138045024 10541694976 1197899328 12.757 95 28 55 1 1210000128 5931120 6169680
14:36:08 50219 8192000000 10170247568 10567942144 1258433744 13.316 99 37 67 1 1271915184 5198176 8283264
14:38:00 5083 8192000000 10163377072 10565795840 1256012112 13.293 99 28 56 1 1269111008 6975072 6123824
14:39:08 4952 8192000000 10201629728 10607423488 1295340448 13.653 100 23 47 0 1306142640 5226736 5575456
14:40:24 5964 8192000000 10200844160 10612367360 1310336672 13.789 100 26 52 0 1321032000 5221312 5474016
14:41:31 29977 8192000000 10202737040 10617458688 1311639264 13.801 100 27 53 0 1322351344 4553520 6158560
14:43:09 30931 8192000000 10241079024 10656264192 1341142016 14.068 100 45 90 1 1359528288 8668816 9717456
14:44:43 21273 8192000000 10270121936 10687885312 1363695600 14.271 100 45 90 2 1387961760 13698176 10567984
14:46:19 54399 8192000000 10289415424 10713624576 1378131696 14.400 100 35 69 0 1403975888 16906304 8937888
14:48:24 26022 8192000000 10305926464 10729771008 1392167040 14.525 100 41 82 0 1422766624 20519760 10079824
14:49:56 14686 8192000000 10317585504 10743255040 1382233408 14.437 99 36 72 0 1422644000 31181280 9229312
14:51:15 5966 8192000000 10314315136 10738024448 1384967552 14.461 99 66 124 1 1421394816 21990320 14436944
14:52:27 6843 8192000000 10325655296 10745188352 1388981664 14.497 99 58 116 1 1427806736 24714160 14110912
14:53:38 1795 8192000000 10360049376 10778877952 1419423344 14.768 100 73 147 0 1465910000 28290192 18196464
14:54:57 2172 8192000000 10369770944 10794352640 1430630768 14.867 100 66 130 2 1480559472 32762736 17165968
14:56:04 1168 8192000000 10391771536 10806530048 1428292880 14.846 99 88 172 1 1485347392 35875200 21179312
14:57:11 6251 8192000000 10391546992 10815959040 1440018432 14.950 100 69 138 0 1499528384 41915440 17594512
14:58:21 1258 8192000000 10390680624 10796523520 1418713792 14.761 100 93 185 2 1492573808 51034320 22825696
14:59:25 11468 8192000000 10340816592 10768158720 1405182080 14.641 100 42 84 0 1449728240 32767232 11778928
15:00:41 8243 8192000000 10323006304 10746130432 1363292176 14.267 98 47 94 0 1413094176 37082000 12720000
15:01:53 16608 8192000000 10314069632 10740744192 1353572480 14.180 98 41 82 0 1402894784 37383840 11938464
15:03:25 1350 8192000000 10346071216 10755063808 1379127216 14.409 99 75 147 0 1441341584 43987552 18226816
15:04:28 12331 8192000000 10325089664 10781130752 1380412016 14.420 99 38 76 0 1437013312 46139712 10461584
15:05:52 29760 8192000000 10285342272 10728755200 1333585328 14.000 97 11 22 0 1366679520 30183168 2911024
15:07:25 38084 8192000000 10297266192 10744598528 1351177472 14.158 99 21 42 0 1394868624 37211984 6479168
15:09:04 33993 8192000000 10235070320 10670280704 1283763920 13.547 96 28 57 0 1302600064 11819984 7016160
15:10:50 17893 8192000000 10268397776 10695462912 1348900224 14.138 100 43 88 1 1365544704 7526800 9117680
15:12:11 79374 8192000000 10266239072 10694877184 1327085360 13.941 98 45 89 1 1345375776 7386928 10903488
15:14:36 159788 8192000000 10271423024 10716930048 1299521248 13.691 95 32 62 1 1317967408 11207232 7238928
15:18:17 45421 8192000000 10289454784 10734886912 1358982496 14.228 99 27 53 0 1382512992 16295680 7234816
15:20:04 51176 8192000000 10287474416 10738098176 1382774528 14.441 100 24 48 0 1409551760 21011488 5765744
15:21:55 33131 8192000000 10297657968 10747154432 1385763216 14.468 100 14 29 0 1402733744 13385936 3584592
15:23:45 37333 8192000000 10304556880 10761764864 1387827840 14.486 100 14 28 0 1409035968 17604432 3603696
15:25:26 14134 8192000000 10307733488 10742886400 1360380560 14.241 98 57 113 2 1390623088 17033584 13208944
15:26:43 68192 8192000000 10293762704 10730409984 1345939232 14.111 97 21 42 0 1362556944 11261056 5356656
15:28:57 59428 8192000000 10284274640 10751254528 1365532592 14.287 99 12 22 1 1388239856 19644224 3063040
15:30:57 35679 8192000000 10304812112 10740031488 1379554208 14.413 99 37 71 0 1396810592 9753552 7502832
15:32:33 45780 8192000000 10287781184 10744602624 1368189552 14.311 99 12 24 0 1383537648 12853520 2494576
15:34:27 40388 8192000000 10301282848 10745417728 1376797296 14.388 99 16 32 0 1393431376 13115344 3518736
15:36:08 89608 8192000000 10290923376 10741854208 1370151424 14.328 99 9 18 0 1393325600 20591712 2582464
15:39:03 58545 8192000000 10296319632 10742194176 1384219312 14.454 100 26 52 0 1399208112 9586320 5402480
15:41:07 32079 8192000000 10296944016 10743336960 1392604768 14.529 100 39 78 1 1412041072 10839744 8596560
15:42:49 67482 8192000000 10295647344 10755125248 1382025808 14.435 99 13 26 0 1396659024 11998160 2635056
15:45:37 70968 8192000000 10284972336 10741944320 1360358496 14.241 98 16 32 0 1377211904 12886896 3966512
15:47:54 42520 8192000000 10284703168 10734333952 1356615424 14.207 98 17 34 0 1371854432 11337840 3901168
15:49:51 38096 8192000000 10276224128 10724933632 1343818112 14.092 97 28 55 0 1357714624 8007888 5888624
15:51:33 16267 8192000000 10250176416 10697850880 1323713440 13.910 97 36 72 0 1340905008 8648976 8542592
15:52:51 34330 8192000000 10280961168 10727157760 1336972544 14.030 97 31 62 0 1358395856 14529520 6893792
15:54:31 84799 8192000000 10264208752 10708557824 1276345248 13.480 93 18 36 0 1296660320 16161184 4153888
15:57:01 86175 8192000000 10264518688 10708107264 1294910080 13.649 95 16 32 0 1308551296 10147360 3493856
16:00:05 23760 8192000000 10287104336 10724233216 1363402944 14.268 100 39 78 0 1383861680 12005776 8452960
16:01:31 59639 8192000000 10282906656 10731978752 1378934704 14.407 100 37 73 1 1395599216 7614560 9049952
16:03:34 25372 8192000000 10278813328 10733338624 1372456032 14.349 99 11 22 0 1383972208 8844928 2671248
16:05:00 50203 8192000000 10258330624 10719760384 1331580848 13.981 97 7 14 0 1346055616 13076880 1397888
16:06:51 77336 8192000000 10265874816 10717097984 1328064496 13.950 97 25 49 0 1338760064 5501328 5194240
16:09:14 86898 8192000000 10269822976 10735431680 1359836592 14.236 99 14 28 0 1371229152 8412160 2980400
16:11:51 51767 8192000000 10257101920 10714411008 1309213856 13.779 95 9 19 0 1318343920 7317776 1812288
16:13:47 48473 8192000000 10258096800 10716307456 1349259440 14.141 99 17 35 0 1363360816 10639344 3462032
16:15:37 57532 8192000000 10278837072 10725093376 1360115968 14.238 98 21 43 0 1377208640 12234928 4857744
16:17:35 79017 8192000000 10287766400 10734493696 1354531232 14.188 98 19 38 0 1371202864 11730000 4941632
16:19:58 43504 8192000000 10293945344 10735984640 1368835056 14.317 99 23 46 1 1383365600 9692848 4837696
16:21:45 13734 8192000000 10310538592 10739351552 1382394144 14.438 100 60 112 2 1403727360 8407760 12925456
16:23:03 108444 8192000000 10299491808 10761588736 1356797808 14.209 97 16 32 0 1374243216 14473184 2972224
16:25:51 80172 8192000000 10303397712 10763452416 1396952080 14.568 100 29 58 0 1420399408 17330800 6116528
16:28:49 27221 8192000000 10293578640 10752987136 1393168640 14.534 100 11 22 0 1405877264 10431824 2276800
16:30:17 40661 8192000000 10254825776 10717413376 1331161856 13.978 96 4 8 0 1340257440 8275024 820560
16:31:58 16350 8192000000 10289771584 10744074240 1380272224 14.419 100 25 47 3 1398165728 12913344 4980160
16:33:22 52672 8192000000 10278271168 10731380736 1378883328 14.407 100 21 40 1 1394520336 10396816 5240192
16:35:19 72484 8192000000 10253436384 10711822336 1322681296 13.901 97 8 16 0 1340252144 15621520 1949328
16:37:35 21761 8192000000 10266133152 10706407424 1332239344 13.987 98 32 64 0 1351416768 12345728 6831696
16:39:01 53968 8192000000 10250357104 10702721024 1307966224 13.768 97 21 42 0 1331676000 19040224 4669552
16:40:57 51161 8192000000 10261104400 10699771904 1323858304 13.912 98 16 32 0 1334505536 6850272 3796960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-02-2023 16:43:34 to 09-02-2023 16:43:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-02-2023 16:43:51 to 09-02-2023 16:43:53.

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          255023
MON$OLDEST_ACTIVE               255024
MON$OLDEST_SNAPSHOT             255024
MON$NEXT_TRANSACTION            255024
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                       2010560
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.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 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'

09.02.23 16:43:54. 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-02-09 16:43:56 No severe PSQL-related problems occured
09.02.23 16:43:58. End of report.