Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.11.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 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 16777588 kB
MemAvailable 31532392 kB
Buffers 0 kB
Cached 14871492 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-24 16:37:54
DTS_END                         2021-11-24 16:37:54.5930
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/46144
TRN_ID                          233869
ATT_ID                          640
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: 9408.44 22442 1129013
customer order: creation 166.33 400 19960
customer order: refuse 14.31 321 1717
order to supplier: creation 44.38 1269 5326
order to supplier: removal 3.61 100140 433
invoice (draft): creation 45.78 3237 5494
invoice (draft): removal 54.20 708 6504
invoice accept: apply 40.75 17735 4890
invoice accept: cancel 49.72 736 5966
customer reserve: creation 3824.65 174 458958
customer reserve: removal 4868.65 59 584238
realization accept: apply 82.19 162 9863
realization accept: cancel 87.89 109 10547
payment from customer: creation 57.54 96 6905
payment from customer: removal 5.53 88 664
payment to supplier: creation 48.03 105 5764
payment to supplier: removal 3.27 84 392
service: total inventory turnovers 4.25 1173 510
service: total monetary turnovers 6.40 17 768
service: aggregate perf. data 0.92 21569 110
service: refresh index statistics 0.03 300657 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-11-2021 16:39:09 to 24-11-2021 16:39:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 530
TEST_TIME 2 1072
TEST_TIME 3 2271
TEST_TIME 4 3081
TEST_TIME 5 3343
TEST_TIME 6 3562
TEST_TIME 7 3471
TEST_TIME 8 3714
TEST_TIME 9 4217
TEST_TIME 10 4446
TEST_TIME 11 5034
TEST_TIME 12 5343
TEST_TIME 13 5508
TEST_TIME 14 5759
TEST_TIME 15 5696
TEST_TIME 16 6122
TEST_TIME 17 6464
TEST_TIME 18 6921
TEST_TIME 19 7430
TEST_TIME 20 7768
TEST_TIME 21 8008
TEST_TIME 22 7962
TEST_TIME 23 8321
TEST_TIME 24 8682
TEST_TIME 25 9392
TEST_TIME 26 10385
TEST_TIME 27 10268
TEST_TIME 28 10294
TEST_TIME 29 10622
TEST_TIME 30 10453
TEST_TIME 31 10332
TEST_TIME 32 10138
TEST_TIME 33 10002
TEST_TIME 34 10127
TEST_TIME 35 10121
TEST_TIME 36 9956
TEST_TIME 37 10174
TEST_TIME 38 10114
TEST_TIME 39 10107
TEST_TIME 40 10027
TEST_TIME 41 10108
TEST_TIME 42 9982
TEST_TIME 43 9957
TEST_TIME 44 9890
TEST_TIME 45 9771
TEST_TIME 46 9689
TEST_TIME 47 9702
TEST_TIME 48 9668
TEST_TIME 49 9502
TEST_TIME 50 9429
TEST_TIME 51 9508
TEST_TIME 52 9493
TEST_TIME 53 9442
TEST_TIME 54 9458
TEST_TIME 55 9565
TEST_TIME 56 9504
TEST_TIME 57 9631
TEST_TIME 58 9718
TEST_TIME 59 9701
TEST_TIME 60 9780
TEST_TIME 61 9746
TEST_TIME 62 9897
TEST_TIME 63 9903
TEST_TIME 64 9893
TEST_TIME 65 9763
TEST_TIME 66 9777
TEST_TIME 67 9690
TEST_TIME 68 9693
TEST_TIME 69 9643
TEST_TIME 70 9586
TEST_TIME 71 9522
TEST_TIME 72 9607
TEST_TIME 73 9560
TEST_TIME 74 9549
TEST_TIME 75 9594
TEST_TIME 76 9593
TEST_TIME 77 9581
TEST_TIME 78 9620
TEST_TIME 79 9667
TEST_TIME 80 9735
TEST_TIME 81 9718
TEST_TIME 82 9717
TEST_TIME 83 9729
TEST_TIME 84 9739
TEST_TIME 85 9651
TEST_TIME 86 9564
TEST_TIME 87 9563
TEST_TIME 88 9621
TEST_TIME 89 9564
TEST_TIME 90 9527
TEST_TIME 91 9491
TEST_TIME 92 9469
TEST_TIME 93 9496
TEST_TIME 94 9475
TEST_TIME 95 9547
TEST_TIME 96 9542
TEST_TIME 97 9502
TEST_TIME 98 9587
TEST_TIME 99 9572
TEST_TIME 100 9556
TEST_TIME 101 9562
TEST_TIME 102 9548
TEST_TIME 103 9604
TEST_TIME 104 9598
TEST_TIME 105 9674
TEST_TIME 106 9688
TEST_TIME 107 9683
TEST_TIME 108 9594
TEST_TIME 109 9590
TEST_TIME 110 9556
TEST_TIME 111 9577
TEST_TIME 112 9546
TEST_TIME 113 9503
TEST_TIME 114 9469
TEST_TIME 115 9440
TEST_TIME 116 9430
TEST_TIME 117 9402
TEST_TIME 118 9420
TEST_TIME 119 9416
TEST_TIME 120 9409
TEST_TIME 121 9331

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-11-2021 16:39:13 to 24-11-2021 16:39:16.

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 1555041 1555041 0 0.00 0 24404 6 0 0
doc_list_biud 1555041 1555041 0 0.00 0 31284 28 0 0
sp_add_invoice_to_stock 4981 4890 91 1.82 36 187989 17736 0 91
sp_cancel_adding_invoice 5966 5966 0 0.00 5 67738 736 0 0
sp_cancel_client_order 1717 1717 0 0.00 4 10806 321 0 0
sp_cancel_customer_reserve 584238 584238 0 0.00 1 31289 60 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 2 8034 89 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 3774 85 0 0
sp_cancel_supplier_invoice 6510 6504 6 0.09 2 12580 708 0 6
sp_cancel_supplier_order 433 433 0 0.00 6 1065112 100140 0 0
sp_cancel_write_off 10547 10547 0 0.00 1 12168 110 0 0
sp_client_order 19960 19960 0 0.00 3 31811 400 0 0
sp_customer_reserve 458958 458958 0 0.00 0 61773 175 0 0
sp_fill_shopping_cart 30762 30762 0 0.00 1 14855 33 0 0
sp_fill_shopping_cart_clo_res 458956 458956 0 0.00 0 32475 15 0 0
sp_get_clo_for_invoice 4888 4888 0 0.00 0 38915 312 0 0
sp_kill_cost_storno 17569 17569 0 0.00 0 24401 57 0 0
sp_kill_qstorno_ret_qs2qd 1185960 1185960 0 0.00 0 31273 32 0 0
sp_kill_qty_storno 624156 624156 0 0.00 0 31283 68 0 0
sp_lock_selected_doc 20466 20466 0 0.00 0 4923 4 0 0
sp_make_cost_storno 27420 27420 0 0.00 0 17687 45 0 0
sp_multiply_rows_for_pdistr 27420 27420 0 0.00 0 9433 25 0 0
sp_multiply_rows_for_qdistr 19960 19960 0 0.00 0 10852 169 0 0
sp_pay_from_customer 6905 6905 0 0.00 4 16212 96 0 0
sp_pay_to_supplier 5764 5764 0 0.00 4 17910 106 0 0
sp_payment_common 12669 12669 0 0.00 1 9020 23 0 0
sp_qd_handle_on_cancel_clo 1717 1717 0 0.00 0 10800 294 0 0
sp_qd_handle_on_invoice_upd_sts 10854 10854 0 0.00 0 20495 310 0 0
sp_qd_handle_on_reserve_upd_sts 9863 9863 0 0.00 0 12413 51 0 0
sp_reserve_write_off 9863 9863 0 0.00 6 17716 163 0 0
sp_supplier_invoice 5494 5494 0 0.00 6 93825 3237 0 0
sp_supplier_order 5326 5326 0 0.00 5 62531 1270 0 0
srv_aggregate_perf_data 110 110 0 0.00 2568 613482 21570 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1471 144106 43545 0 0
srv_find_qd_qs_mism 1082745 1082745 0 0.00 0 12587 5 0 0
srv_make_invnt_saldo 510 510 0 0.00 35 45122 1173 0 0
srv_make_money_saldo 768 768 0 0.00 1 686 18 0 0
srv_recalc_idx_stat 4 4 0 0.00 165570 468447 300657 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4890 4890 0 0.00 0 14089 152 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 2221 78 0 0
t$perf-norm:sp_cancel_client_order 1717 1717 0 0.00 0 4721 14 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 5535 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 15675 50 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 5318 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 339 339 0 0.00 0 1287 17 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 25675 1741 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 4662 11 0 0
t$perf-norm:sp_client_order 19960 19960 0 0.00 0 16710 19 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 2181 8 0 0
t$perf-norm:sp_pay_from_customer 6905 6905 0 0.00 0 17522 18 0 0
t$perf-norm:sp_pay_to_supplier 5764 5764 0 0.00 0 9135 17 0 0
t$perf-norm:sp_reserve_write_off 9863 9863 0 0.00 0 8757 17 0 0
t$perf-norm:sp_supplier_invoice 5494 5494 0 0.00 0 7187 21 0 0
t$perf-norm:sp_supplier_order 5326 5326 0 0.00 0 15363 19 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 135 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 20 1 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 858 18 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 4884 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 120 32 0 0
v_add_invoice_to_stock 9958 9776 182 1.82 0 6286 22 0 182
v_all_customers 36866 36866 0 0.00 0 13 0 0 0
v_all_suppliers 30116 30116 0 0.00 0 84 1 0 0
v_all_wares 39920 39920 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 1268 8 0 0
v_cancel_client_order 3434 3434 0 0.00 0 3152 6 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 3284 11 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 3913 9 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 2217 9 0 0
v_cancel_supplier_invoice 690 678 12 1.73 0 2716 17 0 12
v_cancel_supplier_order 866 866 0 0.00 0 5287 11 0 0
v_cancel_write_off 2052 2052 0 0.00 0 4843 18 0 0
v_max_id_clo_ord 5326 5326 0 0.00 0 377 1 0 0
v_max_id_clo_res 967 967 0 0.00 0 2961 16 0 0
v_max_id_ord_sup 5476 5476 0 0.00 0 5067 5 0 0
v_max_non_paid_invoice 1508 1508 0 0.00 0 435 2 0 0
v_max_non_paid_realizn 3526 3526 0 0.00 0 4347 5 0 0
v_min_id_clo_ord 5326 5326 0 0.00 0 2851 5 0 0
v_min_id_clo_res 967 967 0 0.00 0 603 3 0 0
v_min_id_ord_sup 5476 5476 0 0.00 0 8448 8 0 0
v_min_non_paid_invoice 5764 5764 0 0.00 0 4482 13 0 0
v_min_non_paid_realizn 6905 6905 0 0.00 0 9015 20 0 0
v_our_firm 9812 9812 0 0.00 0 10 1 0 0
v_reserve_write_off 19726 19726 0 0.00 0 4995 8 0 0
x_lock_depdocs_on_canc_invoice 12131 12131 0 0.00 0 16683 95 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 8540 298 0 0
x_make_qstorno_1000_1200 5326 5326 0 0.00 2 62524 1200 0 0
x_make_qstorno_1200_2000 5494 5494 0 0.00 2 93806 3119 0 0
x_make_qstorno_2100_3300 458958 458958 0 0.00 0 60498 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-11-2021 16:39:17 to 24-11-2021 16:39:21.

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:58 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:08:58 40362 8192000000 9919113696 10235674624 993453872 10.815 100 90 180 0 1022942080 6529696 22958512
14:10:49 16550 8192000000 9964584064 10290892800 1048237088 11.344 100 91 182 0 1080300736 9100016 22963632
14:12:14 9040 8192000000 9998581872 10335776768 1086300048 11.707 100 82 164 0 1116702512 10466064 19936400
14:13:26 12366 8192000000 10038867152 10387304448 1119289248 12.020 100 77 154 0 1149247472 12042624 17915600
14:14:44 10398 8192000000 10074818464 10436087808 1166047632 12.460 100 83 164 2 1198043072 13046432 18949008
14:16:00 6043 8192000000 10125895584 10497347584 1216449632 12.929 100 75 149 1 1244949648 11933120 16566896
14:17:09 7123 8192000000 10150846384 10528944128 1238464256 13.132 100 75 150 0 1267764400 13137344 16162800
14:18:18 2072 8192000000 10197568880 10579914752 1281421456 13.526 100 84 167 3 1313643232 14043312 18178464
14:19:23 1667 8192000000 10215360496 10603479040 1299253040 13.688 100 92 184 1 1334428288 13758208 21417040
14:20:26 1566 8192000000 10213888768 10612424704 1304854128 13.739 100 67 134 1 1331223248 11248976 15120144
14:21:29 6378 8192000000 10202198400 10615177216 1311971072 13.804 100 26 52 0 1325030640 7355024 5704544
14:22:36 19720 8192000000 10211423040 10623606784 1319915440 13.876 100 30 59 0 1332693024 5460784 7316800
14:23:59 13287 8192000000 10201372432 10626441216 1320259040 13.879 100 21 42 2 1330585088 5207040 5119008
14:25:13 6820 8192000000 10222427536 10641817600 1331552592 13.981 100 22 44 1 1342474208 6526912 4394704
14:26:21 9675 8192000000 10226268384 10645970944 1318826736 13.866 99 19 38 2 1328677168 6030672 3819760
14:27:35 47365 8192000000 10212688352 10629570560 1315864320 13.839 99 30 60 0 1328838416 5142064 7832032
14:29:25 5971 8192000000 10197298832 10605072384 1266990864 13.394 96 35 71 0 1281374512 5122352 9261296
14:30:32 7856 8192000000 10166947552 10575958016 1259811232 13.328 98 28 50 2 1271793472 6501600 5480640
14:31:40 10484 8192000000 10141850528 10542104576 1208266048 12.853 96 18 36 0 1217836112 5117440 4452624
14:32:52 40148 8192000000 10133464032 10534445056 1190033296 12.684 94 25 50 1 1201483328 5610896 5839136
14:34:32 5165 8192000000 10171968624 10578178048 1277215616 13.488 100 40 80 0 1292850736 6589120 9046000
14:35:39 7465 8192000000 10187421008 10600173568 1292504816 13.627 100 18 36 2 1301615824 4890320 4220688
14:36:53 16611 8192000000 10202464608 10615877632 1305273904 13.743 100 12 22 4 1312972464 4984320 2714240
14:38:13 59196 8192000000 10242665008 10650423296 1331601808 13.982 100 44 88 0 1348182336 6513888 10066640
14:40:17 22391 8192000000 10254611536 10668765184 1347099104 14.121 100 49 98 2 1368237376 10438752 10699520
14:41:43 25131 8192000000 10288208720 10706329600 1377684352 14.396 100 43 86 0 1402863376 15324464 9854560
14:43:21 25238 8192000000 10311712880 10735288320 1394626992 14.547 100 41 82 1 1423424768 18496768 10301008
14:45:00 17847 8192000000 10325281280 10750066688 1401467312 14.608 100 54 108 1 1440152768 25167824 13517632
14:46:24 11645 8192000000 10337719744 10759442432 1406932320 14.657 100 54 108 0 1449338000 28969280 13436400
14:47:41 8888 8192000000 10351915424 10771853312 1412698848 14.708 100 62 121 2 1458836560 30871792 15265920
14:48:59 4188 8192000000 10353007168 10777591808 1404446720 14.635 99 68 137 0 1454714016 32890752 17376544
14:50:07 5777 8192000000 10382436240 10804338688 1431833104 14.877 100 74 147 2 1487189056 37495888 17860064
14:51:16 1943 8192000000 10394483520 10811265024 1431536608 14.875 100 79 156 1 1491245824 40206304 19502912
14:52:26 8608 8192000000 10399562832 10824736768 1443792688 14.983 100 71 142 2 1507050384 45054512 18203184
14:53:56 5808 8192000000 10412835488 10835894272 1451696480 15.053 100 79 158 0 1522997344 50724208 20576656
14:55:06 1470 8192000000 10412645840 10837344256 1442318096 14.970 100 76 151 1 1517215216 55124256 19772864
14:56:12 11807 8192000000 10377817296 10809720832 1418538064 14.760 99 51 102 3 1485375136 53301504 13535568
14:57:25 7459 8192000000 10375774960 10820538368 1429109648 14.853 100 39 78 1 1484172912 44053184 11010080
14:58:38 28190 8192000000 10340787232 10779217920 1344137264 14.095 95 25 50 2 1394961680 43768512 7055904
15:00:08 24528 8192000000 10349268208 10794774528 1396229328 14.561 100 26 52 1 1466144208 62607840 7307040
15:01:44 27938 8192000000 10313592736 10761408512 1360792240 14.244 99 21 42 1 1422296848 55228576 6276032
15:03:18 29548 8192000000 10260844672 10722840576 1346790896 14.119 99 16 30 3 1375949264 25298912 3859456
15:04:48 24343 8192000000 10261832896 10709192704 1336104336 14.022 98 14 28 0 1353911888 14295568 3511984
15:06:16 49882 8192000000 10250413088 10704564224 1311636384 13.801 97 10 20 0 1330498240 16551360 2310496
15:08:20 126371 8192000000 10272611040 10707091456 1330014832 13.967 98 34 68 1 1352127408 14127984 7984592
15:12:12 41868 8192000000 10266060864 10712711168 1349225808 14.141 99 31 62 2 1374128896 17879424 7023664
15:14:06 37048 8192000000 10252558896 10712276992 1328375904 13.952 98 12 24 0 1346236160 14927392 2932864
15:15:48 38275 8192000000 10258074560 10698428416 1339508016 14.053 99 32 65 2 1360592640 13705328 7379296
15:17:26 12736 8192000000 10289537520 10718109696 1369311008 14.321 100 62 124 2 1393476864 10099872 14065984
15:18:42 23330 8192000000 10257091984 10712059904 1335956208 14.021 98 11 22 0 1350550496 12221824 2372464
15:21:05 50251 8192000000 10255903472 10704941056 1359804176 14.236 100 11 22 0 1370067760 7465872 2797712
15:23:31 28568 8192000000 10266659328 10722443264 1368646112 14.315 100 15 30 0 1381684624 9548304 3490208
15:25:01 43796 8192000000 10268403520 10725412864 1350065616 14.148 98 12 24 0 1364593152 11961264 2566272
15:26:58 58543 8192000000 10288913040 10749730816 1385601568 14.467 100 10 20 0 1400717472 12904048 2211856
15:28:57 67113 8192000000 10291037328 10758569984 1375032224 14.372 99 8 16 0 1392877696 16124272 1721200
15:31:07 27073 8192000000 10294731104 10744848384 1388953408 14.497 100 28 56 2 1403204704 7924448 6326848
15:32:42 45144 8192000000 10322971920 10771755008 1399298016 14.589 99 25 50 2 1416017216 11311488 5407712
15:34:38 55304 8192000000 10309281408 10768949248 1406415824 14.652 100 21 42 1 1423595408 12249424 4930160
15:36:35 36096 8192000000 10316874928 10769776640 1405250208 14.642 100 37 74 0 1424605952 11128448 8227296
15:38:15 22357 8192000000 10317454416 10765447168 1391930896 14.523 99 35 69 1 1410061792 10599360 7531536
15:39:43 98491 8192000000 10303826912 10750062592 1334901824 14.011 96 14 28 1 1357845376 19597408 3346144
15:42:21 82980 8192000000 10288616352 10739851264 1348818512 14.137 97 44 88 1 1369616720 10989584 9808624
15:45:03 50334 8192000000 10256909008 10709340160 1341396816 14.070 97 17 34 0 1355627040 10633216 3597008
15:46:54 75881 8192000000 10274810960 10722947072 1307270672 13.761 94 13 26 0 1323598912 13160192 3168048
15:49:14 89786 8192000000 10256604032 10727522304 1317696976 13.856 96 8 16 0 1338341344 18804896 1839472
15:51:51 45452 8192000000 10268953024 10719641600 1342542368 14.080 98 28 56 0 1359433056 10385296 6505392
15:53:46 29013 8192000000 10268965488 10711662592 1349350848 14.142 99 40 80 2 1371385104 12624528 9409728
15:55:20 59377 8192000000 10273258384 10722476032 1334639376 14.009 97 9 18 0 1352524816 15753456 2131984
15:57:21 78481 8192000000 10251527904 10689712128 1284529328 13.554 94 21 42 0 1305589264 16397248 4662688
15:59:41 30493 8192000000 10287134256 10719330304 1354222736 14.185 99 40 81 0 1371782208 8923664 8635808
16:01:16 50777 8192000000 10298787168 10732978176 1347545424 14.125 98 13 27 0 1366251360 15571904 3134032
16:03:14 110219 8192000000 10297412912 10754011136 1381512320 14.430 100 33 66 0 1415481024 26094928 7873776
16:06:23 28644 8192000000 10250390720 10707910656 1333514784 13.999 97 7 14 1 1341105504 6395568 1195152
16:08:02 59724 8192000000 10261577680 10713186304 1330353152 13.970 96 11 23 0 1338061632 5442272 2266208
16:10:50 54610 8192000000 10279422704 10729263104 1349847936 14.146 98 9 18 1 1363137920 10991168 2298816
16:12:47 48465 8192000000 10265985792 10702569472 1293134272 13.633 94 27 54 1 1307015376 7527216 6353888
16:14:37 33140 8192000000 10284222160 10731147264 1365221296 14.284 99 33 66 0 1382240704 9558880 7460528
16:16:12 47834 8192000000 10303095040 10744803328 1373919008 14.362 99 15 30 1 1386011616 9102144 2990464
16:18:02 51357 8192000000 10287519008 10736803840 1360094320 14.238 98 20 40 0 1376014400 10964016 4956064
16:19:58 51104 8192000000 10301186352 10763165696 1368456304 14.313 98 20 40 0 1387270960 14061344 4753312
16:22:01 90633 8192000000 10298424000 10759692288 1362084000 14.256 98 12 24 0 1380203456 15370304 2749152
16:24:38 144105 8192000000 10293223616 10748534784 1376933040 14.389 99 16 32 0 1391409680 10808352 3668288
16:28:14 73587 8192000000 10283477536 10754596864 1364924096 14.282 98 13 26 0 1377085792 9223296 2938400
16:30:31 51239 8192000000 10292835296 10743881728 1380406640 14.420 99 16 32 0 1391527376 7235328 3885408
16:32:26 67998 8192000000 10279361984 10747396096 1370574720 14.332 98 14 28 0 1383609168 9344624 3689824
16:35:56 35304 8192000000 10284754288 10751135744 1384431936 14.456 100 12 24 0 1401244000 14191440 2620624
16:37:31 32061 8192000000 9294531680 9580445696 451654480 5.225 33 8 13 2 456764144 3670992 1438672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-11-2021 16:39:23 to 24-11-2021 16:39:38.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-11-2021 16:39:39 to 24-11-2021 16:39:41.

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          234222
MON$OLDEST_ACTIVE               234223
MON$OLDEST_SNAPSHOT             234223
MON$NEXT_TRANSACTION            234223
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2026272
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:282: 
:283: mscs-test-161.budzdorov.ru    Wed Nov 24 16:38:12 2021
:284:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:285: 
--- end of diff output ---

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

24.11.21 16:39:42. 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-24 16:39:44 No severe PSQL-related problems occured
24.11.21 16:39:45. End of report.