Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.09.2021 22: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 16039580 kB
MemAvailable 30737232 kB
Buffers 0 kB
Cached 15636456 kB
SwapCached 2964 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-09-28 22:38:04
DTS_END                         2021-09-28 22:37:53.8770
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/47412
TRN_ID                          229694
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: 9590.98 20832 1150917
customer order: creation 163.18 383 19582
customer order: refuse 13.88 360 1665
order to supplier: creation 43.47 1327 5216
order to supplier: removal 3.45 111083 414
invoice (draft): creation 44.60 3483 5352
invoice (draft): removal 57.53 803 6903
invoice accept: apply 39.73 18894 4768
invoice accept: cancel 51.67 729 6200
customer reserve: creation 3785.28 182 454233
customer reserve: removal 5100.46 57 612055
realization accept: apply 79.18 200 9502
realization accept: cancel 86.59 110 10391
payment from customer: creation 56.14 128 6737
payment from customer: removal 5.38 100 645
payment to supplier: creation 46.02 151 5522
payment to supplier: removal 3.43 84 411
service: total inventory turnovers 4.45 1160 534
service: total monetary turnovers 5.92 54 710
service: aggregate perf. data 0.61 32275 73
service: refresh index statistics 0.03 245085 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-09-2021 22:38:36 to 28-09-2021 22:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3691
TEST_TIME 2 3621
TEST_TIME 3 4017
TEST_TIME 4 4218
TEST_TIME 5 5684
TEST_TIME 6 5839
TEST_TIME 7 5864
TEST_TIME 8 5980
TEST_TIME 9 6360
TEST_TIME 10 6582
TEST_TIME 11 6590
TEST_TIME 12 6491
TEST_TIME 13 6832
TEST_TIME 14 6789
TEST_TIME 15 6862
TEST_TIME 16 7173
TEST_TIME 17 7128
TEST_TIME 18 7165
TEST_TIME 19 7453
TEST_TIME 20 7715
TEST_TIME 21 8133
TEST_TIME 22 8778
TEST_TIME 23 9025
TEST_TIME 24 8958
TEST_TIME 25 9565
TEST_TIME 26 9921
TEST_TIME 27 9977
TEST_TIME 28 10781
TEST_TIME 29 10495
TEST_TIME 30 10234
TEST_TIME 31 10328
TEST_TIME 32 10269
TEST_TIME 33 10187
TEST_TIME 34 10241
TEST_TIME 35 10065
TEST_TIME 36 9893
TEST_TIME 37 9867
TEST_TIME 38 9755
TEST_TIME 39 9816
TEST_TIME 40 9975
TEST_TIME 41 9855
TEST_TIME 42 9761
TEST_TIME 43 9871
TEST_TIME 44 9770
TEST_TIME 45 9701
TEST_TIME 46 9525
TEST_TIME 47 9780
TEST_TIME 48 9704
TEST_TIME 49 9719
TEST_TIME 50 9626
TEST_TIME 51 9565
TEST_TIME 52 9483
TEST_TIME 53 9472
TEST_TIME 54 9372
TEST_TIME 55 9451
TEST_TIME 56 9358
TEST_TIME 57 9377
TEST_TIME 58 9315
TEST_TIME 59 9340
TEST_TIME 60 9368
TEST_TIME 61 9409
TEST_TIME 62 9459
TEST_TIME 63 9530
TEST_TIME 64 9470
TEST_TIME 65 9537
TEST_TIME 66 9500
TEST_TIME 67 9486
TEST_TIME 68 9562
TEST_TIME 69 9578
TEST_TIME 70 9736
TEST_TIME 71 9697
TEST_TIME 72 9734
TEST_TIME 73 9717
TEST_TIME 74 9611
TEST_TIME 75 9561
TEST_TIME 76 9552
TEST_TIME 77 9502
TEST_TIME 78 9499
TEST_TIME 79 9465
TEST_TIME 80 9420
TEST_TIME 81 9371
TEST_TIME 82 9338
TEST_TIME 83 9300
TEST_TIME 84 9361
TEST_TIME 85 9339
TEST_TIME 86 9345
TEST_TIME 87 9425
TEST_TIME 88 9412
TEST_TIME 89 9411
TEST_TIME 90 9429
TEST_TIME 91 9432
TEST_TIME 92 9485
TEST_TIME 93 9474
TEST_TIME 94 9517
TEST_TIME 95 9564
TEST_TIME 96 9565
TEST_TIME 97 9552
TEST_TIME 98 9580
TEST_TIME 99 9563
TEST_TIME 100 9548
TEST_TIME 101 9477
TEST_TIME 102 9507
TEST_TIME 103 9444
TEST_TIME 104 9466
TEST_TIME 105 9421
TEST_TIME 106 9424
TEST_TIME 107 9392
TEST_TIME 108 9371
TEST_TIME 109 9377
TEST_TIME 110 9367
TEST_TIME 111 9340
TEST_TIME 112 9304
TEST_TIME 113 9274
TEST_TIME 114 9372
TEST_TIME 115 9435
TEST_TIME 116 9509
TEST_TIME 117 9548
TEST_TIME 118 9564
TEST_TIME 119 9601
TEST_TIME 120 9594

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-09-2021 22:38:39 to 28-09-2021 22:38:43.

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 1572268 1572268 0 0.00 0 18084 6 0 0
doc_list_biud 1572268 1572268 0 0.00 0 86844 28 0 0
sp_add_invoice_to_stock 4848 4768 80 1.65 30 162644 18895 0 80
sp_cancel_adding_invoice 6200 6200 0 0.00 4 104725 729 0 0
sp_cancel_client_order 1665 1665 0 0.00 5 14014 360 0 0
sp_cancel_customer_reserve 612055 612055 0 0.00 1 26671 58 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 5325 100 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 3398 84 0 0
sp_cancel_supplier_invoice 6910 6903 7 0.10 2 86855 804 0 7
sp_cancel_supplier_order 414 414 0 0.00 11 848569 111084 0 0
sp_cancel_write_off 10391 10391 0 0.00 1 10523 110 0 0
sp_client_order 19582 19582 0 0.00 3 26542 384 0 0
sp_customer_reserve 454233 454233 0 0.00 0 48527 182 0 0
sp_fill_shopping_cart 30141 30141 0 0.00 1 20402 34 0 0
sp_fill_shopping_cart_clo_res 454223 454223 0 0.00 0 12369 14 0 0
sp_get_clo_for_invoice 4758 4758 0 0.00 0 14104 288 0 0
sp_kill_cost_storno 17647 17647 0 0.00 0 18074 58 0 0
sp_kill_qstorno_ret_qs2qd 1241818 1241818 0 0.00 0 86836 31 0 0
sp_kill_qty_storno 651880 651880 0 0.00 0 86841 68 0 0
sp_lock_selected_doc 19785 19785 0 0.00 0 7750 5 0 0
sp_make_cost_storno 26511 26511 0 0.00 0 14342 55 0 0
sp_multiply_rows_for_pdistr 26511 26511 0 0.00 0 9812 30 0 0
sp_multiply_rows_for_qdistr 19582 19582 0 0.00 0 16893 173 0 0
sp_pay_from_customer 6737 6737 0 0.00 4 18849 129 0 0
sp_pay_to_supplier 5522 5522 0 0.00 5 26218 152 0 0
sp_payment_common 12259 12259 0 0.00 1 12842 34 0 0
sp_qd_handle_on_cancel_clo 1665 1665 0 0.00 0 14006 326 0 0
sp_qd_handle_on_invoice_upd_sts 10950 10950 0 0.00 0 25114 334 0 0
sp_qd_handle_on_reserve_upd_sts 9502 9502 0 0.00 0 16079 71 0 0
sp_reserve_write_off 9502 9502 0 0.00 6 22877 201 0 0
sp_supplier_invoice 5352 5352 0 0.00 7 103090 3484 0 0
sp_supplier_order 5216 5216 0 0.00 6 40640 1327 0 0
srv_aggregate_perf_data 73 73 0 0.00 2726 552224 32275 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2121 122302 45834 0 0
srv_find_qd_qs_mism 1105425 1105425 0 0.00 0 14981 5 0 0
srv_make_invnt_saldo 534 534 0 0.00 28 83958 1161 0 0
srv_make_money_saldo 710 710 0 0.00 0 12801 54 0 0
srv_recalc_idx_stat 4 4 0 0.00 186400 288001 245085 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4768 4768 0 0.00 0 13090 161 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 0 2672 87 0 0
t$perf-norm:sp_cancel_client_order 1665 1665 0 0.00 0 2437 13 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 4111 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 5575 26 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 830 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 765 15 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 22702 1977 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 2053 8 0 0
t$perf-norm:sp_client_order 19582 19582 0 0.00 0 10424 18 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 4735 14 0 0
t$perf-norm:sp_pay_from_customer 6737 6737 0 0.00 0 9224 14 0 0
t$perf-norm:sp_pay_to_supplier 5522 5522 0 0.00 0 9210 19 0 0
t$perf-norm:sp_reserve_write_off 9502 9502 0 0.00 0 16109 18 0 0
t$perf-norm:sp_supplier_invoice 5352 5352 0 0.00 0 14651 20 0 0
t$perf-norm:sp_supplier_order 5216 5216 0 0.00 0 8448 19 0 0
t$perf-norm:srv_aggregate_perf_data 73 73 0 0.00 0 236 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 12 1 0 0
t$perf-norm:srv_make_invnt_saldo 534 534 0 0.00 0 610 16 0 0
t$perf-norm:srv_make_money_saldo 710 710 0 0.00 0 5402 32 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2 2 0 0
v_add_invoice_to_stock 9658 9498 160 1.65 0 5052 16 0 160
v_all_customers 35814 35814 0 0.00 0 72 1 0 0
v_all_suppliers 29324 29324 0 0.00 0 23 1 0 0
v_all_wares 39164 39164 0 0.00 0 73 1 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 4255 11 0 0
v_cancel_client_order 3330 3330 0 0.00 0 9298 9 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 4431 12 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 242 3 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 3379 13 0 0
v_cancel_supplier_invoice 782 768 14 1.79 0 2279 16 0 14
v_cancel_supplier_order 828 828 0 0.00 0 4067 14 0 0
v_cancel_write_off 1962 1962 0 0.00 0 4512 18 0 0
v_max_id_clo_ord 5216 5216 0 0.00 0 2372 2 0 0
v_max_id_clo_res 951 951 0 0.00 0 2967 18 0 0
v_max_id_ord_sup 5343 5343 0 0.00 0 2902 3 0 0
v_max_non_paid_invoice 1419 1419 0 0.00 0 799 3 0 0
v_max_non_paid_realizn 3544 3544 0 0.00 0 6818 6 0 0
v_min_id_clo_ord 5216 5216 0 0.00 0 8540 10 0 0
v_min_id_clo_res 951 951 0 0.00 0 3058 16 0 0
v_min_id_ord_sup 5343 5343 0 0.00 0 8328 13 0 0
v_min_non_paid_invoice 5522 5522 0 0.00 0 7081 24 0 0
v_min_non_paid_realizn 6737 6737 0 0.00 0 12839 20 0 0
v_our_firm 9736 9736 0 0.00 0 71 1 0 0
v_reserve_write_off 19004 19004 0 0.00 0 22824 12 0 0
x_lock_depdocs_on_canc_invoice 12719 12719 0 0.00 0 19410 118 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 7315 377 0 0
x_make_qstorno_1000_1200 5216 5216 0 0.00 2 40626 1257 0 0
x_make_qstorno_1200_2000 5352 5352 0 0.00 3 100766 3363 0 0
x_make_qstorno_2100_3300 454233 454233 0 0.00 0 48525 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-09-2021 22:38:44 to 28-09-2021 22:38:48.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:57 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:57 26823 8192000000 10373827376 10692337664 1418885536 14.763 100 93 186 0 1455893360 6113472 30894352
20:10:31 18816 8192000000 10454345488 10786025472 1506401904 15.532 100 95 190 0 1545583328 8690176 30491248
20:12:07 24740 8192000000 10506783232 10853564416 1551490256 15.923 100 85 170 0 1587936416 10094944 26351216
20:13:36 12948 8192000000 10580129712 10939752448 1632144640 16.613 100 75 150 0 1665232512 10795936 22291936
20:14:58 9668 8192000000 10660852832 11032678400 1723172736 17.379 100 84 168 1 1759688992 12698608 23817648
20:16:13 3801 8192000000 10716134128 11095625728 1778384144 17.836 100 79 158 3 1813275424 13065408 21825872
20:17:19 2973 8192000000 10752713968 11145617408 1826950080 18.234 100 75 151 0 1861431520 13432448 21048992
20:18:26 2840 8192000000 10802936752 11187281920 1860397424 18.507 100 91 180 1 1897763472 12765792 24600256
20:19:31 1314 8192000000 10829086464 11220230144 1881693952 18.679 100 90 181 4 1921826272 14289200 25843120
20:20:34 1459 8192000000 10833677872 11228422144 1889940272 18.745 100 89 177 1 1927081888 10959216 26182400
20:21:42 4012 8192000000 10804813712 11230523392 1890994496 18.754 100 38 74 1 1910776800 9387504 10394800
20:22:47 3031 8192000000 10815364048 11235323904 1899798768 18.825 100 46 92 2 1920503056 7649968 13054320
20:23:50 25070 8192000000 10801190448 11238572032 1899822912 18.825 100 23 46 1 1911612528 5612672 6176944
20:25:16 5213 8192000000 10818647328 11250618368 1907951136 18.890 100 29 58 1 1921907824 5467344 8489344
20:26:23 8480 8192000000 10811675648 11249790976 1889319744 18.740 99 20 37 1 1900495728 4857056 6318928
20:27:35 59466 8192000000 10804277856 11241746432 1865297696 18.546 98 24 46 0 1878893600 7053504 6542400
20:29:37 7324 8192000000 10743378800 11162279936 1806303888 18.066 97 29 58 1 1819667520 5341632 8022000
20:30:47 12000 8192000000 10702523936 11120992256 1716625376 17.324 95 22 44 2 1727995712 4815408 6554928
20:31:59 81333 8192000000 10692070928 11106119680 1646612032 16.736 91 28 56 0 1660205808 4915216 8678560
20:34:23 9025 8192000000 10748598576 11177398272 1837503072 18.320 100 19 38 1 1848563776 5520112 5540592
20:35:35 5444 8192000000 10789243968 11209486336 1864112848 18.537 100 38 77 0 1885725344 10193168 11419328
20:36:42 35354 8192000000 10782929552 11220951040 1877944576 18.649 100 35 70 2 1893533168 5539968 10048624
20:38:48 16071 8192000000 10856957264 11274620928 1918155840 18.972 100 51 102 4 1940583280 8374176 14053264
20:40:10 39509 8192000000 10862400272 11288842240 1932803088 19.089 100 39 78 0 1953605648 10135920 10666640
20:41:56 24662 8192000000 10881112304 11320885248 1950889408 19.234 100 41 83 0 1978525552 15857264 11778880
20:43:28 20936 8192000000 10900791536 11326763008 1960883168 19.313 100 37 73 3 1984328976 12800400 10645408
20:44:53 15559 8192000000 10911567776 11343560704 1959212672 19.300 99 61 122 0 1994729952 17632576 17884704
20:46:19 7621 8192000000 10936046944 11371245568 1993131760 19.569 100 49 97 2 2025472304 18414832 13925712
20:47:30 3718 8192000000 10950938720 11380371456 2003195664 19.648 100 62 124 0 2039162032 16825728 19140640
20:48:41 8102 8192000000 10960505056 11386707968 2001552208 19.635 100 72 144 3 2048774704 26244656 20977840
20:49:58 2268 8192000000 10991145840 11413127168 2019533136 19.776 100 66 132 0 2067969552 28644400 19792016
20:51:05 15469 8192000000 10966173824 11400343552 1996273632 19.593 99 72 144 0 2052064624 33108800 22682192
20:52:36 7983 8192000000 10978679824 11416457216 2016488144 19.753 100 56 112 0 2069094960 34534800 18072016
20:53:53 2121 8192000000 10976595824 11389771776 1993006720 19.568 99 77 153 2 2055630128 38715904 23907504
20:55:00 4503 8192000000 10969943152 11404722176 2002820736 19.645 100 57 114 1 2056645936 35148112 18677088
20:56:11 2979 8192000000 10980650208 11406069760 2000193424 19.624 100 74 148 0 2064949488 40829568 23926496
20:57:30 10134 8192000000 10948275376 11392151552 1947632016 19.208 98 51 101 1 2010836976 46293264 16911696
20:58:49 8981 8192000000 10942259312 11381653504 1965575936 19.350 100 57 114 0 2035487792 51251872 18659984
21:00:00 13786 8192000000 10934652768 11384221696 1974594128 19.422 100 37 74 3 2027678832 41389696 11695008
21:01:17 20922 8192000000 10912601824 11359211520 1923022112 19.011 98 30 58 1 1976965648 43633648 10309888
21:02:43 27938 8192000000 10900951616 11361574912 1927045408 19.043 99 26 52 3 1973671728 37648304 8978016
21:04:15 37633 8192000000 10902335392 11346128896 1872467392 18.604 96 24 48 1 1919439440 39636944 7335104
21:05:56 57611 8192000000 10832466512 11288641536 1883529568 18.694 98 12 24 1 1896187744 9476160 3182016
21:08:38 116445 8192000000 10874237040 11342954496 1895809936 18.793 97 10 20 0 1912475840 14422288 2243616
21:11:39 63485 8192000000 10873315088 11326615552 1903431840 18.854 97 16 32 0 1916928736 9031648 4465248
21:13:46 15360 8192000000 10878599008 11318960128 1924037776 19.019 99 49 98 0 1949918528 11897264 13983488
21:15:03 36239 8192000000 10886374768 11332063232 1928267968 19.053 99 37 75 2 1953998240 14710320 11019952
21:16:42 63275 8192000000 10900916160 11382988800 1967561392 19.366 100 17 34 0 1998887712 25786976 5539344
21:19:52 28104 8192000000 10887633568 11369627648 1958634464 19.295 100 11 22 1 1981121344 18763264 3723616
21:21:21 65951 8192000000 10895052256 11368534016 1954649808 19.263 99 16 32 1 1976043328 16479968 4913552
21:23:30 59474 8192000000 10864767200 11352981504 1910714992 18.912 97 21 42 1 1939327504 21399072 7213440
21:25:37 41449 8192000000 10871019792 11333918720 1923319616 19.013 98 23 46 1 1941821856 12098240 6404000
21:27:37 41380 8192000000 10870300784 11334959104 1935008672 19.107 99 15 30 1 1948362944 9177744 4176528
21:29:20 73602 8192000000 10903204784 11373346816 1976107936 19.434 100 33 66 1 2002954288 17110416 9735936
21:31:38 70214 8192000000 10894202784 11372433408 1958481328 19.294 99 21 42 1 1978972336 14575456 5915552
21:34:02 55463 8192000000 10891449728 11375063040 1970124864 19.386 100 9 18 1 1988288192 15118192 3045136
21:35:58 100931 8192000000 10928236560 11413172224 1991992112 19.560 100 16 32 0 2010028832 13502208 4534512
21:39:03 56755 8192000000 10920138944 11396648960 1973612912 19.414 98 19 38 0 1990868096 11904560 5350624
21:41:01 38018 8192000000 10939340448 11396923392 1987678240 19.525 99 22 44 0 2003005856 9589904 5737712
21:42:40 30768 8192000000 10919367840 11399999488 1982932832 19.488 99 26 52 1 1999904880 9996608 6975440
21:44:11 8446 8192000000 10969735744 11415453696 2015317248 19.743 100 57 114 0 2043102800 12205952 15579600
21:45:22 67846 8192000000 10938274976 11411681280 1910615536 18.912 95 22 44 0 1932612528 15849568 6147424
21:47:36 122301 8192000000 10903550160 11392417792 1878263408 18.651 94 17 34 0 1902403024 19212416 4927200
21:50:38 107419 8192000000 10851731712 11321298944 1840808096 18.347 93 23 44 1 1853324032 6622944 5892992
21:53:30 57248 8192000000 10851492336 11311976448 1905704624 18.872 99 29 58 2 1924572592 11185008 7682960
21:55:28 41750 8192000000 10840778304 11298340864 1807513808 18.076 93 19 38 0 1819755776 7647232 4594736
21:57:11 61363 8192000000 10837818160 11305459712 1902453328 18.846 99 18 36 1 1918775840 10402848 5919664
21:59:12 97985 8192000000 10822851984 11286687744 1868789952 18.574 98 14 28 1 1886265840 13150032 4325856
22:01:54 44009 8192000000 10854438864 11315638272 1930801024 19.073 100 18 34 1 1944264544 8985536 4477984
22:03:43 63007 8192000000 10855974048 11331592192 1904149504 18.860 98 22 44 0 1919150592 9158400 5842688
22:05:54 66287 8192000000 10855175376 11322408960 1878287840 18.651 96 21 41 1 1893680000 9486960 5905200
22:08:07 54267 8192000000 10864813712 11321389056 1861509456 18.516 95 17 34 0 1876372816 10696416 4166944
22:10:08 59699 8192000000 10892608688 11349774336 1899183680 18.820 97 24 48 0 1918573248 13021008 6368560
22:12:59 68102 8192000000 10916991088 11369635840 1961352816 19.317 100 20 40 1 1978076640 11270944 5452880
22:15:12 30817 8192000000 10901862928 11363311616 1947926768 19.210 99 27 54 1 1967418736 11842864 7649104
22:16:43 51242 8192000000 10903586112 11365847040 1965107104 19.347 100 30 60 0 1984436368 10651888 8677376
22:18:58 88638 8192000000 10920562048 11376414720 1959939696 19.306 99 18 36 1 1980104832 15509184 4655952
22:21:34 104467 8192000000 10905828416 11388944384 1969119552 19.378 99 12 24 1 1989862784 17293296 3449936
22:24:23 48104 8192000000 10906083472 11393785856 1967254400 19.364 99 6 12 1 1984606432 15708208 1643824
22:26:19 41517 8192000000 10891073840 11365625856 1948208896 19.212 98 25 47 1 1963011984 8997280 5805808
22:28:12 58535 8192000000 10939619136 11402051584 1941570512 19.159 97 19 38 0 1965018544 18203568 5244464
22:30:11 78574 8192000000 10914958784 11401486336 1906248640 18.877 95 17 34 0 1934416128 22671808 5495680
22:33:52 54004 8192000000 10921005568 11397677056 1979437520 19.460 100 13 26 1 1997795456 14462672 3895264
22:35:47 60638 8192000000 10918511360 11401822208 1968211584 19.371 99 15 30 0 1992440736 20181280 4047872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 28-09-2021 22:38:50 to 28-09-2021 22:39:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-09-2021 22:39:05 to 28-09-2021 22:39:07.

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

28.09.21 22:39:08. 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-09-28 22:39:10 No severe PSQL-related problems occured
28.09.21 22:39:11. End of report.