Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.12.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 16717460 kB
MemAvailable 31470848 kB
Buffers 0 kB
Cached 14918852 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-12-02 16:37:55
DTS_END                         2021-12-02 16:37:55.6040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59026
TRN_ID                          249373
ATT_ID                          692
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: 9290.45 20892 1114854
customer order: creation 183.95 371 22074
customer order: refuse 15.18 304 1821
order to supplier: creation 48.96 1298 5875
order to supplier: removal 3.39 95923 407
invoice (draft): creation 48.15 3196 5778
invoice (draft): removal 53.81 773 6457
invoice accept: apply 42.70 17670 5124
invoice accept: cancel 48.30 751 5796
customer reserve: creation 3897.76 178 467731
customer reserve: removal 4641.95 55 557034
realization accept: apply 89.33 175 10719
realization accept: cancel 81.31 114 9757
payment from customer: creation 62.13 108 7456
payment from customer: removal 5.90 77 708
payment to supplier: creation 51.03 117 6124
payment to supplier: removal 3.42 90 410
service: total inventory turnovers 5.33 851 640
service: total monetary turnovers 6.86 27 823
service: aggregate perf. data 0.97 17663 116
service: refresh index statistics 0.03 278099 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-12-2021 16:38:49 to 02-12-2021 16:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2419
TEST_TIME 2 3196
TEST_TIME 3 3511
TEST_TIME 4 4510
TEST_TIME 5 4397
TEST_TIME 6 4161
TEST_TIME 7 4901
TEST_TIME 8 4941
TEST_TIME 9 5362
TEST_TIME 10 5839
TEST_TIME 11 5976
TEST_TIME 12 6457
TEST_TIME 13 6332
TEST_TIME 14 6224
TEST_TIME 15 6275
TEST_TIME 16 6442
TEST_TIME 17 6682
TEST_TIME 18 7328
TEST_TIME 19 7424
TEST_TIME 20 7690
TEST_TIME 21 7839
TEST_TIME 22 8348
TEST_TIME 23 8669
TEST_TIME 24 8714
TEST_TIME 25 9555
TEST_TIME 26 10290
TEST_TIME 27 10520
TEST_TIME 28 10620
TEST_TIME 29 10347
TEST_TIME 30 10392
TEST_TIME 31 10219
TEST_TIME 32 10172
TEST_TIME 33 10071
TEST_TIME 34 9984
TEST_TIME 35 9940
TEST_TIME 36 9763
TEST_TIME 37 9815
TEST_TIME 38 9894
TEST_TIME 39 9790
TEST_TIME 40 9803
TEST_TIME 41 9898
TEST_TIME 42 9745
TEST_TIME 43 9645
TEST_TIME 44 9545
TEST_TIME 45 9675
TEST_TIME 46 9587
TEST_TIME 47 9633
TEST_TIME 48 9588
TEST_TIME 49 9624
TEST_TIME 50 9705
TEST_TIME 51 9673
TEST_TIME 52 9658
TEST_TIME 53 9787
TEST_TIME 54 9805
TEST_TIME 55 9774
TEST_TIME 56 9712
TEST_TIME 57 9786
TEST_TIME 58 9848
TEST_TIME 59 9798
TEST_TIME 60 9689
TEST_TIME 61 9616
TEST_TIME 62 9577
TEST_TIME 63 9525
TEST_TIME 64 9496
TEST_TIME 65 9403
TEST_TIME 66 9317
TEST_TIME 67 9264
TEST_TIME 68 9388
TEST_TIME 69 9385
TEST_TIME 70 9369
TEST_TIME 71 9390
TEST_TIME 72 9356
TEST_TIME 73 9410
TEST_TIME 74 9443
TEST_TIME 75 9431
TEST_TIME 76 9417
TEST_TIME 77 9377
TEST_TIME 78 9548
TEST_TIME 79 9559
TEST_TIME 80 9642
TEST_TIME 81 9647
TEST_TIME 82 9612
TEST_TIME 83 9512
TEST_TIME 84 9515
TEST_TIME 85 9475
TEST_TIME 86 9496
TEST_TIME 87 9428
TEST_TIME 88 9359
TEST_TIME 89 9321
TEST_TIME 90 9313
TEST_TIME 91 9336
TEST_TIME 92 9310
TEST_TIME 93 9319
TEST_TIME 94 9312
TEST_TIME 95 9316
TEST_TIME 96 9326
TEST_TIME 97 9331
TEST_TIME 98 9348
TEST_TIME 99 9349
TEST_TIME 100 9373
TEST_TIME 101 9374
TEST_TIME 102 9370
TEST_TIME 103 9366
TEST_TIME 104 9344
TEST_TIME 105 9399
TEST_TIME 106 9417
TEST_TIME 107 9409
TEST_TIME 108 9370
TEST_TIME 109 9321
TEST_TIME 110 9270
TEST_TIME 111 9277
TEST_TIME 112 9293
TEST_TIME 113 9284
TEST_TIME 114 9255
TEST_TIME 115 9222
TEST_TIME 116 9224
TEST_TIME 117 9217
TEST_TIME 118 9259
TEST_TIME 119 9233
TEST_TIME 120 9292

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-12-2021 16:38:53 to 02-12-2021 16:38:56.

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 1548867 1548867 0 0.00 0 15985 6 0 0
doc_list_biud 1548867 1548867 0 0.00 0 22329 25 0 0
sp_add_invoice_to_stock 5295 5124 171 3.22 35 216533 17670 0 171
sp_cancel_adding_invoice 5796 5796 0 0.00 5 53616 752 0 0
sp_cancel_client_order 1821 1821 0 0.00 4 8229 304 0 0
sp_cancel_customer_reserve 557034 557034 0 0.00 1 16391 55 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 2 5443 77 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 3025 91 0 0
sp_cancel_supplier_invoice 6463 6457 6 0.09 2 15054 774 0 6
sp_cancel_supplier_order 407 407 0 0.00 4 1039808 95923 0 0
sp_cancel_write_off 9757 9757 0 0.00 1 17857 114 0 0
sp_client_order 22074 22074 0 0.00 3 33061 371 0 0
sp_customer_reserve 467731 467731 0 0.00 0 33549 178 0 0
sp_fill_shopping_cart 33702 33702 0 0.00 1 13634 36 0 0
sp_fill_shopping_cart_clo_res 467730 467730 0 0.00 0 11661 12 0 0
sp_get_clo_for_invoice 5123 5123 0 0.00 0 21179 265 0 0
sp_kill_cost_storno 16671 16671 0 0.00 0 8061 50 0 0
sp_kill_qstorno_ret_qs2qd 1130689 1130689 0 0.00 0 17708 30 0 0
sp_kill_qty_storno 597112 597112 0 0.00 0 22328 65 0 0
sp_lock_selected_doc 21765 21765 0 0.00 0 6543 4 0 0
sp_make_cost_storno 29420 29420 0 0.00 0 15503 51 0 0
sp_multiply_rows_for_pdistr 29420 29420 0 0.00 0 15556 29 0 0
sp_multiply_rows_for_qdistr 22074 22074 0 0.00 0 9535 168 0 0
sp_pay_from_customer 7456 7456 0 0.00 4 14200 109 0 0
sp_pay_to_supplier 6124 6124 0 0.00 5 17168 117 0 0
sp_payment_common 13580 13580 0 0.00 2 17158 33 0 0
sp_qd_handle_on_cancel_clo 1821 1821 0 0.00 0 8221 281 0 0
sp_qd_handle_on_invoice_upd_sts 10917 10917 0 0.00 0 22328 308 0 0
sp_qd_handle_on_reserve_upd_sts 10719 10719 0 0.00 0 16340 57 0 0
sp_reserve_write_off 10719 10719 0 0.00 6 16498 175 0 0
sp_supplier_invoice 5778 5778 0 0.00 6 82551 3197 0 0
sp_supplier_order 5875 5875 0 0.00 5 23773 1298 0 0
srv_aggregate_perf_data 116 116 0 0.00 2741 254621 17663 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1415 133745 51296 0 0
srv_find_qd_qs_mism 1065651 1065651 0 0.00 0 15428 6 0 0
srv_make_invnt_saldo 640 640 0 0.00 39 14970 851 0 0
srv_make_money_saldo 823 823 0 0.00 1 2656 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 69799 454884 278100 0 0
t$perf-abend:sp_add_invoice_to_stock 171 171 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 5125 5125 0 0.00 0 12203 159 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 9397 101 0 0
t$perf-norm:sp_cancel_client_order 1821 1821 0 0.00 0 4000 14 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 2652 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 5637 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 512 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 1296 14 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 17943 1405 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 3789 12 0 0
t$perf-norm:sp_client_order 22074 22074 0 0.00 0 12310 18 0 0
t$perf-norm:sp_customer_reserve 1092 1092 0 0.00 0 798 3 0 0
t$perf-norm:sp_pay_from_customer 7456 7456 0 0.00 0 9642 9 0 0
t$perf-norm:sp_pay_to_supplier 6124 6124 0 0.00 0 4500 9 0 0
t$perf-norm:sp_reserve_write_off 10719 10719 0 0.00 0 8631 12 0 0
t$perf-norm:sp_supplier_invoice 5778 5778 0 0.00 0 7758 19 0 0
t$perf-norm:sp_supplier_order 5875 5875 0 0.00 0 3034 16 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 474 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 1447 24 0 0
t$perf-norm:srv_make_invnt_saldo 640 640 0 0.00 0 490 12 0 0
t$perf-norm:srv_make_money_saldo 823 823 0 0.00 0 1178 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10582 10240 342 3.23 0 7031 21 0 342
v_all_customers 39942 39942 0 0.00 0 59 1 0 0
v_all_suppliers 32136 32136 0 0.00 0 166 1 0 0
v_all_wares 44134 44134 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 181 6 0 0
v_cancel_client_order 3642 3642 0 0.00 0 2513 4 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 2082 6 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 1042 3 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 2168 7 0 0
v_cancel_supplier_invoice 868 856 12 1.38 0 2232 18 0 12
v_cancel_supplier_order 814 814 0 0.00 0 1075 4 0 0
v_cancel_write_off 2028 2028 0 0.00 0 4173 22 0 0
v_max_id_clo_ord 5872 5872 0 0.00 0 2110 2 0 0
v_max_id_clo_res 1092 1092 0 0.00 0 4447 15 0 0
v_max_id_ord_sup 5762 5762 0 0.00 0 4589 4 0 0
v_max_non_paid_invoice 1690 1690 0 0.00 0 1681 4 0 0
v_max_non_paid_realizn 4115 4115 0 0.00 0 1194 3 0 0
v_min_id_clo_ord 5872 5872 0 0.00 0 5362 8 0 0
v_min_id_clo_res 1092 1092 0 0.00 0 3593 5 0 0
v_min_id_ord_sup 5762 5762 0 0.00 0 6399 10 0 0
v_min_non_paid_invoice 6124 6124 0 0.00 0 17155 26 0 0
v_min_non_paid_realizn 7456 7456 0 0.00 0 14159 28 0 0
v_our_firm 10874 10874 0 0.00 0 90 1 0 0
v_reserve_write_off 21436 21436 0 0.00 0 7079 7 0 0
x_lock_depdocs_on_canc_invoice 11825 11825 0 0.00 0 11352 108 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 20164 359 0 0
x_make_qstorno_1000_1200 5875 5875 0 0.00 2 23759 1217 0 0
x_make_qstorno_1200_2000 5778 5778 0 0.00 2 82490 3075 0 0
x_make_qstorno_2100_3300 467731 467731 0 0.00 0 33548 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-12-2021 16:38:57 to 02-12-2021 16:39:01.

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:59 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:00 39921 8192000000 9914775264 10230263808 987572160 10.758 100 92 186 0 1018472464 6451856 24448448
14:10:46 22915 8192000000 9953104512 10277445632 1032250768 11.190 100 92 184 0 1064161552 8532272 23378512
14:12:26 14611 8192000000 10000318176 10342785024 1086172848 11.706 100 83 166 0 1116979296 9973648 20832800
14:13:48 9591 8192000000 10057969840 10413092864 1143625776 12.250 100 81 162 1 1174604896 11909696 19069424
14:15:00 7917 8192000000 10095304176 10463215616 1189132096 12.675 100 80 160 0 1219042144 11772304 18137744
14:16:10 3968 8192000000 10142932464 10515533824 1229620144 13.051 100 78 154 3 1261303632 14509328 17174160
14:17:15 5084 8192000000 10174582640 10558992384 1266475232 13.389 100 73 145 1 1295547616 12884096 16188288
14:18:22 2330 8192000000 10200774080 10588565504 1285838624 13.566 100 88 174 0 1318414336 13419216 19156496
14:19:26 36925 8192000000 10207527872 10602962944 1300518448 13.700 100 56 112 2 1321454912 7626944 13309520
14:21:05 4283 8192000000 10200235984 10614861824 1315994592 13.840 100 41 80 2 1333057664 7694352 9368720
14:22:12 25080 8192000000 10206661344 10632634368 1321058768 13.886 100 46 92 0 1339908768 7858576 10991424
14:24:16 6928 8192000000 10220859040 10640228352 1330739456 13.974 100 26 51 2 1342704816 5925792 6039568
14:25:26 10875 8192000000 10210052176 10629828608 1319539632 13.873 99 16 32 0 1328777936 5761712 3476592
14:26:41 37586 8192000000 10210512384 10638475264 1285625296 13.564 96 23 45 0 1296184848 5415776 5143776
14:28:23 7336 8192000000 10189744112 10602233856 1280406736 13.517 98 21 44 1 1290872880 6034176 4431968
14:29:33 5458 8192000000 10175840016 10574958592 1251892256 13.256 97 29 58 0 1264165872 5081312 7192304
14:30:39 52178 8192000000 10136662464 10542092288 1166407568 12.463 92 14 27 1 1174268288 4359056 3501664
14:32:32 7763 8192000000 10164838240 10569818112 1258106832 13.313 99 25 49 3 1268957312 5351408 5499072
14:33:42 7218 8192000000 10177420176 10577739776 1275492864 13.472 100 24 46 1 1290384048 9136832 5754352
14:34:52 8559 8192000000 10180517792 10594975744 1292234352 13.625 100 12 24 0 1300415120 5265968 2914800
14:36:01 33672 8192000000 10195699920 10609442816 1302790144 13.721 100 44 88 2 1319712912 6751440 10171328
14:37:37 6154 8192000000 10215676960 10632310784 1328382800 13.953 100 36 69 2 1341558400 5333968 7841632
14:38:51 5799 8192000000 10243182544 10652835840 1338012000 14.039 100 50 100 1 1357740144 8688864 11039280
14:40:02 22391 8192000000 10251708944 10669948928 1350970768 14.156 100 42 84 0 1369727216 8965728 9790720
14:41:39 10026 8192000000 10277319008 10699407360 1374387728 14.366 100 49 99 2 1395199648 9107184 11704736
14:43:11 43421 8192000000 10295848656 10718003200 1384132752 14.453 100 57 114 1 1413441776 16567984 12741040
14:45:03 22012 8192000000 10291318400 10716778496 1380146784 14.418 100 41 82 1 1403139776 13569920 9423072
14:46:30 10881 8192000000 10319179552 10736128000 1391421616 14.519 100 51 102 0 1426988320 23527488 12039216
14:47:47 17520 8192000000 10318367424 10745556992 1397153648 14.570 100 54 108 1 1435391744 25059200 13178896
14:49:11 4513 8192000000 10338870528 10761764864 1405715232 14.646 100 61 119 1 1450014912 29569904 14729776
14:50:21 12127 8192000000 10317955360 10742661120 1392884816 14.532 100 49 97 1 1430474800 25839008 11750976
14:51:40 3362 8192000000 10323650416 10741784576 1380235376 14.419 99 56 110 1 1422713888 28114752 14363760
14:52:49 5649 8192000000 10320416896 10736328704 1387746016 14.486 100 64 128 1 1435816784 31455840 16614928
14:54:17 1415 8192000000 10343600688 10752786432 1390580816 14.511 100 76 152 1 1446573312 36537856 19454640
14:55:24 2518 8192000000 10326602032 10741530624 1378855056 14.406 100 60 120 0 1433916816 40228640 14833120
14:56:32 1626 8192000000 10320054016 10728636416 1366740272 14.298 99 85 168 1 1426219952 38291168 21188512
14:57:40 12253 8192000000 10334994800 10752434176 1384973632 14.461 100 43 88 0 1438529600 41500224 12055744
14:58:57 18504 8192000000 10288461920 10716426240 1328532080 13.954 98 38 76 1 1385502688 46348176 10622432
15:00:25 4115 8192000000 10277558400 10691031040 1328307472 13.952 100 44 90 0 1381504032 41418768 11777792
15:01:32 21465 8192000000 10285173456 10710806528 1324577056 13.918 99 28 56 2 1383212000 50279200 8355744
15:02:58 109887 8192000000 10272011392 10716917760 1346126336 14.113 99 18 36 2 1376334736 25327840 4880560
15:05:49 97971 8192000000 10283616848 10721329152 1343088016 14.085 98 20 40 0 1365299488 18317344 3894128
15:08:34 87359 8192000000 10273562288 10709671936 1342434560 14.079 98 33 66 0 1360203024 10034448 7734016
15:11:03 36037 8192000000 10289090544 10739503104 1381645680 14.431 100 34 68 1 1402260624 12926720 7688224
15:13:04 46444 8192000000 10311833312 10755948544 1401214576 14.606 100 31 62 0 1420158352 11196592 7747184
15:14:51 94742 8192000000 10287035456 10753671168 1388858864 14.496 99 15 30 0 1410579104 17411600 4308640
15:18:20 42490 8192000000 10293448592 10758090752 1379910320 14.416 99 26 53 2 1396315264 10289984 6114960
15:20:04 70962 8192000000 10312859312 10770403328 1401091104 14.605 100 24 48 0 1420399344 13281424 6026816
15:23:31 51273 8192000000 10295610608 10757128192 1371860448 14.344 98 21 38 1 1390084736 12020400 6203888
15:25:33 91392 8192000000 10308094096 10775236608 1385102480 14.462 98 32 63 0 1406087296 13777216 7207600
15:28:05 71563 8192000000 10291129776 10740998144 1340780288 14.064 96 9 18 0 1355404000 12591936 2031776
15:30:17 71530 8192000000 10291575648 10742386688 1358699488 14.226 98 14 28 1 1371947824 10462736 2785600
15:32:34 53907 8192000000 10303815216 10740469760 1348923024 14.138 97 35 70 0 1367124976 10399488 7802464
15:34:29 104193 8192000000 10269411568 10714296320 1290271184 13.607 94 28 56 0 1312403872 16182608 5950080
15:37:13 117881 8192000000 10265198896 10723504128 1345266560 14.105 98 28 56 0 1362708480 11337680 6104240
15:40:18 30863 8192000000 10258225904 10692640768 1327256928 13.942 98 39 78 0 1348594304 12683712 8653664
15:41:58 57299 8192000000 10250210144 10704707584 1339716976 14.055 99 13 27 1 1355735280 13018128 3000176
15:45:08 67285 8192000000 10222973088 10680979456 1279882864 13.512 95 18 36 0 1302740032 18139728 4717440
15:47:15 85852 8192000000 10254362736 10700091392 1301929184 13.713 96 15 30 0 1316472320 11025136 3518000
15:49:43 28836 8192000000 10255511088 10691072000 1315442224 13.835 98 36 72 2 1334481056 11094992 7943840
15:51:13 75895 8192000000 10253854752 10700963840 1311305104 13.798 97 27 54 2 1328473440 11092912 6075424
15:53:30 77589 8192000000 10278749232 10721996800 1340402032 14.061 98 17 34 1 1361330672 17040160 3888480
15:55:48 84105 8192000000 10273948720 10722504704 1326001184 13.931 97 12 24 0 1341958240 13577520 2379536
15:58:12 133745 8192000000 10263456992 10713432064 1326031792 13.931 97 9 18 0 1340611936 12626544 1953600
16:01:32 63415 8192000000 10267546064 10719465472 1363902112 14.272 100 17 34 1 1380419360 12534208 3983040
16:03:38 53906 8192000000 10277166672 10739855360 1372038512 14.345 100 8 16 0 1389026016 14784144 2203360
16:05:32 58852 8192000000 10291778528 10748301312 1370458336 14.331 99 21 42 0 1389464880 14087648 4918896
16:07:38 79805 8192000000 10288071920 10759532544 1371416608 14.340 99 13 26 0 1395681648 20978832 3286208
16:10:08 50671 8192000000 10291356464 10761498624 1390926832 14.514 100 13 26 1 1406347392 12409408 3011152
16:12:02 87301 8192000000 10285436848 10737188864 1310467568 13.790 94 14 28 0 1322607168 8996192 3143408
16:14:34 41079 8192000000 10291035952 10752548864 1376562304 14.386 99 27 55 4 1393662480 10747488 6352688
16:16:16 42992 8192000000 10305817328 10757345280 1384767072 14.459 99 32 64 1 1402224336 10080944 7376320
16:18:11 37134 8192000000 10326573040 10767802368 1400597664 14.600 100 26 52 1 1417868352 11439424 5831264
16:19:51 21492 8192000000 10323431728 10760777728 1385299728 14.464 99 29 57 0 1399963312 8145184 6518400
16:21:13 40122 8192000000 10313361264 10764251136 1381798400 14.433 99 42 84 0 1406140480 15021152 9320928
16:22:54 121028 8192000000 10267569088 10717298688 1283339824 13.543 92 10 20 2 1295461696 10057744 2064128
16:25:55 77992 8192000000 10284581120 10740199424 1363784592 14.271 99 21 42 0 1374668944 6058976 4825376
16:28:14 47073 8192000000 10271750736 10739970048 1360626064 14.243 98 20 40 1 1377571696 12170752 4774880
16:30:04 76328 8192000000 10245866112 10711932928 1246393520 13.205 91 5 10 0 1265764176 17992208 1378448
16:32:21 43795 8192000000 10265647216 10717057024 1334227888 14.005 98 5 9 1 1353038544 18014912 795744
16:34:06 83331 8192000000 10250394032 10696769536 1276878400 13.485 94 16 32 0 1294636544 13341504 4416640
16:36:30 42696 8192000000 10256163840 10701697024 1335705776 14.019 99 31 62 2 1355319696 12578432 7035488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-12-2021 16:39:03 to 02-12-2021 16:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 171 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 02-12-2021 16:39:18 to 02-12-2021 16:39:20.

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          249556
MON$OLDEST_ACTIVE               249557
MON$OLDEST_SNAPSHOT             249557
MON$NEXT_TRANSACTION            249557
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                       2024800
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 ---
:682: 
:683: mscs-test-161.budzdorov.ru    Thu Dec  2 16:38:00 2021
:684:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:685: 
:686: 
:687: mscs-test-161.budzdorov.ru    Thu Dec  2 16:38:06 2021
:688:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:689: 
:690: 
:691: mscs-test-161.budzdorov.ru    Thu Dec  2 16:38:06 2021
:692:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:693: 
:694: 
:695: mscs-test-161.budzdorov.ru    Thu Dec  2 16:38:06 2021
:696:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:697: 
:698: 
:699: mscs-test-161.budzdorov.ru    Thu Dec  2 16:38:06 2021
:700:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:701: 
:702: 
:703: mscs-test-161.budzdorov.ru    Thu Dec  2 16:38:07 2021
:704:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:705: 
:706: 
:707: mscs-test-161.budzdorov.ru    Thu Dec  2 16:38:07 2021
:708:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:709: 
--- end of diff output ---

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

02.12.21 16:39:21. 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-12-02 16:39:24 No severe PSQL-related problems occured
02.12.21 16:39:25. End of report.