Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.08.2021 22: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 16242144 kB
MemAvailable 31035376 kB
Buffers 0 kB
Cached 15464764 kB
SwapCached 2612 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-08-14 22:37:44
DTS_END                         2021-08-14 22:37:44.0870
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/47680
TRN_ID                          243270
ATT_ID                          655
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: 9244.69 20024 1109363
customer order: creation 179.49 340 21539
customer order: refuse 15.08 340 1810
order to supplier: creation 46.93 1263 5632
order to supplier: removal 3.46 91890 415
invoice (draft): creation 47.41 3148 5689
invoice (draft): removal 51.39 695 6167
invoice accept: apply 42.56 16243 5107
invoice accept: cancel 46.81 739 5617
customer reserve: creation 3928.95 161 471474
customer reserve: removal 4575.73 55 549087
realization accept: apply 86.33 187 10359
realization accept: cancel 85.06 111 10207
payment from customer: creation 62.25 103 7470
payment from customer: removal 5.99 61 719
payment to supplier: creation 51.12 113 6134
payment to supplier: removal 3.65 75 438
service: total inventory turnovers 4.86 1069 583
service: total monetary turnovers 6.75 27 810
service: aggregate perf. data 0.85 21660 102
service: refresh index statistics 0.03 262202 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-08-2021 22:40:18 to 14-08-2021 22:40:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3395
TEST_TIME 2 4216
TEST_TIME 3 4282
TEST_TIME 4 4341
TEST_TIME 5 4380
TEST_TIME 6 4519
TEST_TIME 7 4636
TEST_TIME 8 4655
TEST_TIME 9 4881
TEST_TIME 10 5397
TEST_TIME 11 5512
TEST_TIME 12 5547
TEST_TIME 13 5606
TEST_TIME 14 5920
TEST_TIME 15 6378
TEST_TIME 16 7092
TEST_TIME 17 7099
TEST_TIME 18 7502
TEST_TIME 19 7744
TEST_TIME 20 7904
TEST_TIME 21 8146
TEST_TIME 22 8457
TEST_TIME 23 8971
TEST_TIME 24 9492
TEST_TIME 25 9798
TEST_TIME 26 9859
TEST_TIME 27 10321
TEST_TIME 28 10323
TEST_TIME 29 10301
TEST_TIME 30 10438
TEST_TIME 31 10334
TEST_TIME 32 10387
TEST_TIME 33 10335
TEST_TIME 34 10333
TEST_TIME 35 10204
TEST_TIME 36 10168
TEST_TIME 37 9945
TEST_TIME 38 9960
TEST_TIME 39 9956
TEST_TIME 40 9799
TEST_TIME 41 9769
TEST_TIME 42 9815
TEST_TIME 43 9829
TEST_TIME 44 9834
TEST_TIME 45 9731
TEST_TIME 46 9685
TEST_TIME 47 9585
TEST_TIME 48 9520
TEST_TIME 49 9547
TEST_TIME 50 9487
TEST_TIME 51 9487
TEST_TIME 52 9440
TEST_TIME 53 9405
TEST_TIME 54 9557
TEST_TIME 55 9522
TEST_TIME 56 9641
TEST_TIME 57 9666
TEST_TIME 58 9641
TEST_TIME 59 9671
TEST_TIME 60 9660
TEST_TIME 61 9778
TEST_TIME 62 9795
TEST_TIME 63 9798
TEST_TIME 64 10015
TEST_TIME 65 9919
TEST_TIME 66 9843
TEST_TIME 67 9785
TEST_TIME 68 9776
TEST_TIME 69 9675
TEST_TIME 70 9650
TEST_TIME 71 9584
TEST_TIME 72 9566
TEST_TIME 73 9515
TEST_TIME 74 9556
TEST_TIME 75 9493
TEST_TIME 76 9445
TEST_TIME 77 9449
TEST_TIME 78 9507
TEST_TIME 79 9485
TEST_TIME 80 9440
TEST_TIME 81 9422
TEST_TIME 82 9452
TEST_TIME 83 9562
TEST_TIME 84 9539
TEST_TIME 85 9557
TEST_TIME 86 9553
TEST_TIME 87 9575
TEST_TIME 88 9617
TEST_TIME 89 9574
TEST_TIME 90 9656
TEST_TIME 91 9578
TEST_TIME 92 9518
TEST_TIME 93 9477
TEST_TIME 94 9450
TEST_TIME 95 9413
TEST_TIME 96 9396
TEST_TIME 97 9379
TEST_TIME 98 9411
TEST_TIME 99 9407
TEST_TIME 100 9388
TEST_TIME 101 9380
TEST_TIME 102 9424
TEST_TIME 103 9445
TEST_TIME 104 9428
TEST_TIME 105 9431
TEST_TIME 106 9444
TEST_TIME 107 9432
TEST_TIME 108 9445
TEST_TIME 109 9517
TEST_TIME 110 9511
TEST_TIME 111 9521
TEST_TIME 112 9450
TEST_TIME 113 9450
TEST_TIME 114 9388
TEST_TIME 115 9394
TEST_TIME 116 9372
TEST_TIME 117 9339
TEST_TIME 118 9321
TEST_TIME 119 9294
TEST_TIME 120 9253

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-08-2021 22:40:22 to 14-08-2021 22:40:25.

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 1545711 1545711 0 0.00 0 30014 6 0 0
doc_list_biud 1545711 1545711 0 0.00 0 30021 25 0 0
sp_add_invoice_to_stock 5209 5107 102 1.95 30 144892 16244 0 102
sp_cancel_adding_invoice 5617 5617 0 0.00 5 78750 740 0 0
sp_cancel_client_order 1810 1810 0 0.00 4 14553 340 0 0
sp_cancel_customer_reserve 549087 549087 0 0.00 1 19408 55 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 3 5330 61 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 4495 76 0 0
sp_cancel_supplier_invoice 6174 6167 7 0.11 2 13147 695 0 7
sp_cancel_supplier_order 415 415 0 0.00 4 1097136 91890 0 0
sp_cancel_write_off 10207 10207 0 0.00 1 12275 112 0 0
sp_client_order 21539 21539 0 0.00 3 15043 341 0 0
sp_customer_reserve 471474 471474 0 0.00 0 80078 161 0 0
sp_fill_shopping_cart 32855 32855 0 0.00 1 13873 38 0 0
sp_fill_shopping_cart_clo_res 471460 471460 0 0.00 0 11611 12 0 0
sp_get_clo_for_invoice 5093 5093 0 0.00 0 18754 278 0 0
sp_kill_cost_storno 16981 16981 0 0.00 0 27935 54 0 0
sp_kill_qstorno_ret_qs2qd 1114963 1114963 0 0.00 0 19386 30 0 0
sp_kill_qty_storno 588755 588755 0 0.00 0 30019 65 0 0
sp_lock_selected_doc 21468 21468 0 0.00 0 9358 6 0 0
sp_make_cost_storno 29056 29056 0 0.00 0 29955 47 0 0
sp_multiply_rows_for_pdistr 29056 29056 0 0.00 0 9390 27 0 0
sp_multiply_rows_for_qdistr 21539 21539 0 0.00 0 11470 157 0 0
sp_pay_from_customer 7470 7470 0 0.00 4 10020 103 0 0
sp_pay_to_supplier 6134 6134 0 0.00 4 13945 113 0 0
sp_payment_common 13604 13604 0 0.00 1 12971 35 0 0
sp_qd_handle_on_cancel_clo 1810 1810 0 0.00 0 14548 298 0 0
sp_qd_handle_on_invoice_upd_sts 10710 10710 0 0.00 0 30019 299 0 0
sp_qd_handle_on_reserve_upd_sts 10359 10359 0 0.00 0 22292 66 0 0
sp_reserve_write_off 10359 10359 0 0.00 6 22338 187 0 0
sp_supplier_invoice 5689 5689 0 0.00 7 76489 3149 0 0
sp_supplier_order 5632 5632 0 0.00 5 30720 1264 0 0
srv_aggregate_perf_data 102 102 0 0.00 2626 431701 21660 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2588 117494 42140 0 0
srv_find_qd_qs_mism 1059857 1059857 0 0.00 0 7585 5 0 0
srv_make_invnt_saldo 583 583 0 0.00 24 121547 1069 0 0
srv_make_money_saldo 810 810 0 0.00 1 5507 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 81159 341033 262202 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5107 5107 0 0.00 0 10330 160 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 1 3012 78 0 0
t$perf-norm:sp_cancel_client_order 1810 1810 0 0.00 0 3719 15 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 355 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 1432 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 1001 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 806 14 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 19771 1599 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 1439 4 0 0
t$perf-norm:sp_client_order 21539 21539 0 0.00 0 7805 18 0 0
t$perf-norm:sp_customer_reserve 1057 1057 0 0.00 0 7494 27 0 0
t$perf-norm:sp_pay_from_customer 7470 7470 0 0.00 0 7474 11 0 0
t$perf-norm:sp_pay_to_supplier 6134 6134 0 0.00 0 5889 12 0 0
t$perf-norm:sp_reserve_write_off 10359 10359 0 0.00 0 8147 14 0 0
t$perf-norm:sp_supplier_invoice 5689 5689 0 0.00 0 7250 16 0 0
t$perf-norm:sp_supplier_order 5632 5632 0 0.00 0 6082 17 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 13 0 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 40 1 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 1808 17 0 0
t$perf-norm:srv_make_money_saldo 810 810 0 0.00 0 7502 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10386 10182 204 1.96 0 9125 19 0 204
v_all_customers 39282 39282 0 0.00 0 15 0 0 0
v_all_suppliers 31514 31514 0 0.00 0 15 1 0 0
v_all_wares 43078 43078 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 2624 8 0 0
v_cancel_client_order 3620 3620 0 0.00 0 5008 10 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 2391 4 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 2438 11 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 1320 5 0 0
v_cancel_supplier_invoice 848 834 14 1.65 0 1328 14 0 14
v_cancel_supplier_order 830 830 0 0.00 0 1983 12 0 0
v_cancel_write_off 2118 2118 0 0.00 0 4238 16 0 0
v_max_id_clo_ord 5632 5632 0 0.00 0 2302 2 0 0
v_max_id_clo_res 1057 1057 0 0.00 0 3442 13 0 0
v_max_id_ord_sup 5684 5684 0 0.00 0 8060 5 0 0
v_max_non_paid_invoice 1693 1693 0 0.00 0 1000 3 0 0
v_max_non_paid_realizn 3987 3987 0 0.00 0 3649 4 0 0
v_min_id_clo_ord 5632 5632 0 0.00 0 3939 6 0 0
v_min_id_clo_res 1057 1057 0 0.00 0 2453 8 0 0
v_min_id_ord_sup 5684 5684 0 0.00 0 5382 8 0 0
v_min_non_paid_invoice 6134 6134 0 0.00 0 9744 25 0 0
v_min_non_paid_realizn 7470 7470 0 0.00 0 9171 21 0 0
v_our_firm 10762 10762 0 0.00 0 102 1 0 0
v_reserve_write_off 20718 20718 0 0.00 0 4921 7 0 0
x_lock_depdocs_on_canc_invoice 11367 11367 0 0.00 0 28725 110 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 14424 374 0 0
x_make_qstorno_1000_1200 5632 5632 0 0.00 1 30706 1188 0 0
x_make_qstorno_1200_2000 5689 5689 0 0.00 3 76482 3008 0 0
x_make_qstorno_2100_3300 471474 471474 0 0.00 0 80075 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-08-2021 22:40:26 to 14-08-2021 22:40:30.

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:47 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:49 26874 8192000000 10394391360 10714365952 1442918704 14.975 100 87 174 0 1477263408 6156800 28187904
20:10:34 27951 8192000000 10466336384 10797867008 1519310400 15.644 100 97 194 0 1559528448 9115568 31102480
20:12:27 16896 8192000000 10522171792 10870239232 1568681856 16.071 100 77 154 0 1603443040 10891792 23869392
20:13:47 13426 8192000000 10589155056 10948288512 1647571504 16.744 100 86 172 0 1684786640 11844496 25370640
20:15:03 5149 8192000000 10658990832 11027156992 1715760384 17.317 100 83 166 2 1753249520 13197216 24291920
20:16:11 4133 8192000000 10703602704 11088224256 1774644656 17.805 100 79 157 2 1808497120 12310928 21541536
20:17:17 3927 8192000000 10746887376 11133624320 1809741072 18.094 100 80 160 1 1845553216 14036128 21776016
20:18:23 1710 8192000000 10804982768 11190468608 1861468416 18.515 100 90 182 3 1900159680 13406064 25285200
20:19:25 6720 8192000000 10773560784 11194617856 1868662880 18.573 100 39 78 2 1888028832 9055248 10310704
20:20:33 8740 8192000000 10780662768 11209502720 1879356480 18.660 100 21 42 1 1891369424 5894880 6118064
20:21:43 11998 8192000000 10794985744 11229282304 1890689488 18.751 100 17 34 1 1905939616 10516752 4733376
20:22:57 30967 8192000000 10810537072 11244908544 1898566272 18.815 100 32 60 3 1912981632 5683696 8731664
20:24:32 5207 8192000000 10834356464 11254284288 1902403776 18.846 100 29 58 2 1917138400 6144816 8589808
20:25:38 9521 8192000000 10834114128 11269836800 1910821024 18.913 100 21 42 1 1926889408 10149328 5919056
20:26:50 67287 8192000000 10814988752 11250425856 1886962464 18.721 99 36 72 2 1902790752 5710000 10118288
20:29:01 5426 8192000000 10775683200 11184234496 1788495376 17.919 96 35 68 2 1805473392 6272800 10705216
20:30:07 7380 8192000000 10707543584 11113345024 1736259488 17.488 97 33 65 2 1751799552 6035680 9504384
20:31:17 58465 8192000000 10690668192 11095871488 1711266800 17.279 95 41 83 1 1728273696 4634032 12372864
20:33:19 5118 8192000000 10745067040 11153825792 1803814448 18.045 99 25 51 0 1816911488 5519760 7577280
20:34:25 10725 8192000000 10761097136 11184558080 1847143248 18.399 100 25 50 3 1860587216 6272848 7171120
20:35:37 10781 8192000000 10773314144 11201167360 1857942320 18.487 100 14 28 2 1867472416 5300752 4229344
20:36:49 23273 8192000000 10784750576 11217182720 1876528832 18.637 100 34 68 3 1891930816 5541968 9860016
20:38:19 16486 8192000000 10844482208 11266080768 1917208544 18.964 100 48 96 1 1939139536 8009904 13921088
20:39:46 21996 8192000000 10872349152 11302350848 1942557344 19.167 100 40 80 1 1965572816 11380544 11634928
20:41:12 13947 8192000000 10901400768 11333345280 1964074976 19.338 100 48 96 3 1997555600 19534944 13945680
20:42:40 20966 8192000000 10929391248 11354419200 1978726416 19.455 100 50 101 3 2013197936 19491648 14979872
20:44:04 20096 8192000000 10917641216 11360886784 1984720800 19.502 100 34 67 0 2013647728 18779552 10147376
20:45:36 20290 8192000000 10946217936 11387777024 2005224928 19.664 100 56 113 2 2048047296 25935664 16886704
20:47:13 8663 8192000000 10976511072 11411083264 2019773904 19.778 100 70 140 1 2070570608 29367680 21429024
20:48:25 11229 8192000000 10981869824 11425120256 2029508960 19.855 100 54 107 0 2075401888 29659056 16233872
20:49:42 6145 8192000000 10981115904 11414401024 2010227488 19.703 99 70 140 1 2063770656 31957184 21585984
20:50:54 10418 8192000000 11002325024 11441414144 2032666272 19.880 100 58 116 1 2090923264 39827440 18429552
20:52:12 5339 8192000000 11000629840 11424800768 2018243136 19.766 100 73 145 0 2079640416 38710400 22686880
20:53:21 2587 8192000000 11022129520 11444236288 2037483712 19.917 100 83 166 1 2101258528 37678832 26095984
20:54:26 10741 8192000000 10942170672 11386720256 1931713776 19.081 96 38 76 1 1976541696 33166752 11661168
20:55:46 15880 8192000000 10936837744 11379003392 1969857504 19.384 100 30 60 4 2022398800 42473504 10067792
20:57:04 23144 8192000000 10885630080 11332710400 1911573984 18.919 99 24 48 2 1956597008 36996896 8026128
20:58:34 10376 8192000000 10893776256 11330785280 1916088848 18.955 100 34 68 1 1975011792 47242496 11680448
20:59:54 18889 8192000000 10901199280 11349819392 1894263152 18.780 97 30 60 0 1952469504 47680880 10525472
21:01:30 27234 8192000000 10863679808 11299827712 1891606576 18.759 98 28 56 2 1931203984 30528320 9069088
21:03:22 25756 8192000000 10882131472 11335602176 1901972720 18.842 98 19 38 1 1934962752 26127984 6862048
21:05:28 69082 8192000000 10844347984 11289108480 1870911056 18.592 97 30 61 2 1888965824 9348304 8706464
21:07:39 61852 8192000000 10863013152 11311968256 1864504352 18.540 96 20 40 0 1881833696 11101120 6228224
21:09:49 45090 8192000000 10853191584 11296096256 1905360368 18.869 99 43 84 1 1929728896 12568080 11800448
21:11:40 57975 8192000000 10874157808 11309338624 1889344000 18.740 98 14 28 0 1902360592 8799808 4216784
21:13:44 117494 8192000000 10884711216 11340009472 1928056016 19.051 99 20 40 0 1949715744 15505344 6154384
21:16:44 78328 8192000000 10900497056 11349123072 1944644000 19.184 99 39 77 1 1964410928 8820464 10946464
21:19:06 18274 8192000000 10937562432 11370135552 1960868176 19.313 99 56 112 4 1989604672 11960048 16776448
21:20:31 53433 8192000000 10899628192 11372879872 1970304128 19.388 100 11 22 1 1987493872 14170592 3019152
21:22:25 10915 8192000000 10911787024 11377553408 1983099952 19.489 100 30 61 1 2003011744 11278496 8633296
21:23:48 10150 8192000000 10902909728 11382304768 1984462192 19.500 100 3 6 0 1992047216 6871200 713824
21:25:47 66611 8192000000 10889616352 11382722560 1973469392 19.413 99 11 22 0 1990631568 13865040 3297136
21:28:23 42741 8192000000 10921834336 11393830912 1957273648 19.284 98 19 38 0 1971349328 8388336 5687344
21:30:07 60991 8192000000 10907789040 11401953280 1987150672 19.521 99 15 30 2 2005496416 14006512 4339232
21:32:12 47929 8192000000 10928531200 11399614464 1981429728 19.476 99 23 46 2 1998050288 9838416 6782144
21:34:06 34167 8192000000 10924674912 11401293824 1980947024 19.472 99 10 20 0 1997668112 13953888 2767200
21:35:41 62079 8192000000 10865245968 11332775936 1836970768 18.316 93 19 38 0 1853057296 10820704 5265824
21:37:52 35874 8192000000 10877078448 11331715072 1914066768 18.939 98 19 38 2 1931285776 12128224 5090784
21:39:31 77758 8192000000 10870335680 11334057984 1834864320 18.299 95 15 30 0 1858285456 19044608 4376528
21:41:49 82666 8192000000 10884210816 11321778176 1917213344 18.965 100 15 30 1 1938323392 16272352 4837696
21:44:22 7695 8192000000 10817275328 11267063808 1861920416 18.519 97 39 78 2 1879628896 7067584 10640896
21:45:33 53954 8192000000 10819552544 11296366592 1884945088 18.705 99 6 12 0 1895217760 8988224 1284448
21:47:27 67852 8192000000 10838620704 11306680320 1840589120 18.346 96 16 32 0 1855014096 9875328 4549648
21:49:38 48268 8192000000 10852718656 11314483200 1903590768 18.855 99 29 59 1 1924790064 12740560 8458736
21:51:45 27996 8192000000 10841850880 11308855296 1887155072 18.723 98 16 33 1 1903827680 12022976 4649632
21:53:38 83523 8192000000 10855630592 11326214144 1876451200 18.636 97 10 20 0 1887960688 8389120 3120368
21:57:22 29502 8192000000 10900474624 11355971584 1951686928 19.240 100 46 92 2 1976055952 11291344 13077680
21:58:53 61425 8192000000 10885157904 11361615872 1923000752 19.011 98 20 40 2 1945762016 16776864 5984400
22:00:57 9500 8192000000 10906665888 11349094400 1958098544 19.291 100 52 103 1 1981400832 8142688 15159600
22:02:08 44127 8192000000 10895680864 11358183424 1936623664 19.120 99 34 68 1 1958487056 12506960 9356432
22:03:53 22286 8192000000 10899212880 11353968640 1956730144 19.280 100 27 54 0 1973283696 9019632 7533920
22:05:17 76426 8192000000 10911471376 11371765760 1920357104 18.990 98 11 22 0 1934519280 11295552 2866624
22:07:34 96337 8192000000 10909620144 11391258624 1976650912 19.438 100 6 12 0 1994457776 16660704 1146160
22:10:28 12276 8192000000 10925415056 11398234112 2001458352 19.634 100 26 52 0 2017895584 8895968 7541264
22:11:44 47039 8192000000 10902428544 11388563456 1972316848 19.404 99 10 20 0 1986921248 11936160 2668240
22:13:33 53314 8192000000 10910413744 11388002304 1964957776 19.345 99 13 26 0 1982025376 12966288 4101312
22:15:37 68819 8192000000 10887904240 11375185920 1907007632 18.883 96 7 14 1 1924148272 15495744 1644896
22:17:45 56904 8192000000 10913931584 11386011648 1966917904 19.361 99 20 39 4 1981066944 8798960 5350080
22:19:43 56567 8192000000 10902267280 11382202368 1937929072 19.130 98 16 33 0 1956813808 14110032 4774704
22:22:16 38857 8192000000 10922643680 11396030464 1971602176 19.398 99 25 50 1 1988007264 9399568 7005520
22:23:58 49249 8192000000 10870468320 11339354112 1869263616 18.578 95 19 38 0 1886188064 11742688 5181760
22:25:47 79307 8192000000 10904338288 11385655296 1947941520 19.210 99 13 26 0 1971595472 19704992 3948960
22:28:11 79385 8192000000 10896648864 11371958272 1934171904 19.100 98 24 48 1 1956470896 16047024 6251968
22:31:09 56994 8192000000 10887833184 11362770944 1943198656 19.172 99 36 73 0 1965293856 11906816 10188384
22:33:08 56199 8192000000 10834686272 11313999872 1796216784 17.983 92 7 13 1 1804577904 6610672 1750448
22:35:04 89449 8192000000 10846830848 11322519552 1875912528 18.632 98 13 26 1 1892721232 13124544 3684160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-08-2021 22:40:32 to 14-08-2021 22:40:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-08-2021 22:40:47 to 14-08-2021 22:40:49.

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          243269
MON$OLDEST_ACTIVE               244206
MON$OLDEST_SNAPSHOT             244206
MON$NEXT_TRANSACTION            244206
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                       2038240
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.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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 ---
:280: 
:281: mscs-test-161.budzdorov.ru    Sat Aug 14 22:38:34 2021
:282:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:283: 
:284: 
:285: mscs-test-161.budzdorov.ru    Sat Aug 14 22:38:34 2021
:286:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:287: 
:288: 
:289: mscs-test-161.budzdorov.ru    Sat Aug 14 22:38:34 2021
:290:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:291: 
--- end of diff output ---

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

14.08.21 22:40:50. 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-08-14 22:40:53 No severe PSQL-related problems occured
14.08.21 22:40:54. End of report.