Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.08.2021 16: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 16083228 kB
MemAvailable 31009420 kB
Buffers 0 kB
Cached 15551584 kB
SwapCached 2624 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-08-07 16:37:47
DTS_END                         2021-08-07 16:37:47.5250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46276
TRN_ID                          243097
ATT_ID                          689
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: 9391.56 24954 1126987
customer order: creation 180.86 322 21703
customer order: refuse 15.05 402 1806
order to supplier: creation 47.80 1215 5736
order to supplier: removal 3.58 106997 429
invoice (draft): creation 48.49 3154 5819
invoice (draft): removal 53.32 809 6398
invoice accept: apply 43.41 18454 5209
invoice accept: cancel 47.53 786 5704
customer reserve: creation 3978.87 183 477464
customer reserve: removal 4671.00 63 560520
realization accept: apply 86.97 191 10436
realization accept: cancel 79.10 116 9492
payment from customer: creation 62.45 119 7494
payment from customer: removal 5.74 81 689
payment to supplier: creation 50.90 140 6108
payment to supplier: removal 3.72 90 446
service: total inventory turnovers 5.02 1045 602
service: total monetary turnovers 6.98 42 838
service: aggregate perf. data 0.76 26566 91
service: refresh index statistics 0.03 338295 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-08-2021 16:39:04 to 07-08-2021 16:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7026
TEST_TIME 2 5888
TEST_TIME 3 5048
TEST_TIME 4 5721
TEST_TIME 5 5338
TEST_TIME 6 5496
TEST_TIME 7 5579
TEST_TIME 8 5767
TEST_TIME 9 5789
TEST_TIME 10 6177
TEST_TIME 11 6139
TEST_TIME 12 6122
TEST_TIME 13 6372
TEST_TIME 14 6276
TEST_TIME 15 6403
TEST_TIME 16 7092
TEST_TIME 17 7299
TEST_TIME 18 7272
TEST_TIME 19 7536
TEST_TIME 20 7879
TEST_TIME 21 7907
TEST_TIME 22 8168
TEST_TIME 23 8242
TEST_TIME 24 8497
TEST_TIME 25 8795
TEST_TIME 26 8952
TEST_TIME 27 9414
TEST_TIME 28 10033
TEST_TIME 29 10577
TEST_TIME 30 10291
TEST_TIME 31 10475
TEST_TIME 32 10538
TEST_TIME 33 10384
TEST_TIME 34 10362
TEST_TIME 35 10238
TEST_TIME 36 10203
TEST_TIME 37 10060
TEST_TIME 38 10184
TEST_TIME 39 10069
TEST_TIME 40 9947
TEST_TIME 41 9998
TEST_TIME 42 10072
TEST_TIME 43 9990
TEST_TIME 44 9936
TEST_TIME 45 10077
TEST_TIME 46 9983
TEST_TIME 47 9894
TEST_TIME 48 9708
TEST_TIME 49 9699
TEST_TIME 50 9722
TEST_TIME 51 9622
TEST_TIME 52 9570
TEST_TIME 53 9525
TEST_TIME 54 9455
TEST_TIME 55 9422
TEST_TIME 56 9693
TEST_TIME 57 9744
TEST_TIME 58 9655
TEST_TIME 59 9608
TEST_TIME 60 9480
TEST_TIME 61 9517
TEST_TIME 62 9509
TEST_TIME 63 9540
TEST_TIME 64 9571
TEST_TIME 65 9501
TEST_TIME 66 9636
TEST_TIME 67 9690
TEST_TIME 68 9690
TEST_TIME 69 9662
TEST_TIME 70 9671
TEST_TIME 71 9651
TEST_TIME 72 9667
TEST_TIME 73 9726
TEST_TIME 74 9751
TEST_TIME 75 9807
TEST_TIME 76 9747
TEST_TIME 77 9684
TEST_TIME 78 9695
TEST_TIME 79 9719
TEST_TIME 80 9661
TEST_TIME 81 9637
TEST_TIME 82 9659
TEST_TIME 83 9636
TEST_TIME 84 9606
TEST_TIME 85 9560
TEST_TIME 86 9556
TEST_TIME 87 9515
TEST_TIME 88 9498
TEST_TIME 89 9461
TEST_TIME 90 9445
TEST_TIME 91 9429
TEST_TIME 92 9405
TEST_TIME 93 9458
TEST_TIME 94 9463
TEST_TIME 95 9476
TEST_TIME 96 9520
TEST_TIME 97 9517
TEST_TIME 98 9519
TEST_TIME 99 9512
TEST_TIME 100 9559
TEST_TIME 101 9523
TEST_TIME 102 9641
TEST_TIME 103 9617
TEST_TIME 104 9642
TEST_TIME 105 9686
TEST_TIME 106 9605
TEST_TIME 107 9562
TEST_TIME 108 9505
TEST_TIME 109 9563
TEST_TIME 110 9511
TEST_TIME 111 9462
TEST_TIME 112 9422
TEST_TIME 113 9436
TEST_TIME 114 9406
TEST_TIME 115 9418
TEST_TIME 116 9401
TEST_TIME 117 9431
TEST_TIME 118 9384
TEST_TIME 119 9407
TEST_TIME 120 9407

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-08-2021 16:39:07 to 07-08-2021 16:39:11.

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 1568187 1568187 0 0.00 0 34219 7 0 0
doc_list_biud 1568183 1568183 0 0.00 0 34770 29 0 0
sp_add_invoice_to_stock 5332 5209 123 2.30 33 167612 18454 0 123
sp_cancel_adding_invoice 5704 5704 0 0.00 5 51782 786 0 0
sp_cancel_client_order 1806 1806 0 0.00 4 19616 402 0 0
sp_cancel_customer_reserve 560520 560520 0 0.00 1 25075 64 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 6029 81 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 6327 90 0 0
sp_cancel_supplier_invoice 6407 6398 9 0.14 2 21428 810 0 9
sp_cancel_supplier_order 429 429 0 0.00 7 1091114 106998 0 0
sp_cancel_write_off 9492 9492 0 0.00 1 10303 117 0 0
sp_client_order 21703 21703 0 0.00 4 28557 322 0 0
sp_customer_reserve 477464 477464 0 0.00 0 75139 183 0 0
sp_fill_shopping_cart 33247 33247 0 0.00 1 12731 39 0 0
sp_fill_shopping_cart_clo_res 477435 477435 0 0.00 0 18278 13 0 0
sp_get_clo_for_invoice 5176 5176 0 0.00 0 43138 294 0 0
sp_kill_cost_storno 16331 16331 0 0.00 0 7093 63 0 0
sp_kill_qstorno_ret_qs2qd 1137359 1137359 0 0.00 0 25040 34 0 0
sp_kill_qty_storno 599960 599960 0 0.00 0 34769 74 0 0
sp_lock_selected_doc 21623 21623 0 0.00 0 9219 5 0 0
sp_make_cost_storno 29213 29213 0 0.00 0 11329 56 0 0
sp_multiply_rows_for_pdistr 29213 29213 0 0.00 0 7971 35 0 0
sp_multiply_rows_for_qdistr 21703 21703 0 0.00 0 14748 140 0 0
sp_pay_from_customer 7494 7494 0 0.00 4 10433 119 0 0
sp_pay_to_supplier 6108 6108 0 0.00 5 12299 141 0 0
sp_payment_common 13602 13602 0 0.00 1 7833 33 0 0
sp_qd_handle_on_cancel_clo 1806 1806 0 0.00 0 19607 370 0 0
sp_qd_handle_on_invoice_upd_sts 10879 10879 0 0.00 0 34767 358 0 0
sp_qd_handle_on_reserve_upd_sts 10436 10436 0 0.00 0 21895 61 0 0
sp_reserve_write_off 10436 10436 0 0.00 6 21949 191 0 0
sp_supplier_invoice 5819 5819 0 0.00 7 84348 3154 0 0
sp_supplier_order 5736 5736 0 0.00 5 29445 1216 0 0
srv_aggregate_perf_data 92 91 1 1.08 3177 418547 26566 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1907 143396 43602 0 0
srv_find_qd_qs_mism 1076718 1076718 0 0.00 0 34216 6 0 0
srv_make_invnt_saldo 602 602 0 0.00 38 54482 1046 0 0
srv_make_money_saldo 838 838 0 0.00 1 11703 43 0 0
srv_recalc_idx_stat 3 3 0 0.00 261574 381397 338295 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 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 5209 5209 0 0.00 0 21549 184 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 7221 131 0 0
t$perf-norm:sp_cancel_client_order 1806 1806 0 0.00 0 6337 22 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 5157 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 4075 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 205 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 3736 30 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 35387 1857 0 0
t$perf-norm:sp_cancel_write_off 1102 1102 0 0.00 0 3998 15 0 0
t$perf-norm:sp_client_order 21703 21703 0 0.00 0 16355 21 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 5762 11 0 0
t$perf-norm:sp_pay_from_customer 7494 7494 0 0.00 0 21345 17 0 0
t$perf-norm:sp_pay_to_supplier 6108 6108 0 0.00 0 15837 18 0 0
t$perf-norm:sp_reserve_write_off 10436 10436 0 0.00 0 17811 15 0 0
t$perf-norm:sp_supplier_invoice 5819 5819 0 0.00 0 5036 18 0 0
t$perf-norm:sp_supplier_order 5736 5736 0 0.00 0 7982 22 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 580 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 137 3 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 850 23 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 5640 30 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10596 10350 246 2.32 0 9311 21 0 246
v_all_customers 39370 39370 0 0.00 0 25 1 0 0
v_all_suppliers 31776 31776 0 0.00 0 22 1 0 0
v_all_wares 43406 43406 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 724 7 0 0
v_cancel_client_order 3612 3612 0 0.00 0 2970 6 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 2428 6 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 1545 8 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 1833 7 0 0
v_cancel_supplier_invoice 840 822 18 2.14 0 2770 22 0 18
v_cancel_supplier_order 858 858 0 0.00 0 1235 7 0 0
v_cancel_write_off 2204 2204 0 0.00 0 1759 16 0 0
v_max_id_clo_ord 5732 5732 0 0.00 0 162 1 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 2327 13 0 0
v_max_id_ord_sup 5812 5812 0 0.00 0 5895 6 0 0
v_max_non_paid_invoice 1764 1764 0 0.00 0 1544 4 0 0
v_max_non_paid_realizn 4007 4007 0 0.00 0 2927 4 0 0
v_min_id_clo_ord 5732 5732 0 0.00 0 4977 6 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 8451 22 0 0
v_min_id_ord_sup 5812 5812 0 0.00 0 3929 4 0 0
v_min_non_paid_invoice 6108 6108 0 0.00 0 7831 26 0 0
v_min_non_paid_realizn 7494 7494 0 0.00 0 7410 20 0 0
v_our_firm 11010 11010 0 0.00 0 17 1 0 0
v_reserve_write_off 20872 20872 0 0.00 0 3481 6 0 0
x_lock_depdocs_on_canc_invoice 11691 11691 0 0.00 0 13486 127 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 11175 344 0 0
x_make_qstorno_1000_1200 5736 5736 0 0.00 1 29323 1128 0 0
x_make_qstorno_1200_2000 5819 5819 0 0.00 3 82749 3019 0 0
x_make_qstorno_2100_3300 477463 477463 0 0.00 0 75135 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-08-2021 16:39:12 to 07-08-2021 16:39:16.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:07:51 3 8192000000 8801155952 8812658688 6410576 0.078 2 1 2 0 6529952 56816 62560
14:08:52 9702 8192000000 10341269136 10654879744 1398644864 14.583 100 87 174 0 1433290048 5860192 28784992
14:10:07 19581 8192000000 10420368944 10746687488 1472247616 15.233 100 90 180 1 1509013264 7919920 28845728
14:11:36 15893 8192000000 10468401264 10808262656 1525650576 15.699 100 82 164 1 1561036512 9458944 25926992
14:12:55 8582 8192000000 10544740000 10901434368 1608111536 16.409 100 78 156 0 1642148144 10567664 23468944
14:14:05 8895 8192000000 10619002608 10985050112 1674589600 16.972 100 79 158 0 1709281840 11824336 22867904
14:15:17 7026 8192000000 10680812448 11055636480 1746261232 17.571 100 85 170 1 1782959008 13062320 23635456
14:16:26 3276 8192000000 10740874416 11124187136 1803912080 18.046 100 86 170 2 1842953712 14138288 24903344
14:17:32 1864 8192000000 10783825040 11171012608 1842055328 18.358 100 88 176 0 1879756064 12755856 24944880
14:18:36 1619 8192000000 10821840768 11205767168 1874801472 18.623 100 92 184 2 1914184208 13649328 25733408
14:19:38 2838 8192000000 10788644848 11208458240 1877175520 18.642 100 52 102 2 1902109536 10274176 14659840
14:20:42 5411 8192000000 10798277248 11217215488 1883133136 18.690 100 20 40 0 1895766976 6098976 6534864
14:21:48 51272 8192000000 10806657680 11242336256 1895097200 18.787 100 38 76 0 1913801264 7156976 11547088
14:23:43 6922 8192000000 10817756832 11256287232 1907922432 18.890 100 19 38 0 1920005344 6861360 5221552
14:24:50 8108 8192000000 10827367968 11263614976 1914553040 18.943 100 19 38 1 1927006832 6135584 6318208
14:25:58 13074 8192000000 10835050848 11270565888 1922704352 19.009 100 36 74 1 1938466848 4921408 10841088
14:27:12 20977 8192000000 10829022512 11267497984 1902979456 18.850 99 27 54 0 1917489568 7041088 7469024
14:28:35 5363 8192000000 10816367280 11235713024 1833941856 18.291 96 38 74 0 1850560016 6380736 10237424
14:29:42 10142 8192000000 10761786208 11184152576 1830695680 18.265 99 27 53 2 1844952336 6397504 7859152
14:30:55 61626 8192000000 10730903696 11135815680 1773974608 17.800 98 32 64 0 1788698496 5900400 8823488
14:32:57 9756 8192000000 10726162848 11139133440 1803718384 18.044 100 22 44 0 1815190032 5454560 6017088
14:34:08 5295 8192000000 10758287632 11183796224 1843722864 18.371 100 32 60 4 1858338112 4818176 9797072
14:35:17 10265 8192000000 10770734032 11204935680 1864235728 18.538 100 20 39 0 1874763792 5200832 5327232
14:36:28 21710 8192000000 10782060464 11214184448 1867350624 18.563 100 25 49 1 1883605200 9577584 6676992
14:37:50 3255 8192000000 10826090576 11247292416 1899682272 18.824 100 38 76 2 1919360208 7883984 11793952
14:39:03 13151 8192000000 10870596720 11284320256 1926845264 19.042 100 57 114 1 1953604128 10242768 16516096
14:40:20 25613 8192000000 10882845104 11305771008 1944465664 19.182 100 48 96 1 1970740224 12325136 13949424
14:41:59 70760 8192000000 10891253216 11329732608 1958039776 19.290 100 40 76 4 1983849808 14451520 11358512
14:44:23 22298 8192000000 10924376560 11359535104 1981198720 19.474 100 54 108 1 2016571440 19827168 15545552
14:45:49 5030 8192000000 10937183472 11362758656 1981569184 19.477 100 53 106 0 2017291664 20223712 15498768
14:46:56 4598 8192000000 10940620864 11370422272 1973179344 19.411 99 55 108 1 2011828592 22458576 16190672
14:48:04 4479 8192000000 10963793136 11382476800 2001722832 19.636 100 84 168 0 2051759552 25461232 24575488
14:49:15 3809 8192000000 10982308656 11403472896 2013354176 19.728 100 79 158 0 2064302736 27917664 23030896
14:50:25 7748 8192000000 10945116640 11384266752 1989714384 19.542 100 45 90 1 2044904352 40607712 14582256
14:51:35 3793 8192000000 10999427536 11415744512 2009206080 19.695 100 78 155 1 2073035488 39584800 24244608
14:52:47 8347 8192000000 11000359360 11427667968 2015323328 19.743 100 71 142 1 2084584448 47777696 21483424
14:54:02 2344 8192000000 10967406608 11386957824 1984359712 19.499 99 79 154 3 2047784064 40491664 22932688
14:55:09 1905 8192000000 10983117360 11401474048 2002874528 19.645 100 82 162 2 2066231840 38002848 25354464
14:56:17 2326 8192000000 10990233360 11410341888 1990628048 19.549 99 85 169 2 2060609088 44197680 25783360
14:57:31 6044 8192000000 10986301936 11397988352 1995629712 19.588 100 78 155 2 2061199424 41477616 24092096
14:58:44 4396 8192000000 10946172240 11350462464 1948889088 19.218 99 93 183 0 2023666944 46280416 28497440
14:59:59 6572 8192000000 10976179008 11403100160 1991274080 19.554 100 65 130 1 2058014304 45412288 21327936
15:01:15 8726 8192000000 10957544432 11390603264 1963079024 19.331 99 53 103 1 2029254048 48506720 17668304
15:02:27 13789 8192000000 10929838576 11378925568 1962331440 19.325 100 29 58 0 2015030016 43172640 9525936
15:04:11 16829 8192000000 10891776816 11337641984 1892423344 18.765 98 32 64 2 1955781712 51937440 11420928
15:05:29 20539 8192000000 10899821840 11345461248 1906358176 18.877 99 22 42 2 1962030528 48079680 7592672
15:06:53 40541 8192000000 10885423600 11322171392 1931701824 19.080 100 27 52 2 1954238736 15286160 7250752
15:08:39 49385 8192000000 10879451088 11343376384 1942932000 19.170 100 17 34 2 1968272688 20693104 4647584
15:11:09 65930 8192000000 10875739248 11318550528 1896380944 18.797 97 26 53 1 1917647104 14932736 6333424
15:13:22 50299 8192000000 10882127360 11340664832 1935357712 19.110 99 14 29 0 1949854512 10446832 4049968
15:15:17 75799 8192000000 10884020944 11354165248 1938649024 19.136 99 7 14 0 1951906448 11690784 1566640
15:17:34 75665 8192000000 10910250208 11378368512 1976335408 19.436 100 17 34 0 1992892176 11972064 4584704
15:19:55 78065 8192000000 10911174736 11373608960 1957121408 19.283 99 6 12 1 1970920416 12283104 1515904
15:22:14 13563 8192000000 10929722240 11376119808 1978983536 19.457 100 25 51 0 1995174640 9199520 6991584
15:23:28 143395 8192000000 10928572736 11371479040 1967056560 19.362 99 22 42 0 1983886144 11247840 5581744
15:27:09 19146 8192000000 10949975056 11380330496 1968174112 19.371 99 61 121 0 1996422144 10872912 17375120
15:28:33 53035 8192000000 10896173136 11358638080 1950782944 19.233 98 29 58 3 1968963904 8547296 9633664
15:30:28 33481 8192000000 10929075808 11392237568 2000238736 19.625 100 39 77 0 2024279184 12730912 11309536
15:32:02 45827 8192000000 10941623584 11403907072 2004573792 19.659 100 39 78 0 2034066576 17446416 12046368
15:34:34 52239 8192000000 10895685264 11378880512 1941702816 19.160 98 13 26 2 1957436496 12190416 3543264
15:37:34 38444 8192000000 10897559744 11379273728 1960300096 19.308 99 7 14 1 1972919344 11201776 1417472
15:39:16 25219 8192000000 10920489984 11371569152 1966658320 19.359 99 27 54 3 1979558912 5448864 7451728
15:40:43 21901 8192000000 10903605536 11373748224 1959436960 19.302 99 18 36 0 1973393056 9154032 4802064
15:42:06 79462 8192000000 10900466224 11378831360 1918874016 18.978 97 19 38 1 1941879616 17441232 5564368
15:44:28 38709 8192000000 10858042800 11333320704 1909738912 18.905 98 13 26 1 1921685360 8084272 3862176
15:46:07 34057 8192000000 10884659664 11329675264 1925695440 19.032 99 28 54 1 1941607296 7586288 8325568
15:47:46 70857 8192000000 10873934400 11330838528 1883002160 18.689 97 22 44 0 1900173056 11268160 5902736
15:49:57 83902 8192000000 10886212128 11369480192 1909123472 18.900 98 13 26 0 1931220256 18515072 3581712
15:52:22 94323 8192000000 10901339760 11370655744 1948855216 19.217 99 21 42 1 1972280736 18109376 5316144
15:54:58 65396 8192000000 10898425056 11361083392 1904360720 18.861 97 29 58 0 1932251024 18502320 9387984
15:57:06 37287 8192000000 10896860512 11370385408 1958478880 19.294 100 17 34 0 1980162528 17229712 4453936
15:59:15 63460 8192000000 10887426288 11356422144 1902937856 18.850 97 11 22 1 1916562064 10659360 2964848
16:01:22 32857 8192000000 10858464944 11336273920 1906611696 18.879 97 13 26 0 1916957968 6989360 3356912
16:03:48 32368 8192000000 10864674816 11338047488 1929688368 19.064 99 20 40 0 1941267760 6159264 5420128
16:05:21 80400 8192000000 10881751952 11352788992 1941588640 19.159 99 17 34 0 1957375152 10545424 5241088
16:07:43 57464 8192000000 10888916320 11365507072 1938626912 19.136 98 20 40 0 1955040336 10156160 6257264
16:09:40 70992 8192000000 10906839392 11398078464 1973889728 19.416 99 14 28 1 1993217536 15339664 3988144
16:11:57 44586 8192000000 10931370960 11395362816 1975292288 19.427 99 37 74 2 1995821104 9839440 10689376
16:13:49 59693 8192000000 10909293280 11376603136 1946483120 19.198 98 24 48 1 1965397664 12056864 6857680
16:15:49 122506 8192000000 10897243680 11370774528 1853595712 18.451 93 20 40 0 1874302656 15077440 5629504
16:19:07 89832 8192000000 10887354384 11351494656 1892102864 18.763 96 6 12 0 1905229648 11443184 1683600
16:21:37 74107 8192000000 10890121360 11358109696 1867565696 18.565 95 18 36 0 1885426912 12623040 5238176
16:23:54 101895 8192000000 10870217536 11332595712 1895272112 18.788 97 24 48 0 1913914960 12234192 6408656
16:27:12 53610 8192000000 10863908768 11357097984 1900405504 18.830 97 7 14 0 1910890080 8084880 2399696
16:29:06 99719 8192000000 10849118976 11301429248 1814769632 18.135 93 13 26 0 1828333360 9834672 3729056
16:32:37 49029 8192000000 10841920592 11326070784 1910084320 18.907 99 9 18 1 1919651072 7427296 2139456
16:34:28 55534 8192000000 10865021120 11333431296 1884774128 18.704 97 9 18 0 1899744384 11741712 3228544
16:36:33 60094 8192000000 10864775504 11331883008 1865601248 18.549 96 20 40 1 1886184864 15233616 5350000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-08-2021 16:39:17 to 07-08-2021 16:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 123 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 07-08-2021 16:39:33 to 07-08-2021 16:39:35.

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          243096
MON$OLDEST_ACTIVE               245391
MON$OLDEST_SNAPSHOT             245391
MON$NEXT_TRANSACTION            245391
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                       2052832
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:334: 
:335: mscs-test-161.budzdorov.ru    Sat Aug  7 16:37:58 2021
:336:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:337: 
:338: 
:339: mscs-test-161.budzdorov.ru    Sat Aug  7 16:37:59 2021
:340:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:341: 
--- end of diff output ---

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

07.08.21 16:39:36. 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-08-07 16:39:38 No severe PSQL-related problems occured
07.08.21 16:39:40. End of report.