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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16115372 kB
MemAvailable 30697044 kB
Buffers 0 kB
Cached 15556664 kB
SwapCached 4684 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-10-04 22:37:54
DTS_END                         2021-10-04 22:37:54.9070
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/57228
TRN_ID                          239113
ATT_ID                          651
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: 9214.84 22154 1105781
customer order: creation 174.12 373 20894
customer order: refuse 14.18 375 1701
order to supplier: creation 46.05 1316 5526
order to supplier: removal 3.34 113015 401
invoice (draft): creation 45.87 3580 5504
invoice (draft): removal 52.66 875 6319
invoice accept: apply 41.43 18954 4971
invoice accept: cancel 47.83 820 5739
customer reserve: creation 3832.62 187 459914
customer reserve: removal 4656.37 62 558764
realization accept: apply 82.95 193 9954
realization accept: cancel 86.43 115 10371
payment from customer: creation 59.46 132 7135
payment from customer: removal 5.48 103 658
payment to supplier: creation 49.33 140 5919
payment to supplier: removal 3.87 97 464
service: total inventory turnovers 4.92 1133 590
service: total monetary turnovers 7.03 24 844
service: aggregate perf. data 0.91 15800 109
service: refresh index statistics 0.03 285776 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-10-2021 22:38:45 to 04-10-2021 22:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1582
TEST_TIME 2 1818
TEST_TIME 3 2800
TEST_TIME 4 2858
TEST_TIME 5 2841
TEST_TIME 6 2977
TEST_TIME 7 3648
TEST_TIME 8 4244
TEST_TIME 9 4599
TEST_TIME 10 4532
TEST_TIME 11 4832
TEST_TIME 12 5288
TEST_TIME 13 5308
TEST_TIME 14 5408
TEST_TIME 15 5518
TEST_TIME 16 5907
TEST_TIME 17 6226
TEST_TIME 18 6390
TEST_TIME 19 6452
TEST_TIME 20 6998
TEST_TIME 21 7244
TEST_TIME 22 8353
TEST_TIME 23 8481
TEST_TIME 24 8564
TEST_TIME 25 9049
TEST_TIME 26 9640
TEST_TIME 27 10662
TEST_TIME 28 10891
TEST_TIME 29 10832
TEST_TIME 30 10512
TEST_TIME 31 10356
TEST_TIME 32 10167
TEST_TIME 33 10133
TEST_TIME 34 9985
TEST_TIME 35 9860
TEST_TIME 36 9795
TEST_TIME 37 9685
TEST_TIME 38 9595
TEST_TIME 39 9531
TEST_TIME 40 9551
TEST_TIME 41 9384
TEST_TIME 42 9294
TEST_TIME 43 9186
TEST_TIME 44 9156
TEST_TIME 45 9105
TEST_TIME 46 9143
TEST_TIME 47 9232
TEST_TIME 48 9183
TEST_TIME 49 9196
TEST_TIME 50 9152
TEST_TIME 51 9204
TEST_TIME 52 9283
TEST_TIME 53 9375
TEST_TIME 54 9441
TEST_TIME 55 9399
TEST_TIME 56 9456
TEST_TIME 57 9609
TEST_TIME 58 9649
TEST_TIME 59 9538
TEST_TIME 60 9436
TEST_TIME 61 9456
TEST_TIME 62 9378
TEST_TIME 63 9335
TEST_TIME 64 9328
TEST_TIME 65 9281
TEST_TIME 66 9274
TEST_TIME 67 9250
TEST_TIME 68 9287
TEST_TIME 69 9296
TEST_TIME 70 9365
TEST_TIME 71 9359
TEST_TIME 72 9363
TEST_TIME 73 9337
TEST_TIME 74 9327
TEST_TIME 75 9405
TEST_TIME 76 9400
TEST_TIME 77 9488
TEST_TIME 78 9499
TEST_TIME 79 9475
TEST_TIME 80 9468
TEST_TIME 81 9392
TEST_TIME 82 9377
TEST_TIME 83 9374
TEST_TIME 84 9331
TEST_TIME 85 9283
TEST_TIME 86 9261
TEST_TIME 87 9202
TEST_TIME 88 9186
TEST_TIME 89 9142
TEST_TIME 90 9218
TEST_TIME 91 9200
TEST_TIME 92 9164
TEST_TIME 93 9167
TEST_TIME 94 9278
TEST_TIME 95 9314
TEST_TIME 96 9328
TEST_TIME 97 9331
TEST_TIME 98 9285
TEST_TIME 99 9343
TEST_TIME 100 9358
TEST_TIME 101 9347
TEST_TIME 102 9398
TEST_TIME 103 9402
TEST_TIME 104 9356
TEST_TIME 105 9291
TEST_TIME 106 9240
TEST_TIME 107 9222
TEST_TIME 108 9208
TEST_TIME 109 9166
TEST_TIME 110 9140
TEST_TIME 111 9167
TEST_TIME 112 9170
TEST_TIME 113 9163
TEST_TIME 114 9192
TEST_TIME 115 9162
TEST_TIME 116 9211
TEST_TIME 117 9179
TEST_TIME 118 9198
TEST_TIME 119 9209
TEST_TIME 120 9217

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-10-2021 22:38:48 to 04-10-2021 22:38:52.

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 1532265 1532265 0 0.00 0 32252 6 0 0
doc_list_biud 1532265 1532265 0 0.00 0 30641 29 0 0
sp_add_invoice_to_stock 5096 4971 125 2.45 36 202282 18955 0 125
sp_cancel_adding_invoice 5739 5739 0 0.00 5 56303 820 0 0
sp_cancel_client_order 1701 1701 0 0.00 4 16323 375 0 0
sp_cancel_customer_reserve 558764 558764 0 0.00 1 22597 63 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 9166 104 0 0
sp_cancel_pay_to_supplier 464 464 0 0.00 3 5639 97 0 0
sp_cancel_supplier_invoice 6324 6319 5 0.07 1 15735 875 0 5
sp_cancel_supplier_order 401 401 0 0.00 9 1221281 113015 0 0
sp_cancel_write_off 10371 10371 0 0.00 1 13858 116 0 0
sp_client_order 20894 20894 0 0.00 3 30513 374 0 0
sp_customer_reserve 459914 459914 0 0.00 0 60450 187 0 0
sp_fill_shopping_cart 31914 31914 0 0.00 1 20056 43 0 0
sp_fill_shopping_cart_clo_res 459911 459911 0 0.00 0 18500 15 0 0
sp_get_clo_for_invoice 4967 4967 0 0.00 0 24207 326 0 0
sp_kill_cost_storno 17232 17232 0 0.00 0 32244 62 0 0
sp_kill_qstorno_ret_qs2qd 1134619 1134619 0 0.00 0 22587 34 0 0
sp_kill_qty_storno 598216 598216 0 0.00 0 30640 73 0 0
sp_lock_selected_doc 20637 20637 0 0.00 0 8524 7 0 0
sp_make_cost_storno 27975 27975 0 0.00 0 17384 58 0 0
sp_multiply_rows_for_pdistr 27975 27975 0 0.00 0 8940 32 0 0
sp_multiply_rows_for_qdistr 20894 20894 0 0.00 0 18912 185 0 0
sp_pay_from_customer 7135 7135 0 0.00 4 26215 133 0 0
sp_pay_to_supplier 5919 5919 0 0.00 5 19063 141 0 0
sp_payment_common 13054 13054 0 0.00 1 26208 38 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 0 16205 341 0 0
sp_qd_handle_on_invoice_upd_sts 10706 10706 0 0.00 0 30640 335 0 0
sp_qd_handle_on_reserve_upd_sts 9954 9954 0 0.00 0 11642 59 0 0
sp_reserve_write_off 9954 9954 0 0.00 7 14659 193 0 0
sp_supplier_invoice 5504 5504 0 0.00 7 84021 3580 0 0
sp_supplier_order 5526 5526 0 0.00 5 34707 1316 0 0
srv_aggregate_perf_data 109 109 0 0.00 2957 128968 15801 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1376 111560 46107 0 0
srv_find_qd_qs_mism 1058158 1058158 0 0.00 0 10378 5 0 0
srv_make_invnt_saldo 590 590 0 0.00 34 125030 1134 0 0
srv_make_money_saldo 844 844 0 0.00 1 501 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 72515 482193 285777 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 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 4971 4971 0 0.00 0 16668 181 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 8784 144 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 1394 14 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 3222 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 26543 46 0 0
t$perf-norm:sp_cancel_pay_to_supplier 464 464 0 0.00 0 1139 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 1500 19 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 33673 1824 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 1003 7 0 0
t$perf-norm:sp_client_order 20894 20894 0 0.00 0 11637 19 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 3168 7 0 0
t$perf-norm:sp_pay_from_customer 7135 7135 0 0.00 0 7846 14 0 0
t$perf-norm:sp_pay_to_supplier 5919 5919 0 0.00 0 3962 10 0 0
t$perf-norm:sp_reserve_write_off 9954 9954 0 0.00 0 6898 13 0 0
t$perf-norm:sp_supplier_invoice 5504 5504 0 0.00 0 26692 23 0 0
t$perf-norm:sp_supplier_order 5526 5526 0 0.00 0 10052 16 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 15743 159 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 65 2 0 0
t$perf-norm:srv_make_invnt_saldo 590 590 0 0.00 0 4951 39 0 0
t$perf-norm:srv_make_money_saldo 844 844 0 0.00 0 12164 34 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10180 9930 250 2.45 0 16196 19 0 250
v_all_customers 38288 38288 0 0.00 0 19 1 0 0
v_all_suppliers 30568 30568 0 0.00 0 24 1 0 0
v_all_wares 41786 41786 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 1564 8 0 0
v_cancel_client_order 3402 3402 0 0.00 0 9176 10 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 2146 8 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 439 3 0 0
v_cancel_payment_to_supplier 928 928 0 0.00 0 5612 17 0 0
v_cancel_supplier_invoice 844 834 10 1.18 0 4354 29 0 10
v_cancel_supplier_order 802 802 0 0.00 0 2755 11 0 0
v_cancel_write_off 1998 1998 0 0.00 0 4656 19 0 0
v_max_id_clo_ord 5524 5524 0 0.00 0 2318 2 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 2281 11 0 0
v_max_id_ord_sup 5497 5497 0 0.00 0 5750 6 0 0
v_max_non_paid_invoice 1656 1656 0 0.00 0 734 3 0 0
v_max_non_paid_realizn 3729 3729 0 0.00 0 2429 6 0 0
v_min_id_clo_ord 5524 5524 0 0.00 0 6273 6 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 1787 4 0 0
v_min_id_ord_sup 5497 5497 0 0.00 0 9647 9 0 0
v_min_non_paid_invoice 5919 5919 0 0.00 0 7178 21 0 0
v_min_non_paid_realizn 7135 7135 0 0.00 0 26203 29 0 0
v_our_firm 10310 10310 0 0.00 0 16 1 0 0
v_reserve_write_off 19908 19908 0 0.00 0 14566 8 0 0
x_lock_depdocs_on_canc_invoice 11641 11641 0 0.00 0 18409 139 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 9715 397 0 0
x_make_qstorno_1000_1200 5526 5526 0 0.00 1 34692 1229 0 0
x_make_qstorno_1200_2000 5504 5504 0 0.00 3 83382 3425 0 0
x_make_qstorno_2100_3300 459914 459914 0 0.00 0 60448 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-10-2021 22:38:53 to 04-10-2021 22:38:57.

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
20:07:58 19 8192000000 8811379536 8825573376 12873680 0.156 4 2 2 0 13000448 64208 62560
20:09:00 29909 8192000000 10359984496 10675130368 1404675232 14.637 100 86 172 0 1438814672 6032080 28107360
20:10:52 19206 8192000000 10442109344 10771705856 1492513968 15.411 100 84 168 1 1527838720 8653824 26670928
20:12:18 21089 8192000000 10511766800 10859118592 1569702656 16.080 100 82 164 2 1605310128 10550368 25057104
20:13:51 15390 8192000000 10594910880 10957697024 1652528608 16.786 100 88 177 1 1690408048 11632816 26246624
20:15:08 7468 8192000000 10656755056 11032096768 1718522352 17.340 100 73 146 1 1751905296 12394432 20988512
20:16:17 4109 8192000000 10729651408 11107737600 1788728352 17.921 100 78 154 0 1821953488 11659008 21566128
20:17:24 8604 8192000000 10768410384 11158155264 1833119904 18.285 100 81 161 2 1868925088 13634112 22171072
20:18:34 1773 8192000000 10812312048 11198861312 1865062640 18.544 100 91 184 1 1905877488 15374864 25439984
20:19:37 1676 8192000000 10827466192 11221151744 1889701024 18.743 100 93 186 1 1928845232 13299328 25844880
20:20:40 5879 8192000000 10825541840 11222081536 1886209392 18.715 100 73 148 4 1915962192 7566832 22185968
20:21:47 7537 8192000000 10797385952 11232751616 1892129232 18.763 100 19 38 3 1902944208 5564864 5250112
20:22:55 6118 8192000000 10800433408 11236364288 1897260224 18.804 100 19 38 0 1908345312 5741488 5343600
20:24:04 10746 8192000000 10814484144 11248988160 1905278720 18.869 100 16 31 1 1915278080 5406640 4592720
20:25:17 60535 8192000000 10830737840 11266879488 1913682272 18.936 100 23 45 1 1925232080 5143280 6406528
20:27:21 8848 8192000000 10804141776 11240194048 1874226400 18.618 98 14 28 0 1883953456 5972160 3754896
20:28:32 7764 8192000000 10785597744 11217514496 1849469072 18.418 98 30 60 0 1864406512 6425744 8511696
20:29:40 64859 8192000000 10719740480 11132653568 1711799920 17.284 93 28 56 0 1725295376 5264560 8230896
20:31:45 5713 8192000000 10714455616 11127894016 1784080288 17.883 99 37 74 1 1800665408 6417664 10167456
20:32:52 5554 8192000000 10720472960 11134660608 1805222960 18.057 100 39 78 0 1823536080 6625008 11688112
20:33:59 13129 8192000000 10762489776 11189620736 1845798688 18.388 100 26 53 0 1862213776 9866816 6548272
20:35:13 21045 8192000000 10778753712 11207372800 1862658224 18.525 100 18 36 0 1878232128 9407136 6166768
20:36:35 5565 8192000000 10782232160 11217186816 1873017088 18.609 100 16 32 0 1883293872 5841952 4434832
20:37:43 8248 8192000000 10819866432 11246481408 1903230912 18.852 100 21 42 0 1913949024 4915792 5802320
20:39:00 36362 8192000000 10885863232 11297550336 1935462336 19.111 100 50 100 2 1958546272 9122160 13961776
20:40:42 99383 8192000000 10878972272 11322081280 1955400368 19.269 100 27 54 1 1977098480 13674352 8023760
20:43:26 18516 8192000000 10920635552 11353157632 1971531936 19.398 100 46 89 0 2005391648 20774352 13085360
20:44:48 2206 8192000000 10969162512 11381891072 1998713152 19.613 100 85 168 1 2048144688 24957296 24474240
20:45:53 7428 8192000000 10959024656 11383721984 1995795584 19.590 100 63 125 1 2041637744 27406784 18435376
20:47:09 11595 8192000000 10956145248 11395362816 2006816464 19.676 100 47 94 1 2049781488 28299136 14665888
20:48:26 3688 8192000000 10984955568 11415425024 2019724816 19.778 100 65 130 1 2074818000 35377952 19715232
20:49:34 1364 8192000000 10999639296 11415556096 2019865552 19.779 100 83 165 1 2081526912 36371552 25289808
20:50:39 4065 8192000000 11002466880 11411804160 2015043200 19.741 100 90 180 2 2083658208 40044176 28570832
20:51:49 2885 8192000000 10990803344 11401129984 1995901456 19.590 100 97 194 3 2072918896 47306672 29710768
20:52:57 7066 8192000000 10989180864 11406774272 1998961264 19.615 100 77 153 0 2078184752 55559872 23663616
20:54:08 3599 8192000000 10997325008 11416367104 1999220576 19.617 100 71 142 0 2076837136 54890560 22726000
20:55:18 1833 8192000000 10999147280 11412910080 1984766464 19.502 99 85 167 1 2067464144 56655872 26041808
20:56:24 2555 8192000000 11015311456 11427479552 2002104432 19.639 100 84 168 0 2096871568 66791936 27975200
20:57:34 3864 8192000000 11015917680 11438489600 1993263648 19.570 99 70 138 1 2086038704 69754896 23020160
20:58:45 2728 8192000000 10991960272 11409756160 1967875024 19.369 99 77 154 1 2064222880 71469984 24877872
20:59:54 19167 8192000000 10932371776 11374432256 1950566688 19.231 100 32 64 1 2015543952 53790368 11186896
21:01:45 18759 8192000000 10936004400 11381202944 1953222016 19.252 100 30 60 3 2020111856 56580928 10308912
21:03:18 59162 8192000000 10918480736 11380727808 1931902160 19.082 99 21 42 3 2003548176 63411056 8234960
21:05:22 62762 8192000000 10906351712 11350929408 1940876160 19.154 99 24 47 0 1962398896 14837456 6685280
21:07:26 45170 8192000000 10886185120 11351994368 1951024096 19.235 99 30 60 1 1968306480 7858944 9423440
21:09:40 101020 8192000000 10875303456 11344150528 1921926976 19.002 97 17 36 0 1933366096 7070352 4368768
21:12:32 87270 8192000000 10865019472 11330961408 1910793536 18.913 98 10 20 0 1921403968 7450576 3159856
21:15:03 82779 8192000000 10895690080 11361046528 1968143888 19.371 100 13 26 1 1984438192 12441120 3853184
21:17:45 102926 8192000000 10870433488 11340906496 1936763728 19.121 98 19 38 1 1957622784 14724720 6134336
21:20:29 25214 8192000000 10891118768 11345027072 1957447520 19.286 100 33 67 0 1974714576 7739856 9527200
21:21:55 56192 8192000000 10871097600 11358015488 1959363280 19.301 100 8 16 0 1973988448 12215872 2409296
21:24:41 50461 8192000000 10889780720 11364921344 1956369584 19.277 100 8 16 0 1973490288 15492112 1628592
21:26:36 41130 8192000000 10898974320 11370532864 1959007008 19.298 99 33 66 0 1980233680 10856368 10370304
21:28:29 60994 8192000000 10923509600 11400933376 1982048640 19.481 100 24 48 3 2020394112 30391136 7954336
21:30:31 77961 8192000000 10913334128 11378925568 1977248640 19.443 100 5 10 1 2000456480 21186240 2021600
21:33:16 50553 8192000000 10944271584 11390119936 1985434944 19.508 100 29 58 2 2009525888 15893056 8197888
21:35:08 89110 8192000000 10910690176 11359223808 1884569264 18.702 95 20 40 0 1903227088 13230736 5427088
21:37:40 38369 8192000000 10919674160 11352121344 1966803424 19.360 99 64 128 2 1995014032 10734224 17476384
21:39:21 34504 8192000000 10914008368 11378114560 1980732144 19.470 100 29 58 0 1999610112 11315792 7562176
21:40:57 40431 8192000000 10885983600 11351175168 1920676784 18.992 97 25 50 1 1938900272 11396464 6827024
21:42:43 78189 8192000000 10877871040 11362537472 1948850912 19.217 99 22 43 0 1971212368 16365504 5995952
21:45:10 47502 8192000000 10893082560 11370323968 1966534624 19.358 100 19 38 2 1987180432 15222912 5422896
21:46:58 45312 8192000000 10860793232 11313590272 1826189872 18.228 94 19 38 0 1843795296 12144208 5461216
21:48:47 30616 8192000000 10850521520 11295911936 1907889696 18.890 99 29 58 0 1924051600 8134192 8027712
21:50:19 27005 8192000000 10860729952 11306729472 1880873168 18.672 97 36 72 0 1899408736 8721184 9814384
21:51:54 32852 8192000000 10834506816 11296681984 1892187088 18.763 99 35 71 0 1917346256 15259280 9899888
21:53:39 111560 8192000000 10877113584 11339501568 1878049056 18.649 97 10 20 1 1892984208 12142112 2793040
21:56:32 64600 8192000000 10850541376 11317678080 1863108080 18.528 96 23 46 2 1883910384 14315328 6486976
21:58:37 92287 8192000000 10839403024 11305422848 1852923632 18.446 96 19 38 0 1865609200 7373216 5312352
22:01:11 27197 8192000000 10841691504 11318902784 1885256608 18.708 98 10 20 0 1902371264 14574912 2539744
22:02:41 66707 8192000000 10861896240 11338084352 1940481552 19.151 100 11 23 0 1952872496 9200144 3190800
22:04:48 108067 8192000000 10887590800 11372015616 1959401536 19.301 100 23 47 0 1985295712 18843312 7050864
22:08:46 38412 8192000000 10911787824 11375181824 1976752272 19.439 100 22 44 1 1991750320 9031696 5966352
22:10:25 92502 8192000000 10921648992 11404005376 1969556304 19.382 99 20 40 0 1999768944 24521856 5690784
22:13:12 29425 8192000000 10914169200 11378298880 1984259216 19.498 100 35 70 2 2003213568 9544048 9410304
22:14:42 28011 8192000000 10887861008 11346452480 1926058064 19.035 97 33 66 0 1944161648 8938512 9165072
22:16:13 83787 8192000000 10906140656 11389362176 1937356992 19.126 98 21 42 0 1962195504 18583024 6255488
22:18:46 15838 8192000000 10947511504 11386347520 1989419248 19.539 100 58 114 1 2014086608 7624864 17042496
22:20:07 63527 8192000000 10887826144 11366023168 1936213232 19.117 98 7 14 0 1947946272 9933456 1799584
22:22:11 104415 8192000000 10905120528 11375968256 1965702736 19.351 99 22 44 1 1980392272 8618000 6071536
22:25:21 50916 8192000000 10871087392 11350310912 1947104832 19.203 99 9 18 0 1963496176 13766736 2624608
22:27:19 75904 8192000000 10901335728 11381055488 1959243552 19.300 99 9 18 0 1974370192 12633952 2492688
22:30:01 64688 8192000000 10856772832 11329441792 1822007280 18.194 92 19 38 1 1837988544 10532544 5448720
22:32:06 42354 8192000000 10878212864 11341197312 1910605760 18.912 98 33 66 0 1926672864 7095216 8971888
22:33:49 41042 8192000000 10860229488 11314008064 1860552544 18.508 96 21 42 0 1881082256 14746080 5783632
22:35:33 57498 8192000000 10845355680 11312881664 1865339120 18.547 96 15 30 0 1882138240 12634560 4164560
22:37:31 31345 8192000000 9322705328 9601388544 476059552 5.492 26 13 24 1 486104112 6554224 3490336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-10-2021 22:38:59 to 04-10-2021 22:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 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:01, from 04-10-2021 22:39:15 to 04-10-2021 22:39:16.

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          239428
MON$OLDEST_ACTIVE               240549
MON$OLDEST_SNAPSHOT             240549
MON$NEXT_TRANSACTION            240549
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                       2013952
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.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:200: 
:201: mscs-test-161.budzdorov.ru    Mon Oct  4 22:38:08 2021
:202:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:203: 
:204: 
:205: mscs-test-161.budzdorov.ru    Mon Oct  4 22:38:08 2021
:206:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:207: 
--- end of diff output ---

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

04.10.21 22:39:17. 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-10-04 22:39:20 No severe PSQL-related problems occured
04.10.21 22:39:21. End of report.