Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.10.2021 19:38
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15753836 kB
MemAvailable 30670524 kB
Buffers 0 kB
Cached 15916568 kB
SwapCached 3872 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.8
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 2021-10-10 19:37:49
DTS_END                         2021-10-10 19:37:49.7740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35324
TRN_ID                          236172
ATT_ID                          670
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: 9207.74 22646 1104929
customer order: creation 166.18 383 19942
customer order: refuse 13.88 406 1666
order to supplier: creation 45.22 1374 5426
order to supplier: removal 3.38 110726 406
invoice (draft): creation 45.28 3625 5434
invoice (draft): removal 51.93 874 6232
invoice accept: apply 40.86 20337 4903
invoice accept: cancel 47.12 898 5654
customer reserve: creation 3822.43 197 458691
customer reserve: removal 4683.32 61 561998
realization accept: apply 80.48 193 9658
realization accept: cancel 84.18 102 10102
payment from customer: creation 56.09 123 6731
payment from customer: removal 5.41 90 649
payment to supplier: creation 46.16 135 5539
payment to supplier: removal 3.53 89 424
service: total inventory turnovers 4.87 1239 584
service: total monetary turnovers 6.64 56 797
service: aggregate perf. data 0.74 24334 89
service: refresh index statistics 0.03 287668 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-10-2021 19:38:32 to 10-10-2021 19:38:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 953
TEST_TIME 2 1895
TEST_TIME 3 2861
TEST_TIME 4 3158
TEST_TIME 5 4418
TEST_TIME 6 5208
TEST_TIME 7 5142
TEST_TIME 8 5773
TEST_TIME 9 6166
TEST_TIME 10 6350
TEST_TIME 11 6491
TEST_TIME 12 6602
TEST_TIME 13 6924
TEST_TIME 14 7057
TEST_TIME 15 7282
TEST_TIME 16 7648
TEST_TIME 17 7717
TEST_TIME 18 7997
TEST_TIME 19 7901
TEST_TIME 20 8172
TEST_TIME 21 8383
TEST_TIME 22 9042
TEST_TIME 23 8953
TEST_TIME 24 9437
TEST_TIME 25 9282
TEST_TIME 26 9532
TEST_TIME 27 9695
TEST_TIME 28 9911
TEST_TIME 29 9758
TEST_TIME 30 10018
TEST_TIME 31 10864
TEST_TIME 32 11077
TEST_TIME 33 11226
TEST_TIME 34 11080
TEST_TIME 35 10794
TEST_TIME 36 10513
TEST_TIME 37 10568
TEST_TIME 38 10485
TEST_TIME 39 10283
TEST_TIME 40 10311
TEST_TIME 41 10266
TEST_TIME 42 10193
TEST_TIME 43 10104
TEST_TIME 44 10110
TEST_TIME 45 10030
TEST_TIME 46 9911
TEST_TIME 47 9826
TEST_TIME 48 9714
TEST_TIME 49 9579
TEST_TIME 50 9556
TEST_TIME 51 9699
TEST_TIME 52 9550
TEST_TIME 53 9513
TEST_TIME 54 9495
TEST_TIME 55 9410
TEST_TIME 56 9418
TEST_TIME 57 9361
TEST_TIME 58 9244
TEST_TIME 59 9186
TEST_TIME 60 9065
TEST_TIME 61 9178
TEST_TIME 62 9231
TEST_TIME 63 9187
TEST_TIME 64 9231
TEST_TIME 65 9327
TEST_TIME 66 9327
TEST_TIME 67 9256
TEST_TIME 68 9484
TEST_TIME 69 9421
TEST_TIME 70 9501
TEST_TIME 71 9575
TEST_TIME 72 9521
TEST_TIME 73 9599
TEST_TIME 74 9614
TEST_TIME 75 9665
TEST_TIME 76 9559
TEST_TIME 77 9483
TEST_TIME 78 9439
TEST_TIME 79 9398
TEST_TIME 80 9442
TEST_TIME 81 9373
TEST_TIME 82 9386
TEST_TIME 83 9349
TEST_TIME 84 9357
TEST_TIME 85 9319
TEST_TIME 86 9218
TEST_TIME 87 9202
TEST_TIME 88 9202
TEST_TIME 89 9183
TEST_TIME 90 9165
TEST_TIME 91 9178
TEST_TIME 92 9205
TEST_TIME 93 9178
TEST_TIME 94 9202
TEST_TIME 95 9252
TEST_TIME 96 9276
TEST_TIME 97 9303
TEST_TIME 98 9256
TEST_TIME 99 9318
TEST_TIME 100 9351
TEST_TIME 101 9344
TEST_TIME 102 9347
TEST_TIME 103 9350
TEST_TIME 104 9301
TEST_TIME 105 9248
TEST_TIME 106 9219
TEST_TIME 107 9195
TEST_TIME 108 9157
TEST_TIME 109 9164
TEST_TIME 110 9162
TEST_TIME 111 9125
TEST_TIME 112 9122
TEST_TIME 113 9117
TEST_TIME 114 9106
TEST_TIME 115 9112
TEST_TIME 116 9136
TEST_TIME 117 9149
TEST_TIME 118 9221
TEST_TIME 119 9207
TEST_TIME 120 9208

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-10-2021 19:38:36 to 10-10-2021 19:38:39.

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 1529980 1529980 0 0.00 0 18490 6 0 0
doc_list_biud 1529931 1529931 0 0.00 0 59749 29 0 0
sp_add_invoice_to_stock 5024 4903 121 2.40 29 207002 20337 0 121
sp_cancel_adding_invoice 5654 5654 0 0.00 5 62879 899 0 0
sp_cancel_client_order 1666 1666 0 0.00 4 25958 407 0 0
sp_cancel_customer_reserve 561998 561998 0 0.00 1 59752 62 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 9951 90 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 3225 90 0 0
sp_cancel_supplier_invoice 6238 6232 6 0.09 2 18341 875 0 6
sp_cancel_supplier_order 406 406 0 0.00 10 1045807 110727 0 0
sp_cancel_write_off 10102 10102 0 0.00 1 11312 103 0 0
sp_client_order 19942 19942 0 0.00 3 72995 384 0 0
sp_customer_reserve 458691 458691 0 0.00 0 71779 197 0 0
sp_fill_shopping_cart 30801 30801 0 0.00 1 17849 48 0 0
sp_fill_shopping_cart_clo_res 458690 458690 0 0.00 0 20025 15 0 0
sp_get_clo_for_invoice 4902 4902 0 0.00 0 33782 390 0 0
sp_kill_cost_storno 16829 16829 0 0.00 0 7416 58 0 0
sp_kill_qstorno_ret_qs2qd 1140736 1140736 0 0.00 0 59745 33 0 0
sp_kill_qty_storno 600584 600584 0 0.00 0 59749 73 0 0
sp_lock_selected_doc 20079 20079 0 0.00 0 11971 6 0 0
sp_make_cost_storno 26830 26830 0 0.00 0 18479 60 0 0
sp_multiply_rows_for_pdistr 26781 26781 0 0.00 0 8286 33 0 0
sp_multiply_rows_for_qdistr 19942 19942 0 0.00 0 65409 186 0 0
sp_pay_from_customer 6731 6731 0 0.00 4 37690 123 0 0
sp_pay_to_supplier 5539 5539 0 0.00 5 14615 136 0 0
sp_payment_common 12255 12255 0 0.00 1 15638 28 0 0
sp_qd_handle_on_cancel_clo 1666 1666 0 0.00 0 25945 375 0 0
sp_qd_handle_on_invoice_upd_sts 10537 10537 0 0.00 0 57339 403 0 0
sp_qd_handle_on_reserve_upd_sts 9643 9643 0 0.00 0 24702 65 0 0
sp_reserve_write_off 9658 9658 0 0.00 7 25385 193 0 0
sp_supplier_invoice 5434 5434 0 0.00 6 75373 3626 0 0
sp_supplier_order 5426 5426 0 0.00 5 38009 1375 0 0
srv_aggregate_perf_data 89 89 0 0.00 2987 261062 24335 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1583 133131 42673 0 0
srv_find_qd_qs_mism 1058594 1058594 0 0.00 0 17627 5 0 0
srv_make_invnt_saldo 584 584 0 0.00 24 116574 1240 0 0
srv_make_money_saldo 797 797 0 0.00 1 13109 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 180678 512721 287669 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4903 4903 0 0.00 0 7374 192 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 5849 139 0 0
t$perf-norm:sp_cancel_client_order 1666 1666 0 0.00 0 4075 19 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 1711 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 416 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 1660 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 3021 24 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 35102 1907 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 5944 18 0 0
t$perf-norm:sp_client_order 19942 19942 0 0.00 0 9038 21 0 0
t$perf-norm:sp_customer_reserve 911 911 0 0.00 0 4274 18 0 0
t$perf-norm:sp_pay_from_customer 6731 6731 0 0.00 0 5935 14 0 0
t$perf-norm:sp_pay_to_supplier 5539 5539 0 0.00 0 17194 12 0 0
t$perf-norm:sp_reserve_write_off 9658 9658 0 0.00 0 18276 17 0 0
t$perf-norm:sp_supplier_invoice 5434 5434 0 0.00 0 4148 18 0 0
t$perf-norm:sp_supplier_order 5426 5426 0 0.00 0 10825 23 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 192 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 59 1 0 0
t$perf-norm:srv_make_invnt_saldo 584 584 0 0.00 0 1158 30 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 1078 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10008 9766 242 2.41 0 6532 19 0 242
v_all_customers 36092 36092 0 0.00 0 57 1 0 0
v_all_suppliers 29486 29486 0 0.00 0 57 1 0 0
v_all_wares 39884 39884 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 952 8 0 0
v_cancel_client_order 3332 3332 0 0.00 0 2864 6 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 9396 13 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 783 5 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 1304 7 0 0
v_cancel_supplier_invoice 794 782 12 1.51 0 9755 28 0 12
v_cancel_supplier_order 812 812 0 0.00 0 1472 9 0 0
v_cancel_write_off 1968 1968 0 0.00 0 2391 17 0 0
v_max_id_clo_ord 5425 5425 0 0.00 0 1910 2 0 0
v_max_id_clo_res 911 911 0 0.00 0 5987 25 0 0
v_max_id_ord_sup 5433 5433 0 0.00 0 8630 9 0 0
v_max_non_paid_invoice 1643 1643 0 0.00 0 210 3 0 0
v_max_non_paid_realizn 3636 3636 0 0.00 0 3680 5 0 0
v_min_id_clo_ord 5425 5425 0 0.00 0 13669 22 0 0
v_min_id_clo_res 911 911 0 0.00 0 1561 6 0 0
v_min_id_ord_sup 5433 5433 0 0.00 0 8706 9 0 0
v_min_non_paid_invoice 5528 5528 0 0.00 0 5639 16 0 0
v_min_non_paid_realizn 6725 6725 0 0.00 0 15632 24 0 0
v_our_firm 9970 9970 0 0.00 0 13 1 0 0
v_reserve_write_off 19284 19284 0 0.00 0 5136 4 0 0
x_lock_depdocs_on_canc_invoice 11495 11495 0 0.00 0 39740 140 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 10480 430 0 0
x_make_qstorno_1000_1200 5426 5426 0 0.00 1 35456 1264 0 0
x_make_qstorno_1200_2000 5434 5434 0 0.00 3 74828 3469 0 0
x_make_qstorno_2100_3300 458691 458691 0 0.00 0 71778 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-10-2021 19:38:40 to 10-10-2021 19:38:44.

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
17:07:53 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:54 32886 8192000000 9932032688 10245902336 999893520 10.877 100 91 182 0 1029728032 6623248 23211264
17:10:37 27421 8192000000 9962846240 10292326400 1038579216 11.251 100 87 174 1 1070362736 9730016 22053504
17:12:10 19395 8192000000 10010480080 10354180096 1092883376 11.770 100 82 164 0 1124065408 11105056 20076976
17:13:34 10711 8192000000 10048616080 10403160064 1134651120 12.165 100 73 146 1 1163818672 11810128 17357424
17:14:48 9682 8192000000 10089899952 10450915328 1178278432 12.574 100 86 172 0 1211280992 13532144 19470416
17:15:59 7652 8192000000 10128446800 10502795264 1218730768 12.950 100 79 158 0 1248987264 12115040 18141456
17:17:09 3658 8192000000 10165725200 10538553344 1254311200 13.278 100 86 173 0 1285711456 12476736 18923520
17:18:14 1681 8192000000 10197897712 10580692992 1287943952 13.585 100 99 200 2 1322906432 12735088 22227392
17:19:17 2062 8192000000 10225827680 10608549888 1305038400 13.741 100 95 190 0 1340134496 13546560 21549536
17:20:19 9785 8192000000 10190756256 10603118592 1302666336 13.719 100 43 87 1 1319132800 6902704 9563760
17:21:30 4258 8192000000 10195413632 10610765824 1308653136 13.774 100 26 53 1 1321703104 6870880 6179088
17:22:35 6348 8192000000 10206058624 10620735488 1316196384 13.842 100 39 78 1 1331480992 6120208 9164400
17:23:44 62181 8192000000 10214974592 10637832192 1324747760 13.920 100 33 67 0 1341601072 9227040 7626272
17:25:49 7400 8192000000 10220665344 10644271104 1331343568 13.979 100 19 38 1 1341413440 5733648 4336224
17:26:58 10138 8192000000 10210171120 10630385664 1312188880 13.806 99 20 38 2 1322304128 6472816 3642432
17:28:08 59035 8192000000 10171665424 10581209088 1219428000 12.956 93 28 55 0 1230714432 4794416 6492016
17:30:08 5630 8192000000 10138378000 10537619456 1243602352 13.179 99 26 52 0 1255231824 6391968 5237504
17:31:17 7022 8192000000 10128659248 10524131328 1196216528 12.741 96 22 44 0 1207372608 5922992 5233088
17:32:27 15654 8192000000 10130789072 10523901952 1199078816 12.768 96 28 56 0 1215548528 10195424 6274288
17:33:44 31706 8192000000 10159582880 10569547776 1251086032 13.248 98 28 56 1 1262925472 4772720 7066720
17:35:17 9814 8192000000 10188164288 10598117376 1293170176 13.633 100 22 44 0 1304254608 6004624 5079808
17:36:29 11358 8192000000 10194072944 10609754112 1303529984 13.727 100 15 31 0 1311681248 5065552 3085712
17:37:42 52797 8192000000 10236911776 10643062784 1328993680 13.958 100 47 94 0 1346071312 5474864 11602768
17:39:39 34894 8192000000 10242495120 10658672640 1337927248 14.039 100 43 86 3 1357308880 9320896 10060736
17:41:20 34848 8192000000 10273587904 10693054464 1356292496 14.204 100 37 72 1 1382542512 17839184 8410832
17:43:03 7463 8192000000 10303460848 10714648576 1376689856 14.387 100 80 157 1 1413189232 19136144 17363232
17:44:14 10626 8192000000 10307875232 10719010816 1381163568 14.427 100 62 122 0 1413492992 18829408 13500016
17:45:29 6817 8192000000 10308689072 10729598976 1386850720 14.478 100 50 98 1 1418040400 19077472 12112208
17:46:39 9420 8192000000 10315878064 10739253248 1395605392 14.556 100 61 121 1 1427856512 19008256 13242864
17:47:53 4720 8192000000 10336800912 10760785920 1408520144 14.671 100 59 118 0 1447644704 25189024 13935536
17:49:02 6061 8192000000 10320846064 10741235712 1389268928 14.499 100 62 124 0 1428095984 23863136 14963920
17:50:12 1851 8192000000 10334327520 10742046720 1379585968 14.413 99 83 164 1 1428229936 29562768 19081200
17:51:19 9046 8192000000 10317294480 10730143744 1371930960 14.344 99 62 124 1 1416206896 28967680 15308256
17:52:40 2088 8192000000 10332378464 10745266176 1383911840 14.452 100 70 139 2 1435426560 34653056 16861664
17:53:47 7575 8192000000 10340858096 10753495040 1389071136 14.498 100 74 144 0 1444363840 37082976 18209728
17:55:00 6179 8192000000 10339365632 10743959552 1372504192 14.349 100 88 176 0 1437221136 42358016 22358928
17:56:13 4875 8192000000 10345901184 10754379776 1387242832 14.481 100 80 160 2 1452387312 45065088 20079392
17:57:23 5757 8192000000 10343100144 10750226432 1371258992 14.338 100 74 148 5 1443252688 53588352 18405344
17:58:33 2326 8192000000 10347793248 10748162048 1370829920 14.334 100 87 173 1 1443769696 51524128 21415648
17:59:44 3642 8192000000 10355188112 10766913536 1388168448 14.490 100 83 166 1 1456619296 47758688 20692160
18:00:52 10185 8192000000 10360709760 10786099200 1398504208 14.582 100 42 82 0 1457087632 46712864 11870560
18:02:06 13591 8192000000 10349896288 10783322112 1399611376 14.592 100 39 78 2 1454040192 43622960 10805856
18:03:24 1582 8192000000 10372454704 10787831808 1401586896 14.609 100 79 158 3 1470974336 48984496 20402944
18:04:29 23571 8192000000 10339141424 10777358336 1387707184 14.485 99 25 49 0 1442039008 47068032 7263792
18:06:08 40511 8192000000 10331385664 10764554240 1378877680 14.407 99 36 72 2 1429787360 40547488 10362192
18:08:03 41210 8192000000 10308549072 10761142272 1379874192 14.415 100 16 32 1 1432417440 47887904 4655344
18:09:45 50622 8192000000 10298360880 10750271488 1373251024 14.356 99 23 46 0 1399722176 21227744 5243408
18:11:38 27570 8192000000 10303789552 10750042112 1382712720 14.441 99 24 48 0 1398060336 9851616 5496000
18:13:07 50950 8192000000 10319677680 10755031040 1385492496 14.466 99 57 113 3 1411132208 12238608 13401104
18:15:05 32107 8192000000 10281828192 10736959488 1359718656 14.235 98 18 36 1 1380750800 16344320 4687824
18:16:37 77896 8192000000 10291910928 10753916928 1383106704 14.444 100 14 28 0 1400398912 13844096 3448112
18:19:22 95782 8192000000 10312788832 10771722240 1394060816 14.542 100 22 44 0 1412851088 13859200 4931072
18:22:03 42160 8192000000 10305588656 10760085504 1402160512 14.614 100 18 35 3 1413781072 7577568 4042992
18:23:47 67444 8192000000 10304939760 10763870208 1381159232 14.427 98 15 30 0 1392001808 7376768 3465808
18:25:56 130749 8192000000 10291272352 10754154496 1350433200 14.151 96 12 24 1 1369995488 16689984 2872304
18:29:39 7761 8192000000 10287737136 10754134016 1386571504 14.475 100 19 37 1 1403155072 12409872 4173696
18:30:48 21717 8192000000 10288786720 10736381952 1374551856 14.368 99 20 40 1 1387767568 8670640 4545072
18:32:11 37921 8192000000 10272075888 10728206336 1365360688 14.285 98 23 45 1 1378058384 7739200 4958496
18:33:55 41032 8192000000 10283712544 10745675776 1385419824 14.465 100 11 22 2 1395650368 7862432 2368112
18:35:41 76655 8192000000 10297594112 10760278016 1367757216 14.307 98 19 38 0 1384942224 12264208 4920800
18:38:07 45430 8192000000 10291708240 10768076800 1387200768 14.481 99 18 36 2 1404626208 13348176 4077264
18:39:59 33386 8192000000 10295814336 10746093568 1342751328 14.082 97 26 51 0 1354553888 5872928 5929632
18:41:37 89862 8192000000 10282877232 10754924544 1359079984 14.229 98 18 35 3 1378451680 15302384 4069312
18:44:22 108493 8192000000 10277759280 10736046080 1304918000 13.740 94 13 26 0 1328107888 20287584 2902304
18:47:17 42933 8192000000 10261960544 10716925952 1315512640 13.836 96 27 54 0 1340480160 18941712 6025808
18:49:01 46769 8192000000 10263818976 10699456512 1287717472 13.583 95 30 59 0 1305641040 11552640 6370928
18:50:50 52007 8192000000 10265205008 10701885440 1310465424 13.790 97 17 34 1 1326551760 12489312 3597024
18:52:49 133127 8192000000 10263756528 10711048192 1337481072 14.035 98 22 44 0 1351890496 9592512 4816912
18:56:02 45957 8192000000 10279258480 10721652736 1350542832 14.152 99 36 72 2 1375120544 16834432 7743280
18:57:50 43765 8192000000 10265718560 10723856384 1353773248 14.181 99 15 30 1 1372579712 15276048 3530416
18:59:49 34434 8192000000 10250245216 10702233600 1340221104 14.059 99 16 31 2 1353715296 9879552 3614640
19:01:28 65264 8192000000 10247983712 10703208448 1334483440 14.008 98 12 24 1 1349121536 11417424 3220672
19:03:40 61844 8192000000 10268154000 10718224384 1349283152 14.141 99 22 44 1 1365955456 11654064 5018240
19:05:44 47928 8192000000 10243575904 10705604608 1310080016 13.787 96 4 8 1 1318692800 7979920 632864
19:07:32 68190 8192000000 10251653888 10706575360 1271479536 13.435 93 20 40 0 1284076288 8317776 4278976
19:09:59 35514 8192000000 10301633920 10739707904 1379288096 14.410 100 34 68 0 1396761440 9862208 7611136
19:11:38 73416 8192000000 10294032432 10751414272 1367082336 14.301 99 17 33 2 1380120992 9373216 3665440
19:13:56 61364 8192000000 10298636704 10747383808 1376628192 14.386 99 13 25 4 1391296336 11762208 2905936
19:15:58 56227 8192000000 10307219136 10762604544 1389323312 14.500 100 31 62 1 1413800576 17266256 7211008
19:18:25 78338 8192000000 10319888704 10766893056 1385694224 14.467 99 16 32 1 1399972832 11006080 3272528
19:20:43 78495 8192000000 10285727552 10743156736 1383037248 14.444 99 16 32 1 1397891520 11634144 3220128
19:23:36 53802 8192000000 10285580896 10749231104 1374806352 14.370 99 13 26 0 1390779984 13094752 2878880
19:25:30 55939 8192000000 10302156528 10761568256 1396870800 14.567 100 8 16 0 1412047696 13318336 1858560
19:27:40 54014 8192000000 10256438416 10713092096 1337607040 14.036 97 15 30 1 1347342960 6223296 3512624
19:29:35 45178 8192000000 10269141184 10726715392 1345526720 14.107 98 12 22 2 1357888128 9843888 2517520
19:31:33 52359 8192000000 10280871296 10733625344 1352841280 14.173 98 25 48 2 1370688400 12338656 5508464
19:33:36 59431 8192000000 10275675136 10727202816 1346223504 14.113 98 28 56 0 1372053136 19091184 6738448
19:35:57 90846 8192000000 10261463760 10715193344 1312196864 13.806 95 22 45 3 1325553632 8981360 4375408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-10-2021 19:38:46 to 10-10-2021 19:39:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-10-2021 19:39:02 to 10-10-2021 19:39:04.

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          236510
MON$OLDEST_ACTIVE               236511
MON$OLDEST_SNAPSHOT             236511
MON$NEXT_TRANSACTION            236511
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                       2052896
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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'

10.10.21 19:39:05. 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
2021-10-10 19:39:07 No severe PSQL-related problems occured
10.10.21 19:39:09. End of report.