Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.11.2021 01: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 16011380 kB
MemAvailable 30837728 kB
Buffers 0 kB
Cached 15719560 kB
SwapCached 2428 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-11-06 01:37:53
DTS_END                         2021-11-06 01:37:53.9950
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/51888
TRN_ID                          246103
ATT_ID                          731
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: 9324.50 22526 1118940
customer order: creation 176.98 364 21238
customer order: refuse 14.92 383 1790
order to supplier: creation 46.66 1331 5599
order to supplier: removal 3.37 102412 404
invoice (draft): creation 47.72 3266 5726
invoice (draft): removal 53.04 733 6365
invoice accept: apply 42.94 17051 5153
invoice accept: cancel 48.08 783 5770
customer reserve: creation 3909.13 170 469095
customer reserve: removal 4680.97 58 561716
realization accept: apply 85.80 180 10296
realization accept: cancel 81.94 109 9833
payment from customer: creation 61.33 123 7360
payment from customer: removal 5.88 73 705
payment to supplier: creation 49.54 144 5945
payment to supplier: removal 3.61 76 433
service: total inventory turnovers 4.65 1131 558
service: total monetary turnovers 7.06 59 847
service: aggregate perf. data 0.86 20643 103
service: refresh index statistics 0.03 301427 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-11-2021 01:39:18 to 06-11-2021 01:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 806
TEST_TIME 2 1151
TEST_TIME 3 2404
TEST_TIME 4 3394
TEST_TIME 5 3959
TEST_TIME 6 4005
TEST_TIME 7 4275
TEST_TIME 8 4461
TEST_TIME 9 4480
TEST_TIME 10 4846
TEST_TIME 11 5389
TEST_TIME 12 5783
TEST_TIME 13 6524
TEST_TIME 14 7304
TEST_TIME 15 7319
TEST_TIME 16 7265
TEST_TIME 17 7401
TEST_TIME 18 7218
TEST_TIME 19 7828
TEST_TIME 20 7987
TEST_TIME 21 8224
TEST_TIME 22 8606
TEST_TIME 23 8609
TEST_TIME 24 8868
TEST_TIME 25 9070
TEST_TIME 26 9024
TEST_TIME 27 9472
TEST_TIME 28 9852
TEST_TIME 29 10817
TEST_TIME 30 10665
TEST_TIME 31 10604
TEST_TIME 32 10517
TEST_TIME 33 10271
TEST_TIME 34 10240
TEST_TIME 35 10152
TEST_TIME 36 9953
TEST_TIME 37 9858
TEST_TIME 38 9812
TEST_TIME 39 9770
TEST_TIME 40 9693
TEST_TIME 41 9743
TEST_TIME 42 9746
TEST_TIME 43 9610
TEST_TIME 44 9506
TEST_TIME 45 9444
TEST_TIME 46 9452
TEST_TIME 47 9634
TEST_TIME 48 9575
TEST_TIME 49 9526
TEST_TIME 50 9574
TEST_TIME 51 9582
TEST_TIME 52 9595
TEST_TIME 53 9629
TEST_TIME 54 9588
TEST_TIME 55 9559
TEST_TIME 56 9595
TEST_TIME 57 9537
TEST_TIME 58 9633
TEST_TIME 59 9799
TEST_TIME 60 9825
TEST_TIME 61 9869
TEST_TIME 62 9723
TEST_TIME 63 9639
TEST_TIME 64 9648
TEST_TIME 65 9606
TEST_TIME 66 9605
TEST_TIME 67 9618
TEST_TIME 68 9519
TEST_TIME 69 9495
TEST_TIME 70 9499
TEST_TIME 71 9439
TEST_TIME 72 9417
TEST_TIME 73 9336
TEST_TIME 74 9456
TEST_TIME 75 9463
TEST_TIME 76 9405
TEST_TIME 77 9364
TEST_TIME 78 9351
TEST_TIME 79 9374
TEST_TIME 80 9398
TEST_TIME 81 9368
TEST_TIME 82 9411
TEST_TIME 83 9423
TEST_TIME 84 9466
TEST_TIME 85 9448
TEST_TIME 86 9467
TEST_TIME 87 9541
TEST_TIME 88 9521
TEST_TIME 89 9527
TEST_TIME 90 9509
TEST_TIME 91 9418
TEST_TIME 92 9424
TEST_TIME 93 9401
TEST_TIME 94 9372
TEST_TIME 95 9343
TEST_TIME 96 9299
TEST_TIME 97 9309
TEST_TIME 98 9295
TEST_TIME 99 9305
TEST_TIME 100 9302
TEST_TIME 101 9322
TEST_TIME 102 9299
TEST_TIME 103 9350
TEST_TIME 104 9321
TEST_TIME 105 9373
TEST_TIME 106 9397
TEST_TIME 107 9387
TEST_TIME 108 9427
TEST_TIME 109 9523
TEST_TIME 110 9516
TEST_TIME 111 9482
TEST_TIME 112 9448
TEST_TIME 113 9466
TEST_TIME 114 9410
TEST_TIME 115 9352
TEST_TIME 116 9338
TEST_TIME 117 9339
TEST_TIME 118 9347
TEST_TIME 119 9348
TEST_TIME 120 9328

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-11-2021 01:39:21 to 06-11-2021 01:39:25.

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 1552738 1552738 0 0.00 0 14797 6 0 0
doc_list_biud 1552722 1552722 0 0.00 0 31840 27 0 0
sp_add_invoice_to_stock 5315 5153 162 3.04 38 150868 17052 0 162
sp_cancel_adding_invoice 5770 5770 0 0.00 5 53246 784 0 0
sp_cancel_client_order 1790 1790 0 0.00 4 22326 384 0 0
sp_cancel_customer_reserve 561716 561716 0 0.00 1 28379 59 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 4081 73 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 5610 77 0 0
sp_cancel_supplier_invoice 6374 6365 9 0.14 2 16866 734 0 9
sp_cancel_supplier_order 404 404 0 0.00 10 890159 102413 0 0
sp_cancel_write_off 9833 9833 0 0.00 1 11671 109 0 0
sp_client_order 21238 21238 0 0.00 3 22343 364 0 0
sp_customer_reserve 469095 469095 0 0.00 0 60266 171 0 0
sp_fill_shopping_cart 32558 32558 0 0.00 1 15783 39 0 0
sp_fill_shopping_cart_clo_res 469093 469093 0 0.00 0 27530 13 0 0
sp_get_clo_for_invoice 5148 5148 0 0.00 0 18622 300 0 0
sp_kill_cost_storno 16741 16741 0 0.00 0 8988 54 0 0
sp_kill_qstorno_ret_qs2qd 1140034 1140034 0 0.00 0 28340 31 0 0
sp_kill_qty_storno 601312 601312 0 0.00 0 31839 68 0 0
sp_lock_selected_doc 21313 21313 0 0.00 0 7473 5 0 0
sp_make_cost_storno 28749 28749 0 0.00 0 14291 51 0 0
sp_multiply_rows_for_pdistr 28739 28739 0 0.00 0 11834 27 0 0
sp_multiply_rows_for_qdistr 21238 21238 0 0.00 0 19847 165 0 0
sp_pay_from_customer 7360 7360 0 0.00 4 11899 123 0 0
sp_pay_to_supplier 5945 5945 0 0.00 5 17468 144 0 0
sp_payment_common 13299 13299 0 0.00 1 17445 30 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 22314 358 0 0
sp_qd_handle_on_invoice_upd_sts 10914 10914 0 0.00 0 31838 323 0 0
sp_qd_handle_on_reserve_upd_sts 10290 10290 0 0.00 0 18797 69 0 0
sp_reserve_write_off 10296 10296 0 0.00 6 18832 181 0 0
sp_supplier_invoice 5726 5726 0 0.00 7 85833 3267 0 0
sp_supplier_order 5599 5599 0 0.00 4 53713 1332 0 0
srv_aggregate_perf_data 104 103 1 0.96 2988 268607 20643 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2388 119072 44707 0 0
srv_find_qd_qs_mism 1069802 1069802 0 0.00 0 13393 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 36 89163 1131 0 0
srv_make_money_saldo 847 847 0 0.00 0 19126 59 0 0
srv_recalc_idx_stat 4 4 0 0.00 123879 430567 301428 0 0
t$perf-abend:sp_add_invoice_to_stock 162 162 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5153 5153 0 0.00 0 11351 180 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 5054 99 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 4266 14 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 1557 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 762 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 1377 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 572 10 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 25777 1665 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 3952 12 0 0
t$perf-norm:sp_client_order 21238 21238 0 0.00 0 6779 18 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 980 6 0 0
t$perf-norm:sp_pay_from_customer 7360 7360 0 0.00 0 11830 14 0 0
t$perf-norm:sp_pay_to_supplier 5945 5945 0 0.00 0 5066 12 0 0
t$perf-norm:sp_reserve_write_off 10296 10296 0 0.00 0 10655 11 0 0
t$perf-norm:sp_supplier_invoice 5726 5726 0 0.00 0 6189 17 0 0
t$perf-norm:sp_supplier_order 5599 5599 0 0.00 0 10636 20 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 3711 45 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 391 8 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 732 23 0 0
t$perf-norm:srv_make_money_saldo 847 847 0 0.00 0 2109 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10612 10288 324 3.05 0 6933 22 0 324
v_all_customers 38798 38798 0 0.00 0 95 1 0 0
v_all_suppliers 31050 31050 0 0.00 0 95 1 0 0
v_all_wares 42472 42472 0 0.00 0 63 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 1904 9 0 0
v_cancel_client_order 3580 3580 0 0.00 0 5240 6 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 4071 9 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 4202 11 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 3093 9 0 0
v_cancel_supplier_invoice 826 808 18 2.17 0 2079 21 0 18
v_cancel_supplier_order 808 808 0 0.00 0 4178 12 0 0
v_cancel_write_off 2024 2024 0 0.00 0 2138 15 0 0
v_max_id_clo_ord 5599 5599 0 0.00 0 1604 2 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 3040 20 0 0
v_max_id_ord_sup 5723 5723 0 0.00 0 4369 4 0 0
v_max_non_paid_invoice 1739 1739 0 0.00 0 1813 4 0 0
v_max_non_paid_realizn 3978 3978 0 0.00 0 3298 5 0 0
v_min_id_clo_ord 5599 5599 0 0.00 0 1885 5 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 2982 6 0 0
v_min_id_ord_sup 5723 5723 0 0.00 0 6346 11 0 0
v_min_non_paid_invoice 5942 5942 0 0.00 0 17440 24 0 0
v_min_non_paid_realizn 7357 7357 0 0.00 0 5553 19 0 0
v_our_firm 10432 10432 0 0.00 0 15 1 0 0
v_reserve_write_off 20580 20580 0 0.00 0 4494 5 0 0
x_lock_depdocs_on_canc_invoice 11731 11731 0 0.00 0 15442 121 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 15989 473 0 0
x_make_qstorno_1000_1200 5599 5599 0 0.00 1 50895 1250 0 0
x_make_qstorno_1200_2000 5726 5726 0 0.00 4 85748 3126 0 0
x_make_qstorno_2100_3300 469095 469095 0 0.00 0 60265 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-11-2021 01:39:26 to 06-11-2021 01:39:30.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:57 4 8192000000 8799611296 8811024384 5314912 0.064 2 1 2 0 5414512 56816 42784
23:08:58 24672 8192000000 9913969440 10226229248 992015696 10.801 100 88 176 0 1021228496 6500480 22712320
23:10:27 21469 8192000000 9953309760 10277007360 1033610688 11.203 100 92 184 0 1066137888 9062208 23464992
23:11:54 21520 8192000000 9989672256 10327564288 1072762160 11.578 100 84 168 1 1103938848 10394672 20782016
23:13:23 12655 8192000000 10023133104 10371170304 1111353024 11.945 100 84 168 1 1142794576 11538400 19903152
23:14:40 10104 8192000000 10077732384 10436210688 1164610288 12.446 100 79 160 1 1194743104 12498576 17634240
23:15:53 5872 8192000000 10114931696 10482651136 1202258544 12.797 100 76 152 2 1231363424 12800320 16304560
23:17:01 2177 8192000000 10162177680 10534977536 1244092736 13.184 100 91 180 2 1279155488 14967952 20094800
23:18:04 1314 8192000000 10185099792 10568642560 1273130272 13.450 100 86 172 0 1304834272 12145664 19558336
23:19:07 28961 8192000000 10201005888 10595733504 1292968624 13.631 100 47 93 0 1313350560 10382080 9999856
23:20:40 8020 8192000000 10190404544 10601328640 1302115424 13.714 100 32 64 1 1317987968 8258384 7614160
23:21:48 11790 8192000000 10186311440 10606661632 1307811776 13.766 100 22 44 1 1319194880 6355360 5027744
23:23:04 35767 8192000000 10195457248 10621255680 1315575504 13.837 100 18 35 2 1326389408 5760304 5053600
23:24:42 6942 8192000000 10220175424 10635702272 1320947776 13.885 100 21 42 0 1331588016 6201536 4438704
23:25:54 9040 8192000000 10211201984 10639138816 1330712576 13.974 100 22 43 0 1341754672 5724288 5317808
23:27:04 10457 8192000000 10199011216 10618494976 1302921904 13.722 99 13 26 0 1311264944 5292112 3050928
23:28:17 52732 8192000000 10134473712 10538086400 1210181216 12.871 94 39 76 2 1224324384 4392000 9751168
23:30:10 7702 8192000000 10163987312 10569129984 1261478240 13.344 99 35 71 2 1276032816 6309616 8244960
23:31:24 7864 8192000000 10153488080 10555219968 1252515456 13.261 99 21 42 0 1263570016 5211120 5843440
23:32:33 6533 8192000000 10158472992 10561339392 1261376896 13.343 100 29 58 1 1278440752 10248672 6815184
23:33:42 35816 8192000000 10148291328 10551009280 1232735568 13.079 97 26 51 1 1243873248 5358960 5778720
23:35:18 6308 8192000000 10179021040 10585243648 1284058112 13.550 100 29 58 1 1296938448 5625184 7255152
23:36:27 6600 8192000000 10194159776 10605281280 1298146848 13.678 100 26 53 1 1310416928 5461568 6808512
23:37:38 46340 8192000000 10222232352 10634354688 1323724928 13.910 100 21 42 0 1333203024 4551616 4926480
23:39:39 17554 8192000000 10251985376 10662162432 1342501104 14.080 100 44 88 0 1361061760 9005072 9555584
23:41:02 5027 8192000000 10266071600 10679693312 1357740848 14.217 100 53 106 4 1381333728 10898688 12694192
23:42:11 23732 8192000000 10270214864 10695610368 1366783696 14.298 100 32 64 0 1384541744 10118496 7639552
23:43:40 31943 8192000000 10296520592 10715607040 1379048432 14.408 100 51 102 0 1404207408 13637968 11521008
23:45:32 3694 8192000000 10305897152 10726666240 1387009744 14.479 100 46 90 1 1417791184 19618848 11162592
23:46:39 5913 8192000000 10326486032 10734465024 1391591664 14.520 100 76 151 1 1432422560 22779248 18051648
23:47:48 3594 8192000000 10313784016 10724888576 1368025136 14.309 98 70 138 2 1407725648 22642208 17058304
23:48:56 4936 8192000000 10313706688 10732191744 1381860000 14.433 100 57 114 2 1419588112 23875120 13852992
23:50:12 4697 8192000000 10301525104 10710999040 1353566880 14.180 98 71 142 0 1404206080 32891056 17748144
23:51:24 5286 8192000000 10306957536 10732355584 1368065696 14.310 99 51 102 0 1409310048 28727328 12517024
23:52:34 5130 8192000000 10321857728 10737283072 1383002112 14.443 100 64 128 0 1424054464 26059504 14992848
23:53:46 5002 8192000000 10324966496 10742632448 1387516192 14.484 100 63 125 0 1433507616 30240736 15750688
23:54:55 3227 8192000000 10321205168 10737655808 1379195952 14.409 100 59 118 5 1434044752 39102656 15746144
23:56:02 2413 8192000000 10330898880 10735448064 1371998480 14.345 100 85 166 1 1440430000 47039344 21392176
23:57:08 6187 8192000000 10339186240 10746572800 1367628672 14.306 99 78 156 3 1430254624 43298144 19327808
23:58:19 6776 8192000000 10320991648 10745696256 1362649200 14.261 99 48 96 2 1419765664 44035936 13080528
23:59:29 2388 8192000000 10312080224 10730684416 1364472944 14.277 100 47 93 0 1418210768 41637840 12099984
00:00:36 7248 8192000000 10328165408 10758414336 1387573808 14.484 100 38 75 1 1434126096 36076896 10475392
00:01:47 11873 8192000000 10345276848 10782326784 1396639152 14.565 100 38 76 1 1455116288 48172752 10304384
00:03:00 12934 8192000000 10342948240 10776305664 1390491920 14.510 100 40 80 0 1448362432 46907424 10963088
00:04:32 25537 8192000000 10314507312 10756096000 1343134688 14.086 98 25 50 1 1399991616 49432384 7424544
00:05:59 58301 8192000000 10268166640 10736066560 1348483968 14.134 100 11 22 0 1395354256 43524272 3346016
00:08:00 73074 8192000000 10283720192 10727952384 1375575920 14.377 100 23 46 1 1389510464 8037792 5896752
00:10:13 107677 8192000000 10286127744 10740207616 1360877072 14.245 98 33 66 1 1377717776 8798896 8041808
00:13:13 80317 8192000000 10303337968 10747211776 1385082496 14.462 100 38 77 1 1406190672 12709472 8398704
00:15:34 23374 8192000000 10300481360 10755645440 1389736320 14.504 100 36 72 0 1412887008 14806096 8344592
00:17:11 50355 8192000000 10303099200 10763763712 1395579840 14.556 100 15 30 1 1414946496 15636528 3730128
00:19:09 27490 8192000000 10295585216 10767319040 1395045120 14.551 100 8 16 0 1409500272 12479568 1975584
00:21:19 41600 8192000000 10313167328 10769354752 1400548432 14.600 100 16 32 1 1424717456 20282352 3886672
00:23:01 58436 8192000000 10298234352 10773028864 1380261424 14.419 99 14 28 0 1407453376 23390880 3801072
00:25:14 84696 8192000000 10298143920 10757423104 1368176624 14.311 98 15 30 1 1382849120 11832096 2840400
00:27:41 73322 8192000000 10296962608 10755166208 1367044000 14.301 98 16 32 0 1381515120 10465696 4005424
00:30:01 51878 8192000000 10301292016 10763845632 1380705216 14.423 99 20 40 0 1399264096 13864800 4694080
00:32:01 17405 8192000000 10286577200 10733477888 1370227072 14.329 99 32 64 0 1385586224 8525872 6833280
00:33:21 82572 8192000000 10300195456 10747068416 1341699056 14.073 97 17 33 0 1356306976 11109792 3498128
00:35:51 119071 8192000000 10290653104 10750152704 1321650336 13.892 95 8 16 0 1345338176 21805008 1882832
00:38:50 75749 8192000000 10296005888 10745114624 1372982144 14.354 99 15 29 0 1391360400 15711056 2667200
00:41:27 82000 8192000000 10286651520 10723323904 1353826880 14.182 97 34 68 2 1380680400 19229680 7623840
00:43:50 69831 8192000000 10277941008 10742403072 1366913584 14.299 99 11 22 0 1379428560 10089408 2425568
00:46:00 81022 8192000000 10255613200 10711715840 1322425712 13.899 96 14 28 0 1342389136 16882992 3080432
00:48:28 46846 8192000000 10281635120 10734985216 1363817392 14.272 99 27 53 0 1383487344 13393248 6276704
00:51:40 55302 8192000000 10275024416 10714599424 1349098736 14.139 99 33 66 2 1374357072 17850464 7407872
00:53:36 50966 8192000000 10248146768 10693705728 1311964704 13.804 96 22 44 0 1325478688 7652208 5861776
00:55:28 62921 8192000000 10270643264 10740387840 1358391952 14.223 99 17 34 1 1373632544 11643152 3597440
00:57:49 17941 8192000000 10262099824 10711285760 1357648400 14.216 100 27 55 2 1370439616 7001680 5789536
00:59:07 47661 8192000000 10273016608 10729127936 1345387504 14.106 98 31 62 0 1366695632 14554864 6753264
01:00:55 74913 8192000000 10288461696 10743439360 1366770288 14.298 99 17 34 1 1380704032 10635584 3298160
01:03:10 29157 8192000000 10280614752 10731094016 1346759072 14.118 98 27 56 1 1361762464 8820560 6182832
01:04:43 37350 8192000000 10305637792 10745741312 1384841120 14.460 100 32 64 1 1402298592 9224448 8233024
01:06:23 86351 8192000000 10285508704 10734903296 1321786528 13.893 95 17 34 0 1333177536 7250272 4140736
01:08:50 73524 8192000000 10286162704 10754916352 1374045568 14.363 99 17 34 1 1391243600 13651520 3546512
01:11:06 16713 8192000000 10303113968 10752974848 1392491856 14.528 100 25 45 2 1406514128 9057952 4964320
01:12:43 64820 8192000000 10280764896 10736562176 1324268512 13.915 95 20 40 0 1338722160 9031280 5422368
01:14:56 54260 8192000000 10285972272 10738778112 1358037552 14.220 97 12 24 0 1367429360 6998752 2393056
01:16:52 95333 8192000000 10277947712 10741530624 1349885328 14.146 97 9 18 1 1360836000 9346224 1604448
01:19:29 46299 8192000000 10302237008 10760994816 1392738032 14.530 100 14 27 1 1407702560 11658256 3306272
01:21:17 54834 8192000000 10310678192 10769883136 1400791072 14.602 100 39 78 4 1420270912 10500640 8979200
01:23:15 102506 8192000000 10307074784 10765701120 1380581104 14.422 99 5 10 0 1393651712 12157888 912720
01:26:00 93118 8192000000 10280190080 10735329280 1247956336 13.219 90 11 22 2 1273424128 22513264 2954528
01:28:37 108658 8192000000 10277771392 10742743040 1365739664 14.289 99 18 36 1 1385726912 15732416 4254832
01:31:40 45748 8192000000 10276132592 10735607808 1344088608 14.094 97 12 24 0 1363882928 16915664 2878656
01:33:26 46017 8192000000 10250303472 10712993792 1327059024 13.941 97 9 18 0 1341219216 12028368 2131824
01:35:15 58401 8192000000 10264492464 10726768640 1354728512 14.190 99 15 30 0 1370874800 12367568 3778720
01:37:19 36577 8192000000 9474772016 9799397376 620790736 7.044 46 7 10 2 625186560 3150208 1245616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-11-2021 01:39:32 to 06-11-2021 01:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 162 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-11-2021 01:39:48 to 06-11-2021 01:39:50.

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          246543
MON$OLDEST_ACTIVE               246544
MON$OLDEST_SNAPSHOT             246544
MON$NEXT_TRANSACTION            246544
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                       2038944
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.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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 ---
:141: 
:142: mscs-test-161.budzdorov.ru    Sat Nov  6 01:37:57 2021
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:144: 
--- end of diff output ---

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

06.11.21 01:39:51. 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-11-06 01:39:53 No severe PSQL-related problems occured
06.11.21 01:39:54. End of report.