Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.11.2021 16:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16773840 kB
MemAvailable 31623444 kB
Buffers 0 kB
Cached 14902636 kB
SwapCached 0 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-12 16:37:57
DTS_END                         2021-11-12 16:37:57.1540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55588
TRN_ID                          247652
ATT_ID                          709
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: 9347.48 22725 1121698
customer order: creation 181.21 354 21745
customer order: refuse 15.78 392 1893
order to supplier: creation 48.71 1285 5845
order to supplier: removal 3.35 106346 402
invoice (draft): creation 47.95 3460 5754
invoice (draft): removal 51.20 889 6144
invoice accept: apply 43.95 18217 5274
invoice accept: cancel 46.26 844 5551
customer reserve: creation 4027.58 182 483310
customer reserve: removal 4572.14 61 548657
realization accept: apply 88.71 200 10645
realization accept: cancel 82.14 112 9857
payment from customer: creation 62.27 128 7472
payment from customer: removal 6.66 94 799
payment to supplier: creation 52.35 131 6282
payment to supplier: removal 3.83 111 460
service: total inventory turnovers 5.20 1200 624
service: total monetary turnovers 7.17 55 860
service: aggregate perf. data 1.00 17334 120
service: refresh index statistics 0.03 303095 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-11-2021 16:39:32 to 12-11-2021 16:39:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2468
TEST_TIME 2 4031
TEST_TIME 3 4126
TEST_TIME 4 4465
TEST_TIME 5 4522
TEST_TIME 6 4812
TEST_TIME 7 4806
TEST_TIME 8 5439
TEST_TIME 9 5593
TEST_TIME 10 5561
TEST_TIME 11 5917
TEST_TIME 12 6211
TEST_TIME 13 7078
TEST_TIME 14 7386
TEST_TIME 15 7326
TEST_TIME 16 7535
TEST_TIME 17 8091
TEST_TIME 18 8434
TEST_TIME 19 8395
TEST_TIME 20 8254
TEST_TIME 21 8181
TEST_TIME 22 8409
TEST_TIME 23 8443
TEST_TIME 24 8630
TEST_TIME 25 9118
TEST_TIME 26 9174
TEST_TIME 27 9427
TEST_TIME 28 10078
TEST_TIME 29 10712
TEST_TIME 30 10626
TEST_TIME 31 10724
TEST_TIME 32 10682
TEST_TIME 33 10577
TEST_TIME 34 10424
TEST_TIME 35 10384
TEST_TIME 36 10186
TEST_TIME 37 10258
TEST_TIME 38 10147
TEST_TIME 39 10125
TEST_TIME 40 10088
TEST_TIME 41 9916
TEST_TIME 42 9924
TEST_TIME 43 9945
TEST_TIME 44 9904
TEST_TIME 45 9926
TEST_TIME 46 9914
TEST_TIME 47 9797
TEST_TIME 48 9690
TEST_TIME 49 9637
TEST_TIME 50 9605
TEST_TIME 51 9530
TEST_TIME 52 9597
TEST_TIME 53 9588
TEST_TIME 54 9538
TEST_TIME 55 9534
TEST_TIME 56 9486
TEST_TIME 57 9418
TEST_TIME 58 9456
TEST_TIME 59 9551
TEST_TIME 60 9676
TEST_TIME 61 9674
TEST_TIME 62 9736
TEST_TIME 63 9780
TEST_TIME 64 9844
TEST_TIME 65 9817
TEST_TIME 66 9823
TEST_TIME 67 9815
TEST_TIME 68 9812
TEST_TIME 69 9808
TEST_TIME 70 9689
TEST_TIME 71 9725
TEST_TIME 72 9643
TEST_TIME 73 9627
TEST_TIME 74 9581
TEST_TIME 75 9532
TEST_TIME 76 9528
TEST_TIME 77 9421
TEST_TIME 78 9373
TEST_TIME 79 9478
TEST_TIME 80 9451
TEST_TIME 81 9440
TEST_TIME 82 9448
TEST_TIME 83 9477
TEST_TIME 84 9576
TEST_TIME 85 9561
TEST_TIME 86 9562
TEST_TIME 87 9586
TEST_TIME 88 9632
TEST_TIME 89 9653
TEST_TIME 90 9687
TEST_TIME 91 9723
TEST_TIME 92 9621
TEST_TIME 93 9543
TEST_TIME 94 9498
TEST_TIME 95 9526
TEST_TIME 96 9490
TEST_TIME 97 9443
TEST_TIME 98 9472
TEST_TIME 99 9439
TEST_TIME 100 9434
TEST_TIME 101 9505
TEST_TIME 102 9509
TEST_TIME 103 9498
TEST_TIME 104 9479
TEST_TIME 105 9473
TEST_TIME 106 9479
TEST_TIME 107 9500
TEST_TIME 108 9476
TEST_TIME 109 9540
TEST_TIME 110 9559
TEST_TIME 111 9509
TEST_TIME 112 9450
TEST_TIME 113 9461
TEST_TIME 114 9418
TEST_TIME 115 9421
TEST_TIME 116 9401
TEST_TIME 117 9364
TEST_TIME 118 9335
TEST_TIME 119 9309
TEST_TIME 120 9349

Performance per minute, chart


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

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1569029 1569029 0 0.00 0 20703 6 0 0
doc_list_biud 1569029 1569029 0 0.00 0 23058 28 0 0
sp_add_invoice_to_stock 5380 5274 106 1.97 20 216805 18217 0 106
sp_cancel_adding_invoice 5551 5551 0 0.00 4 64997 844 0 0
sp_cancel_client_order 1893 1893 0 0.00 4 10772 393 0 0
sp_cancel_customer_reserve 548657 548657 0 0.00 0 23061 62 0 0
sp_cancel_pay_from_customer 799 799 0 0.00 2 4527 95 0 0
sp_cancel_pay_to_supplier 460 460 0 0.00 3 6548 111 0 0
sp_cancel_supplier_invoice 6149 6144 5 0.08 1 14593 890 0 5
sp_cancel_supplier_order 402 402 0 0.00 5 1029072 106346 0 0
sp_cancel_write_off 9857 9857 0 0.00 1 7811 112 0 0
sp_client_order 21745 21745 0 0.00 3 17980 355 0 0
sp_customer_reserve 483310 483310 0 0.00 0 103796 182 0 0
sp_fill_shopping_cart 33325 33325 0 0.00 1 24384 38 0 0
sp_fill_shopping_cart_clo_res 483307 483307 0 0.00 0 15920 13 0 0
sp_get_clo_for_invoice 5270 5270 0 0.00 0 27233 296 0 0
sp_kill_cost_storno 16667 16667 0 0.00 0 8258 57 0 0
sp_kill_qstorno_ret_qs2qd 1113717 1113717 0 0.00 0 23056 34 0 0
sp_kill_qty_storno 588417 588417 0 0.00 0 23058 73 0 0
sp_lock_selected_doc 22025 22025 0 0.00 0 8908 6 0 0
sp_make_cost_storno 29667 29667 0 0.00 0 11257 60 0 0
sp_multiply_rows_for_pdistr 29667 29667 0 0.00 0 8398 32 0 0
sp_multiply_rows_for_qdistr 21745 21745 0 0.00 0 17324 152 0 0
sp_pay_from_customer 7472 7472 0 0.00 4 29071 129 0 0
sp_pay_to_supplier 6282 6282 0 0.00 4 12038 132 0 0
sp_payment_common 13754 13754 0 0.00 1 28972 25 0 0
sp_qd_handle_on_cancel_clo 1893 1893 0 0.00 0 10765 352 0 0
sp_qd_handle_on_invoice_upd_sts 10819 10819 0 0.00 0 16639 326 0 0
sp_qd_handle_on_reserve_upd_sts 10645 10645 0 0.00 0 19208 67 0 0
sp_reserve_write_off 10645 10645 0 0.00 6 19232 200 0 0
sp_supplier_invoice 5754 5754 0 0.00 6 82518 3461 0 0
sp_supplier_order 5845 5845 0 0.00 5 33078 1286 0 0
srv_aggregate_perf_data 120 120 0 0.00 2540 241419 17334 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1469 143939 43600 0 0
srv_find_qd_qs_mism 1071226 1071226 0 0.00 0 20694 5 0 0
srv_make_invnt_saldo 624 624 0 0.00 39 48978 1200 0 0
srv_make_money_saldo 860 860 0 0.00 0 23196 55 0 0
srv_recalc_idx_stat 4 4 0 0.00 199502 392195 303095 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5274 5274 0 0.00 0 12424 176 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 9348 121 0 0
t$perf-norm:sp_cancel_client_order 1893 1893 0 0.00 0 6826 17 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 3101 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 799 799 0 0.00 0 489 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 460 460 0 0.00 0 7597 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 2323 13 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 21893 1614 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 24494 29 0 0
t$perf-norm:sp_client_order 21745 21745 0 0.00 0 14883 18 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 4029 10 0 0
t$perf-norm:sp_pay_from_customer 7472 7472 0 0.00 0 13794 10 0 0
t$perf-norm:sp_pay_to_supplier 6282 6282 0 0.00 0 6009 9 0 0
t$perf-norm:sp_reserve_write_off 10645 10645 0 0.00 0 14943 16 0 0
t$perf-norm:sp_supplier_invoice 5754 5754 0 0.00 0 5138 19 0 0
t$perf-norm:sp_supplier_order 5845 5845 0 0.00 0 5087 19 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 3882 48 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 672 12 0 0
t$perf-norm:srv_make_invnt_saldo 624 624 0 0.00 0 4423 32 0 0
t$perf-norm:srv_make_money_saldo 860 860 0 0.00 0 9123 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 508 129 0 0
v_add_invoice_to_stock 10740 10528 212 1.97 0 8260 20 0 212
v_all_customers 39274 39274 0 0.00 0 128 1 0 0
v_all_suppliers 32376 32376 0 0.00 0 27 1 0 0
v_all_wares 43486 43486 0 0.00 0 193 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 174 5 0 0
v_cancel_client_order 3786 3786 0 0.00 0 7784 11 0 0
v_cancel_customer_prepayment 1598 1598 0 0.00 0 2277 13 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 856 5 0 0
v_cancel_payment_to_supplier 920 920 0 0.00 0 4975 18 0 0
v_cancel_supplier_invoice 884 874 10 1.13 0 4887 20 0 10
v_cancel_supplier_order 804 804 0 0.00 0 1761 8 0 0
v_cancel_write_off 1962 1962 0 0.00 0 2847 15 0 0
v_max_id_clo_ord 5844 5844 0 0.00 0 2944 2 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 1575 9 0 0
v_max_id_ord_sup 5738 5738 0 0.00 0 7115 5 0 0
v_max_non_paid_invoice 1676 1676 0 0.00 0 564 3 0 0
v_max_non_paid_realizn 4176 4176 0 0.00 0 1763 3 0 0
v_min_id_clo_ord 5844 5844 0 0.00 0 2387 6 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 1797 8 0 0
v_min_id_ord_sup 5738 5738 0 0.00 0 5413 7 0 0
v_min_non_paid_invoice 6282 6282 0 0.00 0 5973 15 0 0
v_min_non_paid_realizn 7472 7472 0 0.00 0 28969 23 0 0
v_our_firm 10804 10804 0 0.00 0 19 1 0 0
v_reserve_write_off 21288 21288 0 0.00 0 7825 10 0 0
x_lock_depdocs_on_canc_invoice 11258 11258 0 0.00 0 14816 125 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 9141 375 0 0
x_make_qstorno_1000_1200 5845 5845 0 0.00 1 33057 1204 0 0
x_make_qstorno_1200_2000 5754 5754 0 0.00 3 82510 3325 0 0
x_make_qstorno_2100_3300 483310 483310 0 0.00 0 103793 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-11-2021 16:39:40 to 12-11-2021 16:39:44.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:00 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:02 17075 8192000000 9887974416 10194489344 972428160 10.610 100 88 176 0 1001617408 5935008 23254240
14:10:21 27916 8192000000 9938252784 10258739200 1014073824 11.015 100 94 188 0 1046733728 8184368 24475536
14:11:50 15586 8192000000 9963049760 10295898112 1047810816 11.340 100 84 168 0 1078905952 9706848 21388288
14:13:12 7680 8192000000 10013964224 10355609600 1099641216 11.834 100 84 168 0 1131702944 11358896 20702832
14:14:29 9228 8192000000 10069586960 10427158528 1150512608 12.314 100 74 148 0 1179166912 11634000 17020304
14:15:40 5685 8192000000 10119748944 10488107008 1207354240 12.845 100 73 146 1 1235695600 12346512 15994848
14:16:47 3567 8192000000 10152934160 10529390592 1247713408 13.217 100 82 164 1 1277534256 11563504 18257344
14:17:53 1698 8192000000 10178974640 10560843776 1275402544 13.471 100 87 173 1 1306691408 12518304 18770560
14:18:56 1936 8192000000 10215195216 10597490688 1291551744 13.618 100 85 169 0 1323473696 13149696 18772256
14:19:59 6206 8192000000 10191692704 10597470208 1299980560 13.695 100 39 78 0 1317723856 9201696 8541600
14:21:06 8113 8192000000 10196901424 10611118080 1310411472 13.790 100 26 52 1 1322940160 6956416 5572272
14:22:17 80124 8192000000 10209996864 10633818112 1322746784 13.902 100 30 59 0 1334989024 5227984 7014256
14:24:38 9446 8192000000 10228275536 10646585344 1331827504 13.984 100 30 60 1 1344535056 5319376 7388176
14:25:49 8178 8192000000 10229355632 10655907840 1333979728 14.003 100 20 40 1 1348546752 9840144 4726880
14:26:59 21859 8192000000 10212618160 10622447616 1267154112 13.396 95 39 73 3 1280714368 5015984 8544272
14:28:28 18593 8192000000 10181893280 10600103936 1269036832 13.413 96 18 36 1 1278682528 5964720 3680976
14:29:47 3805 8192000000 10177118176 10582298624 1264527712 13.372 98 30 60 1 1277629792 6466400 6635680
14:30:57 5641 8192000000 10132127440 10525712384 1224144752 13.000 99 22 44 2 1236041312 5360912 6535648
14:32:03 71764 8192000000 10138009952 10546782208 1227448272 13.030 97 20 40 0 1237702416 5340400 4913744
14:34:15 6424 8192000000 10176166432 10582278144 1279532576 13.509 100 25 50 0 1291838672 6843616 5462480
14:35:22 7986 8192000000 10186926736 10603311104 1296810080 13.666 100 25 49 0 1308260704 6115424 5335200
14:36:37 10518 8192000000 10201722528 10619109376 1308396112 13.772 100 19 38 1 1318777536 6223136 4158288
14:37:59 54277 8192000000 10235410480 10647482368 1330463600 13.971 100 34 69 0 1344316864 6473584 7379680
14:39:58 26996 8192000000 10260988832 10678067200 1355707584 14.199 100 41 79 1 1373001392 8508272 8785536
14:41:45 26967 8192000000 10290575184 10706845696 1362486848 14.260 99 49 98 1 1386059952 12648432 10924672
14:43:28 3085 8192000000 10297928320 10716336128 1384061392 14.453 100 63 122 0 1413380176 13919760 15399024
14:44:34 8794 8192000000 10313255408 10734903296 1393705280 14.539 100 45 90 0 1424670688 20506288 10459120
14:45:47 17546 8192000000 10322734240 10753232896 1402880976 14.621 100 45 90 0 1438921792 24894256 11146560
14:47:15 6607 8192000000 10336210768 10755977216 1401932896 14.612 100 62 122 0 1440084160 24022080 14129184
14:48:31 5514 8192000000 10348324208 10771693568 1409555744 14.680 100 61 122 2 1454818656 29785600 15477312
14:49:42 1469 8192000000 10355063488 10765783040 1399524720 14.591 100 88 175 2 1455294688 35164960 20605008
14:50:46 9584 8192000000 10330103392 10742218752 1386089888 14.471 100 65 130 1 1431405776 30088944 15226944
14:52:00 4452 8192000000 10338967312 10752163840 1388592608 14.493 100 66 132 2 1441916816 37462368 15861840
14:53:09 2225 8192000000 10329736368 10740838400 1366221152 14.293 98 70 142 0 1427306672 43261424 17824096
14:54:15 5770 8192000000 10337396176 10749595648 1378260672 14.401 100 78 156 1 1438954928 41330784 19363472
14:55:25 4677 8192000000 10345079648 10755604480 1390041072 14.506 100 72 144 2 1442250272 33377728 18831472
14:56:36 3568 8192000000 10336949920 10749394944 1384877104 14.460 100 68 134 0 1438860688 36712864 17270720
14:57:42 1560 8192000000 10341756096 10757115904 1377800800 14.397 99 64 126 2 1432286416 37888768 16596848
14:58:50 7958 8192000000 10356722720 10771390464 1394639104 14.547 100 49 98 0 1455363728 46078208 14646416
15:00:07 3316 8192000000 10363324528 10773454848 1397662496 14.574 100 68 135 0 1461969424 46037216 18269712
15:01:14 14094 8192000000 10344130480 10766331904 1386854384 14.478 100 38 76 2 1445760976 47810128 11096464
15:02:33 19015 8192000000 10331934656 10772049920 1384497520 14.457 100 28 56 1 1438829008 46033184 8298304
15:04:05 26235 8192000000 10327079872 10762924032 1368138800 14.310 100 40 78 1 1427511184 48169120 11203264
15:05:37 21847 8192000000 10297598560 10740146176 1363745472 14.271 100 23 45 2 1409222336 39060832 6416032
15:07:26 56550 8192000000 10284210320 10735894528 1353403680 14.178 99 13 24 2 1375443904 18561648 3478576
15:09:25 55644 8192000000 10281640352 10726907904 1341600544 14.072 98 14 28 2 1358796912 13990928 3205440
15:11:27 50672 8192000000 10285071024 10730442752 1349102928 14.139 98 31 63 2 1367191936 11263312 6825696
15:13:19 46574 8192000000 10288950992 10739097600 1375729504 14.378 100 21 42 0 1392837632 12192688 4915440
15:15:17 20235 8192000000 10302711008 10736128000 1355454992 14.197 98 46 91 2 1373823840 7648496 10720352
15:16:43 26972 8192000000 10299814416 10736578560 1372531568 14.350 100 63 127 2 1397020704 9845440 14643696
15:18:12 56828 8192000000 10285741744 10726780928 1341918320 14.075 97 40 79 2 1362525888 11585984 9021584
15:20:13 34780 8192000000 10279127520 10723753984 1346527712 14.116 98 13 26 0 1362455568 12723136 3204720
15:21:59 39847 8192000000 10291489856 10742128640 1384331776 14.455 100 39 73 1 1402772064 10137568 8302720
15:23:40 86064 8192000000 10296040976 10753740800 1381728048 14.432 99 24 48 0 1396305232 9351792 5225392
15:26:10 57940 8192000000 10286807920 10747564032 1376608000 14.386 99 13 26 0 1389462816 9490976 3363840
15:28:38 74708 8192000000 10285065248 10749943808 1350175168 14.149 97 33 64 2 1374423296 17109408 7138720
15:31:05 85018 8192000000 10265131728 10739929088 1362488768 14.260 99 10 20 1 1381962064 17170064 2303232
15:33:31 41782 8192000000 10282896800 10744389632 1367233552 14.302 99 12 24 0 1383288240 13423088 2631600
15:35:14 25570 8192000000 10286286896 10740936704 1368612160 14.315 99 16 32 0 1389650800 17098704 3939936
15:36:44 70336 8192000000 10272481456 10740891648 1347930016 14.129 98 18 36 0 1371393808 18953024 4510768
15:39:23 143939 8192000000 10293169808 10746912768 1341247328 14.069 97 16 32 0 1367002704 22409776 3345600
15:42:52 26222 8192000000 10290156464 10721730560 1344098512 14.094 98 28 56 0 1358698896 7875648 6724736
15:44:18 17758 8192000000 10285283872 10724225024 1359002208 14.228 99 44 88 0 1378865376 10216128 9647040
15:45:39 57811 8192000000 10263318592 10717978624 1329571216 13.963 97 24 48 1 1345382560 10518464 5292880
15:47:41 87797 8192000000 10257071488 10710925312 1330753792 13.974 97 19 38 1 1343063408 8225136 4084480
15:50:19 74290 8192000000 10268457568 10723467264 1364409408 14.277 100 11 22 0 1377619248 10763424 2446416
15:52:38 71154 8192000000 10264957792 10719358976 1356590784 14.207 99 21 42 0 1376105840 13603328 5911728
15:56:07 53513 8192000000 10261886016 10732572672 1353151056 14.176 99 21 42 1 1375688224 17580672 4956496
15:58:09 49748 8192000000 10283211696 10732126208 1371369696 14.339 100 19 38 0 1386104048 10312448 4421904
16:00:02 53041 8192000000 10283550896 10737889280 1341627120 14.072 97 22 44 1 1361632704 14586288 5419296
16:01:56 51139 8192000000 10318234768 10758742016 1383305424 14.446 100 21 41 0 1401357328 13633648 4418256
16:03:48 67104 8192000000 10297131968 10755035136 1355507200 14.197 97 18 36 0 1376118672 16700320 3911152
16:05:56 89793 8192000000 10298798624 10765119488 1376986944 14.390 99 14 28 0 1399997840 19484192 3526704
16:08:26 128767 8192000000 10281143808 10732875776 1339910464 14.057 96 17 34 1 1354579520 10791120 3877936
16:11:42 50815 8192000000 10284681328 10759675904 1378989008 14.408 99 10 20 0 1398960864 17806304 2165552
16:14:26 44466 8192000000 10282484704 10740981760 1355819648 14.200 98 13 26 2 1373954256 15005856 3128752
16:16:10 60664 8192000000 10302231664 10760282112 1378225248 14.401 99 20 40 0 1398918448 15783280 4909920
16:18:34 73788 8192000000 10279187808 10740293632 1342615296 14.081 96 13 26 1 1362449600 16811424 3022880
16:21:00 25448 8192000000 10285434256 10725281792 1342413568 14.079 97 33 66 4 1357354000 7989168 6951264
16:22:30 49810 8192000000 10273286736 10723921920 1331970352 13.985 97 35 71 0 1351581024 11928112 7682560
16:24:27 26769 8192000000 10276167856 10723270656 1341958816 14.075 98 39 78 0 1362079168 11873504 8246848
16:25:56 84236 8192000000 10273723552 10740252672 1324365200 13.916 97 15 30 2 1353411168 25713664 3332304
16:28:24 142715 8192000000 10268803376 10722766848 1346279392 14.114 98 27 54 0 1363657120 10481616 6896112
16:31:51 2448 8192000000 10278054976 10715295744 1352385664 14.169 98 52 103 0 1371928368 8236848 11305856
16:32:54 55123 8192000000 10255525904 10714640384 1353490144 14.179 99 17 34 1 1365678608 7796912 4391552
16:34:50 45831 8192000000 10255974192 10705948672 1329244400 13.960 97 23 46 0 1343547216 8457120 5845696
16:36:38 94679 8192000000 9564437808 9918803968 700256688 7.874 52 5 9 1 710113392 9167696 689008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-11-2021 16:39:46 to 12-11-2021 16:40:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-11-2021 16:40:02 to 12-11-2021 16:40:04.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          247856
MON$OLDEST_ACTIVE               247857
MON$OLDEST_SNAPSHOT             247857
MON$NEXT_TRANSACTION            247857
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                       2037664
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:150: 
:151: mscs-test-161.budzdorov.ru    Fri Nov 12 16:38:16 2021
:152:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:153: 
:154: 
:155: mscs-test-161.budzdorov.ru    Fri Nov 12 16:38:17 2021
:156:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:157: 
--- end of diff output ---

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

12.11.21 16:40:05. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-11-12 16:40:07 No severe PSQL-related problems occured
12.11.21 16:40:08. End of report.