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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16166572 kB
MemAvailable 30797936 kB
Buffers 0 kB
Cached 15547916 kB
SwapCached 3244 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-09-26 01:37:46
DTS_END                         2021-09-26 01:37:46.7700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60130
TRN_ID                          231839
ATT_ID                          681
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: 9133.22 19281 1095986
customer order: creation 163.96 382 19675
customer order: refuse 14.28 364 1714
order to supplier: creation 43.58 1345 5230
order to supplier: removal 3.22 115799 386
invoice (draft): creation 42.65 3620 5118
invoice (draft): removal 52.43 820 6292
invoice accept: apply 39.42 20316 4731
invoice accept: cancel 47.63 856 5716
customer reserve: creation 3742.30 196 449076
customer reserve: removal 4700.44 61 564053
realization accept: apply 78.49 203 9419
realization accept: cancel 82.70 116 9924
payment from customer: creation 56.06 152 6727
payment from customer: removal 5.50 118 660
payment to supplier: creation 45.80 146 5496
payment to supplier: removal 3.02 78 362
service: total inventory turnovers 4.66 1259 559
service: total monetary turnovers 6.38 71 765
service: aggregate perf. data 0.66 30136 79
service: refresh index statistics 0.03 209582 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-09-2021 01:39:27 to 26-09-2021 01:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5180
TEST_TIME 2 3371
TEST_TIME 3 3354
TEST_TIME 4 3735
TEST_TIME 5 3893
TEST_TIME 6 4105
TEST_TIME 7 4538
TEST_TIME 8 4467
TEST_TIME 9 5349
TEST_TIME 10 5930
TEST_TIME 11 6149
TEST_TIME 12 6240
TEST_TIME 13 6305
TEST_TIME 14 6563
TEST_TIME 15 6663
TEST_TIME 16 6712
TEST_TIME 17 6907
TEST_TIME 18 7060
TEST_TIME 19 7273
TEST_TIME 20 8117
TEST_TIME 21 8257
TEST_TIME 22 9006
TEST_TIME 23 8702
TEST_TIME 24 8900
TEST_TIME 25 8765
TEST_TIME 26 9082
TEST_TIME 27 9022
TEST_TIME 28 9369
TEST_TIME 29 9826
TEST_TIME 30 11095
TEST_TIME 31 11047
TEST_TIME 32 10798
TEST_TIME 33 10677
TEST_TIME 34 10690
TEST_TIME 35 10406
TEST_TIME 36 10524
TEST_TIME 37 10315
TEST_TIME 38 10225
TEST_TIME 39 10116
TEST_TIME 40 9981
TEST_TIME 41 9824
TEST_TIME 42 9828
TEST_TIME 43 9769
TEST_TIME 44 9733
TEST_TIME 45 9628
TEST_TIME 46 9578
TEST_TIME 47 9395
TEST_TIME 48 9357
TEST_TIME 49 9365
TEST_TIME 50 9271
TEST_TIME 51 9206
TEST_TIME 52 9284
TEST_TIME 53 9403
TEST_TIME 54 9318
TEST_TIME 55 9213
TEST_TIME 56 9265
TEST_TIME 57 9180
TEST_TIME 58 9181
TEST_TIME 59 9142
TEST_TIME 60 9098
TEST_TIME 61 9123
TEST_TIME 62 9138
TEST_TIME 63 9159
TEST_TIME 64 9202
TEST_TIME 65 9325
TEST_TIME 66 9289
TEST_TIME 67 9465
TEST_TIME 68 9465
TEST_TIME 69 9409
TEST_TIME 70 9369
TEST_TIME 71 9429
TEST_TIME 72 9458
TEST_TIME 73 9430
TEST_TIME 74 9338
TEST_TIME 75 9297
TEST_TIME 76 9241
TEST_TIME 77 9215
TEST_TIME 78 9215
TEST_TIME 79 9148
TEST_TIME 80 9119
TEST_TIME 81 9181
TEST_TIME 82 9174
TEST_TIME 83 9194
TEST_TIME 84 9211
TEST_TIME 85 9285
TEST_TIME 86 9224
TEST_TIME 87 9307
TEST_TIME 88 9310
TEST_TIME 89 9337
TEST_TIME 90 9347
TEST_TIME 91 9336
TEST_TIME 92 9375
TEST_TIME 93 9377
TEST_TIME 94 9418
TEST_TIME 95 9331
TEST_TIME 96 9281
TEST_TIME 97 9256
TEST_TIME 98 9229
TEST_TIME 99 9211
TEST_TIME 100 9152
TEST_TIME 101 9139
TEST_TIME 102 9192
TEST_TIME 103 9145
TEST_TIME 104 9110
TEST_TIME 105 9106
TEST_TIME 106 9142
TEST_TIME 107 9095
TEST_TIME 108 9107
TEST_TIME 109 9133
TEST_TIME 110 9117
TEST_TIME 111 9122
TEST_TIME 112 9175
TEST_TIME 113 9201
TEST_TIME 114 9221
TEST_TIME 115 9230
TEST_TIME 116 9235
TEST_TIME 117 9248
TEST_TIME 118 9215
TEST_TIME 119 9160
TEST_TIME 120 9135

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-09-2021 01:39:30 to 26-09-2021 01:39:33.

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 1511911 1511911 0 0.00 0 21002 7 0 0
doc_list_biud 1511911 1511911 0 0.00 0 39517 29 0 0
sp_add_invoice_to_stock 4929 4731 198 4.01 36 232233 20317 0 198
sp_cancel_adding_invoice 5716 5716 0 0.00 6 66125 857 0 0
sp_cancel_client_order 1714 1714 0 0.00 4 29844 365 0 0
sp_cancel_customer_reserve 564053 564053 0 0.00 1 29771 61 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 7653 118 0 0
sp_cancel_pay_to_supplier 362 362 0 0.00 2 4465 79 0 0
sp_cancel_supplier_invoice 6302 6292 10 0.15 2 23419 821 0 10
sp_cancel_supplier_order 386 386 0 0.00 5 1001850 115799 0 0
sp_cancel_write_off 9924 9924 0 0.00 1 22840 116 0 0
sp_client_order 19675 19675 0 0.00 4 29941 383 0 0
sp_customer_reserve 449076 449076 0 0.00 0 62282 196 0 0
sp_fill_shopping_cart 30012 30012 0 0.00 1 10578 39 0 0
sp_fill_shopping_cart_clo_res 449073 449073 0 0.00 0 16753 14 0 0
sp_get_clo_for_invoice 4728 4728 0 0.00 0 27016 330 0 0
sp_kill_cost_storno 16662 16662 0 0.00 0 9730 62 0 0
sp_kill_qstorno_ret_qs2qd 1144708 1144708 0 0.00 0 23407 33 0 0
sp_kill_qty_storno 602232 602232 0 0.00 0 39515 72 0 0
sp_lock_selected_doc 19650 19650 0 0.00 0 10390 6 0 0
sp_make_cost_storno 26370 26370 0 0.00 0 20990 65 0 0
sp_multiply_rows_for_pdistr 26370 26370 0 0.00 0 19163 34 0 0
sp_multiply_rows_for_qdistr 19675 19675 0 0.00 0 24347 161 0 0
sp_pay_from_customer 6727 6727 0 0.00 4 19182 152 0 0
sp_pay_to_supplier 5496 5496 0 0.00 5 30044 147 0 0
sp_payment_common 12223 12223 0 0.00 2 30022 37 0 0
sp_qd_handle_on_cancel_clo 1714 1714 0 0.00 0 29829 340 0 0
sp_qd_handle_on_invoice_upd_sts 10443 10443 0 0.00 0 39513 393 0 0
sp_qd_handle_on_reserve_upd_sts 9419 9419 0 0.00 0 12357 66 0 0
sp_reserve_write_off 9419 9419 0 0.00 6 19772 204 0 0
sp_supplier_invoice 5118 5118 0 0.00 6 79248 3621 0 0
sp_supplier_order 5230 5230 0 0.00 5 63078 1345 0 0
srv_aggregate_perf_data 79 79 0 0.00 2672 345813 30137 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1444 126294 43935 0 0
srv_find_qd_qs_mism 1050456 1050456 0 0.00 0 18777 6 0 0
srv_make_invnt_saldo 559 559 0 0.00 42 94377 1259 0 0
srv_make_money_saldo 765 765 0 0.00 1 18060 71 0 0
srv_recalc_idx_stat 4 4 0 0.00 58292 389512 209583 0 0
t$perf-abend:sp_add_invoice_to_stock 198 198 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4732 4732 0 0.00 0 11742 193 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 8205 142 0 0
t$perf-norm:sp_cancel_client_order 1714 1714 0 0.00 0 4118 12 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 3827 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 3279 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 362 362 0 0.00 0 281 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 3945 26 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 29952 2101 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 2675 9 0 0
t$perf-norm:sp_client_order 19675 19675 0 0.00 0 20093 18 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 4511 11 0 0
t$perf-norm:sp_pay_from_customer 6727 6727 0 0.00 0 6594 7 0 0
t$perf-norm:sp_pay_to_supplier 5496 5496 0 0.00 0 22099 17 0 0
t$perf-norm:sp_reserve_write_off 9419 9419 0 0.00 0 7516 11 0 0
t$perf-norm:sp_supplier_invoice 5118 5118 0 0.00 0 6461 19 0 0
t$perf-norm:sp_supplier_order 5230 5230 0 0.00 0 4092 18 0 0
t$perf-norm:srv_aggregate_perf_data 79 79 0 0.00 0 159 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 3951 61 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 742 18 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 896 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 87 24 0 0
v_add_invoice_to_stock 9850 9454 396 4.02 0 6692 20 0 396
v_all_customers 36244 36244 0 0.00 0 24 1 0 0
v_all_suppliers 28978 28978 0 0.00 0 19 1 0 0
v_all_wares 39350 39350 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 4226 14 0 0
v_cancel_client_order 3428 3428 0 0.00 0 1948 6 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 3374 9 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 5725 8 0 0
v_cancel_payment_to_supplier 724 724 0 0.00 0 609 6 0 0
v_cancel_supplier_invoice 742 722 20 2.69 0 703 15 0 20
v_cancel_supplier_order 772 772 0 0.00 0 439 5 0 0
v_cancel_write_off 1892 1892 0 0.00 0 5129 18 0 0
v_max_id_clo_ord 5229 5229 0 0.00 0 1124 2 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 1848 12 0 0
v_max_id_ord_sup 5107 5107 0 0.00 0 7722 5 0 0
v_max_non_paid_invoice 1343 1343 0 0.00 0 30014 27 0 0
v_max_non_paid_realizn 3442 3442 0 0.00 0 1105 5 0 0
v_min_id_clo_ord 5229 5229 0 0.00 0 9886 10 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 5715 16 0 0
v_min_id_ord_sup 5107 5107 0 0.00 0 8600 9 0 0
v_min_non_paid_invoice 5496 5496 0 0.00 0 4555 15 0 0
v_min_non_paid_realizn 6727 6727 0 0.00 0 8627 28 0 0
v_our_firm 9676 9676 0 0.00 0 83 1 0 0
v_reserve_write_off 18838 18838 0 0.00 0 5224 6 0 0
x_lock_depdocs_on_canc_invoice 11647 11647 0 0.00 0 18981 122 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 4943 400 0 0
x_make_qstorno_1000_1200 5230 5230 0 0.00 2 63066 1257 0 0
x_make_qstorno_1200_2000 5118 5118 0 0.00 3 79231 3485 0 0
x_make_qstorno_2100_3300 449076 449076 0 0.00 0 62280 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-09-2021 01:39:34 to 26-09-2021 01:39:38.

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
23:07:50 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:51 33534 8192000000 10370021408 10689871872 1415375808 14.732 100 87 174 0 1450132704 5869856 28887040
23:10:28 10890 8192000000 10418015504 10746728448 1475625408 15.263 100 91 182 0 1512826848 8143344 29058096
23:11:44 24360 8192000000 10477256256 10815455232 1535403360 15.784 100 91 182 1 1574020080 10057632 28559088
23:13:13 14716 8192000000 10553192512 10907402240 1612381984 16.445 100 80 160 0 1647398928 10849216 24167728
23:14:31 6985 8192000000 10636720128 11003801600 1695228816 17.145 100 73 146 1 1728594496 11725552 21640128
23:15:40 6125 8192000000 10697004032 11069812736 1756147440 17.653 100 86 172 1 1793910224 13573856 24188928
23:16:48 3891 8192000000 10738601824 11117912064 1799793376 18.012 100 92 182 3 1838042288 12777920 25470992
23:17:55 3110 8192000000 10762598272 11150340096 1827626640 18.240 100 81 162 1 1862620112 12928128 22065344
23:18:59 1621 8192000000 10810967824 11199741952 1870512304 18.588 100 94 187 1 1910286240 13247280 26526656
23:20:03 29073 8192000000 10805348800 11213279232 1878216368 18.651 100 49 98 4 1899137728 6925952 13995408
23:21:33 7277 8192000000 10800369168 11221790720 1886180336 18.715 100 42 85 0 1906199360 8071248 11947776
23:22:41 11110 8192000000 10804848400 11231739904 1894154688 18.779 100 21 40 2 1906672720 5923920 6594112
23:23:55 69359 8192000000 10827622608 11256377344 1904870848 18.865 100 29 57 1 1918802128 5455680 8475600
23:26:09 2704 8192000000 10844708080 11271426048 1917130800 18.964 100 41 81 1 1936988768 9044112 10813856
23:27:14 7623 8192000000 10822176448 11258118144 1895719872 18.792 99 18 36 2 1905945952 5297952 4928128
23:28:24 16128 8192000000 10773979056 11194978304 1720474176 17.356 91 12 22 2 1727918096 5016000 2427920
23:29:43 22725 8192000000 10739644256 11161296896 1698572688 17.173 91 28 57 0 1712009888 6099808 7337392
23:31:06 5752 8192000000 10684793056 11099340800 1751290912 17.612 98 19 37 1 1762444992 5639584 5514496
23:32:13 6078 8192000000 10708973632 11129753600 1773263408 17.794 98 30 58 2 1788350240 5607824 9479008
23:33:21 43886 8192000000 10785896608 11193364480 1844574064 18.378 100 28 56 2 1865701072 13572080 7554928
23:35:19 10763 8192000000 10782906528 11207393280 1859889920 18.502 100 31 62 0 1875017808 6741056 8386832
23:36:33 8067 8192000000 10787280544 11216252928 1870471344 18.588 100 12 24 0 1879485248 5615840 3398064
23:37:41 8366 8192000000 10824945520 11251482624 1901230512 18.836 100 24 46 1 1916842112 9078224 6533376
23:38:54 117344 8192000000 10860827104 11299844096 1940809616 19.153 100 45 89 2 1964598976 10724480 13064880
23:42:13 9915 8192000000 10908259632 11326267392 1961200448 19.316 100 57 112 1 1990903472 12746256 16956768
23:43:26 6307 8192000000 10921271536 11348377600 1974852256 19.424 100 54 108 2 2008874448 17355584 16666608
23:44:35 17082 8192000000 10924604128 11357396992 1973935264 19.417 100 32 64 1 2008892144 24992624 9964256
23:45:55 10161 8192000000 10950210752 11380703232 1992144832 19.561 100 55 106 0 2037493648 29556640 15792176
23:47:09 5177 8192000000 10965974656 11394957312 2004896560 19.661 100 63 127 0 2052201488 27735696 19569232
23:48:20 3528 8192000000 10951114688 11377123328 1991417952 19.555 100 69 136 0 2038646144 27707968 19520224
23:49:27 2211 8192000000 10950800320 11378307072 1993306320 19.570 100 76 150 0 2042538736 26984240 22248176
23:50:36 2759 8192000000 10959047840 11381071872 1992875552 19.567 100 73 144 1 2047012192 31875152 22261488
23:51:44 14357 8192000000 10949004736 11371421696 1970963600 19.393 99 75 148 2 2026579904 32762272 22854032
23:53:02 5760 8192000000 10956044640 11384115200 1987435136 19.524 99 67 134 0 2044760928 36942112 20383680
23:54:11 3850 8192000000 10960369424 11372691456 1969558752 19.382 100 76 150 2 2039548544 46385824 23603968
23:55:32 1730 8192000000 10953281344 11366764544 1958579264 19.295 99 80 160 2 2034103392 49985776 25538352
23:56:42 3802 8192000000 10994659696 11412557824 1990997248 19.552 100 82 164 1 2074434256 57018944 26418064
23:57:54 6655 8192000000 10960459408 11387404288 1968361376 19.372 100 51 100 1 2043908208 57524720 18022112
23:59:06 1442 8192000000 11002518496 11423764480 1997787648 19.605 100 83 167 1 2082845936 57571328 27486960
00:00:11 29496 8192000000 10953225824 11393085440 1945469360 19.190 98 37 74 2 2002492208 44675264 12347584
00:01:53 16246 8192000000 10931395488 11381710848 1956823536 19.281 99 37 75 2 2016032704 47257216 11951952
00:03:12 5388 8192000000 10967047360 11404451840 1978920944 19.456 100 42 84 3 2045211632 52185888 14104800
00:04:19 32424 8192000000 10947727472 11399667712 1968356128 19.372 100 28 56 0 2028568800 50449088 9763584
00:06:13 20362 8192000000 10921900992 11384528896 1934538944 19.103 100 27 54 3 2010350160 66269232 9541984
00:07:35 104373 8192000000 10869001232 11335913472 1860355088 18.506 96 12 24 0 1880947072 17453056 3138928
00:10:20 45159 8192000000 10906086848 11359051776 1938630208 19.136 99 24 48 1 1966230224 19885680 7714336
00:12:09 51038 8192000000 10929777760 11370127360 1951858784 19.241 99 50 98 2 1984251696 18187120 14205792
00:14:02 52616 8192000000 10876939376 11328970752 1916302400 18.957 97 38 76 2 1933981344 6865920 10813024
00:16:13 63970 8192000000 10882034608 11358400512 1964172688 19.339 100 10 21 0 1978281392 11601744 2506960
00:18:17 61538 8192000000 10901693680 11372953600 1983225664 19.490 100 23 46 0 2002994432 13401728 6367040
00:20:19 29623 8192000000 10886297280 11342761984 1914884880 18.946 97 14 28 0 1926884240 8160128 3839232
00:21:49 51333 8192000000 10911182832 11383148544 1968094240 19.370 99 14 28 2 1989629728 17350352 4185136
00:23:42 85668 8192000000 10909333504 11384016896 1981502464 19.477 100 11 22 1 2003345728 18143232 3700032
00:26:28 33311 8192000000 10896102864 11366301696 1976271904 19.435 100 19 37 1 1997788384 16134736 5381744
00:28:03 63214 8192000000 10915284720 11395948544 1992335248 19.562 100 43 85 1 2027448000 21909072 13203680
00:30:09 47189 8192000000 10913087424 11389394944 1983376480 19.491 100 12 24 0 2004033824 17005808 3651536
00:31:56 65519 8192000000 10890639280 11364241408 1961775392 19.320 99 25 50 0 1980978768 11903712 7299664
00:34:03 52760 8192000000 10892429568 11368218624 1971041168 19.394 100 10 20 0 1985500608 10987760 3471680
00:36:05 58046 8192000000 10895577296 11365806080 1950950864 19.234 98 5 10 0 1959041440 6938560 1152016
00:38:06 76591 8192000000 10906045216 11384631296 1957326080 19.285 98 18 36 2 1977852592 15876352 4650160
00:40:23 31793 8192000000 10903575024 11369672704 1941282192 19.157 98 20 40 1 1961215760 14442480 5491088
00:41:57 51673 8192000000 10923462704 11395678208 1988901040 19.535 100 24 48 0 2010961920 15025312 7035568
00:43:49 55334 8192000000 10915208208 11399401472 1957564656 19.287 99 10 20 0 1979893456 19386672 2942128
00:45:54 69151 8192000000 10905083632 11362906112 1900691792 18.832 96 18 36 2 1922360864 16840352 4828720
00:48:09 41999 8192000000 10881686272 11336888320 1868263360 18.570 95 38 74 1 1897327648 18908320 10155968
00:49:51 59196 8192000000 10861015120 11327983616 1872481200 18.604 96 26 49 1 1892750400 14056016 6213184
00:52:24 48718 8192000000 10862291296 11331002368 1922618016 19.008 99 18 36 0 1938190208 10916656 4655536
00:54:16 78552 8192000000 10875779920 11355308032 1947807264 19.209 100 20 41 1 1976969936 23910768 5251904
00:56:36 55189 8192000000 10854827648 11324555264 1865371456 18.547 96 13 26 0 1891400016 22071680 3956880
00:58:59 69464 8192000000 10867932144 11340296192 1897681808 18.808 98 10 20 0 1919451952 18665072 3105072
01:01:11 77594 8192000000 10844298176 11328229376 1867560240 18.565 96 17 34 0 1880976080 8885488 4530352
01:03:29 31247 8192000000 10905638592 11350392832 1956396976 19.277 100 46 91 0 1978898016 9574896 12926144
01:05:03 30410 8192000000 10863720736 11324399616 1869694640 18.582 96 23 46 1 1890260176 14326032 6239504
01:06:33 93227 8192000000 10881750896 11337441280 1916462832 18.958 98 25 48 2 1936237168 12961872 6812464
01:09:07 22699 8192000000 10901513280 11362435072 1960430256 19.309 100 45 90 1 1986869632 14061040 12378336
01:10:32 52252 8192000000 10905091568 11370049536 1907508064 18.887 97 25 50 0 1924723552 10317760 6897728
01:12:25 96000 8192000000 10897462032 11356966912 1928259968 19.053 98 24 48 1 1943593824 9578816 5755040
01:15:16 52015 8192000000 10879240752 11355676672 1924871856 19.026 98 22 44 0 1946982192 15697760 6412576
01:17:19 75373 8192000000 10902637328 11383476224 1976490176 19.437 100 19 38 0 1998350384 16522368 5337840
01:19:38 62381 8192000000 10890320016 11357126656 1945659440 19.192 99 25 50 1 1962821168 10485968 6675760
01:21:44 71008 8192000000 10895376400 11376193536 1952527408 19.247 99 9 18 0 1968995648 13859008 2609232
01:24:00 30830 8192000000 10919330144 11386507264 1990894112 19.551 100 19 38 1 2004660288 7731504 6034672
01:25:40 59853 8192000000 10909501872 11392360448 1984567776 19.501 100 15 30 1 1997557424 8863808 4125840
01:27:44 36022 8192000000 10932372304 11403419648 1997004912 19.599 100 28 56 2 2017894240 13096800 7792528
01:29:32 69585 8192000000 10911412336 11390324736 1939680880 19.144 97 16 32 0 1958976416 14703264 4592272
01:31:44 126275 8192000000 10889660000 11364012032 1886594464 18.718 95 5 10 0 1901289792 13199456 1495872
01:34:54 56390 8192000000 10858616352 11346903040 1930228064 19.069 100 9 19 0 1958316864 25804560 2284240
01:37:08 29097 8192000000 10839708368 11304771584 1912558848 18.927 100 38 74 0 1939143856 15749344 10835664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-09-2021 01:39:40 to 26-09-2021 01:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 198 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-09-2021 01:39:56 to 26-09-2021 01:39:58.

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          231103
MON$OLDEST_ACTIVE               232554
MON$OLDEST_SNAPSHOT             232554
MON$NEXT_TRANSACTION            232554
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                       2020288
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.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 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 ---
:876: 
:877: mscs-test-161.budzdorov.ru    Sun Sep 26 01:37:49 2021
:878:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:879: 
:880: 
:881: mscs-test-161.budzdorov.ru    Sun Sep 26 01:37:59 2021
:882:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:883: 
:884: 
:885: mscs-test-161.budzdorov.ru    Sun Sep 26 01:37:59 2021
:886:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:887: 
--- end of diff output ---

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

26.09.21 01:39:59. 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-09-26 01:40:01 No severe PSQL-related problems occured
26.09.21 01:40:03. End of report.