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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16853600 kB
MemAvailable 31584600 kB
Buffers 0 kB
Cached 14792560 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-14 19:37:56
DTS_END                         2021-11-14 19:37:56.0750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/32772
TRN_ID                          244964
ATT_ID                          692
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9458.34 25440 1135001
customer order: creation 176.59 350 21191
customer order: refuse 14.69 335 1763
order to supplier: creation 46.58 1341 5590
order to supplier: removal 3.46 94795 415
invoice (draft): creation 48.00 2908 5760
invoice (draft): removal 53.93 742 6471
invoice accept: apply 42.40 16841 5088
invoice accept: cancel 48.23 715 5787
customer reserve: creation 3936.32 167 472358
customer reserve: removal 4791.76 54 575011
realization accept: apply 86.42 178 10370
realization accept: cancel 75.87 116 9104
payment from customer: creation 61.86 117 7423
payment from customer: removal 6.02 63 722
payment to supplier: creation 50.89 123 6107
payment to supplier: removal 3.22 88 386
service: total inventory turnovers 4.48 1331 538
service: total monetary turnovers 6.79 34 815
service: aggregate perf. data 0.82 19277 98
service: refresh index statistics 0.03 369219 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-11-2021 19:38:51 to 14-11-2021 19:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2032
TEST_TIME 2 2759
TEST_TIME 3 3852
TEST_TIME 4 4037
TEST_TIME 5 3685
TEST_TIME 6 3322
TEST_TIME 7 3529
TEST_TIME 8 4018
TEST_TIME 9 4315
TEST_TIME 10 4394
TEST_TIME 11 5076
TEST_TIME 12 5805
TEST_TIME 13 6514
TEST_TIME 14 6811
TEST_TIME 15 7362
TEST_TIME 16 7465
TEST_TIME 17 7572
TEST_TIME 18 8333
TEST_TIME 19 8438
TEST_TIME 20 8573
TEST_TIME 21 8979
TEST_TIME 22 9519
TEST_TIME 23 9803
TEST_TIME 24 10185
TEST_TIME 25 10392
TEST_TIME 26 10785
TEST_TIME 27 10880
TEST_TIME 28 10519
TEST_TIME 29 10474
TEST_TIME 30 10368
TEST_TIME 31 10264
TEST_TIME 32 10240
TEST_TIME 33 10216
TEST_TIME 34 10087
TEST_TIME 35 9947
TEST_TIME 36 9927
TEST_TIME 37 10200
TEST_TIME 38 10147
TEST_TIME 39 10007
TEST_TIME 40 9812
TEST_TIME 41 9657
TEST_TIME 42 9984
TEST_TIME 43 9871
TEST_TIME 44 9946
TEST_TIME 45 9834
TEST_TIME 46 9767
TEST_TIME 47 9900
TEST_TIME 48 9895
TEST_TIME 49 9816
TEST_TIME 50 9858
TEST_TIME 51 9918
TEST_TIME 52 9877
TEST_TIME 53 9892
TEST_TIME 54 10020
TEST_TIME 55 9962
TEST_TIME 56 9886
TEST_TIME 57 10062
TEST_TIME 58 9898
TEST_TIME 59 9852
TEST_TIME 60 9814
TEST_TIME 61 9738
TEST_TIME 62 9678
TEST_TIME 63 9761
TEST_TIME 64 9693
TEST_TIME 65 9633
TEST_TIME 66 9610
TEST_TIME 67 9614
TEST_TIME 68 9613
TEST_TIME 69 9614
TEST_TIME 70 9594
TEST_TIME 71 9638
TEST_TIME 72 9654
TEST_TIME 73 9605
TEST_TIME 74 9688
TEST_TIME 75 9687
TEST_TIME 76 9652
TEST_TIME 77 9666
TEST_TIME 78 9637
TEST_TIME 79 9659
TEST_TIME 80 9733
TEST_TIME 81 9725
TEST_TIME 82 9655
TEST_TIME 83 9579
TEST_TIME 84 9589
TEST_TIME 85 9543
TEST_TIME 86 9551
TEST_TIME 87 9512
TEST_TIME 88 9494
TEST_TIME 89 9464
TEST_TIME 90 9423
TEST_TIME 91 9447
TEST_TIME 92 9506
TEST_TIME 93 9520
TEST_TIME 94 9541
TEST_TIME 95 9511
TEST_TIME 96 9494
TEST_TIME 97 9619
TEST_TIME 98 9676
TEST_TIME 99 9678
TEST_TIME 100 9702
TEST_TIME 101 9681
TEST_TIME 102 9691
TEST_TIME 103 9764
TEST_TIME 104 9716
TEST_TIME 105 9710
TEST_TIME 106 9634
TEST_TIME 107 9611
TEST_TIME 108 9583
TEST_TIME 109 9547
TEST_TIME 110 9541
TEST_TIME 111 9547
TEST_TIME 112 9490
TEST_TIME 113 9512
TEST_TIME 114 9441
TEST_TIME 115 9474
TEST_TIME 116 9461
TEST_TIME 117 9430
TEST_TIME 118 9413
TEST_TIME 119 9473
TEST_TIME 120 9461

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-11-2021 19:38:55 to 14-11-2021 19:38:58.

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 1573080 1573080 0 0.00 0 21481 6 0 0
doc_list_biud 1573080 1573080 0 0.00 0 28961 25 0 0
sp_add_invoice_to_stock 5224 5088 136 2.60 29 161534 16841 0 136
sp_cancel_adding_invoice 5787 5787 0 0.00 4 67563 716 0 0
sp_cancel_client_order 1763 1763 0 0.00 4 10405 335 0 0
sp_cancel_customer_reserve 575011 575011 0 0.00 1 21013 54 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 2 2931 64 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 3 8695 88 0 0
sp_cancel_supplier_invoice 6486 6471 15 0.23 2 17494 742 0 15
sp_cancel_supplier_order 415 415 0 0.00 9 873610 94796 0 0
sp_cancel_write_off 9104 9104 0 0.00 1 15055 117 0 0
sp_client_order 21191 21191 0 0.00 3 21148 351 0 0
sp_customer_reserve 472358 472358 0 0.00 0 132544 168 0 0
sp_fill_shopping_cart 32526 32526 0 0.00 1 15043 37 0 0
sp_fill_shopping_cart_clo_res 472356 472356 0 0.00 0 19569 14 0 0
sp_get_clo_for_invoice 5085 5085 0 0.00 0 24795 288 0 0
sp_kill_cost_storno 15999 15999 0 0.00 0 15034 54 0 0
sp_kill_qstorno_ret_qs2qd 1166012 1166012 0 0.00 0 20955 30 0 0
sp_kill_qty_storno 614006 614006 0 0.00 0 28961 64 0 0
sp_lock_selected_doc 21273 21273 0 0.00 0 8093 5 0 0
sp_make_cost_storno 28985 28985 0 0.00 0 19520 49 0 0
sp_multiply_rows_for_pdistr 28985 28985 0 0.00 0 10415 30 0 0
sp_multiply_rows_for_qdistr 21191 21191 0 0.00 0 15103 148 0 0
sp_pay_from_customer 7423 7423 0 0.00 4 18318 117 0 0
sp_pay_to_supplier 6107 6107 0 0.00 4 29392 123 0 0
sp_payment_common 13530 13530 0 0.00 1 29377 37 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 10394 298 0 0
sp_qd_handle_on_invoice_upd_sts 10872 10872 0 0.00 0 28959 292 0 0
sp_qd_handle_on_reserve_upd_sts 10370 10370 0 0.00 0 14081 59 0 0
sp_reserve_write_off 10370 10370 0 0.00 6 33461 179 0 0
sp_supplier_invoice 5760 5760 0 0.00 6 70645 2909 0 0
sp_supplier_order 5590 5590 0 0.00 4 39790 1342 0 0
srv_aggregate_perf_data 98 98 0 0.00 2655 171460 19277 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2841 133459 47485 0 0
srv_find_qd_qs_mism 1086232 1086232 0 0.00 0 21480 6 0 0
srv_make_invnt_saldo 538 538 0 0.00 29 104389 1331 0 0
srv_make_money_saldo 815 815 0 0.00 1 6391 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 161394 557621 369220 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5088 5088 0 0.00 0 17920 169 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 3394 72 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 9655 17 0 0
t$perf-norm:sp_cancel_customer_reserve 739 739 0 0.00 0 6398 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 2969 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 181 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 1429 17 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 22745 1612 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 2034 10 0 0
t$perf-norm:sp_client_order 21191 21191 0 0.00 0 7790 16 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 10072 21 0 0
t$perf-norm:sp_pay_from_customer 7423 7423 0 0.00 0 11962 15 0 0
t$perf-norm:sp_pay_to_supplier 6107 6107 0 0.00 0 5405 6 0 0
t$perf-norm:sp_reserve_write_off 10370 10370 0 0.00 0 6218 13 0 0
t$perf-norm:sp_supplier_invoice 5760 5760 0 0.00 0 17553 27 0 0
t$perf-norm:sp_supplier_order 5590 5590 0 0.00 0 6985 15 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 41 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 771 19 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 717 16 0 0
t$perf-norm:srv_make_money_saldo 815 815 0 0.00 0 4881 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10442 10170 272 2.60 0 6720 18 0 272
v_all_customers 38996 38996 0 0.00 0 73 0 0 0
v_all_suppliers 31594 31594 0 0.00 0 72 1 0 0
v_all_wares 42382 42382 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 7736 18 0 0
v_cancel_client_order 3526 3526 0 0.00 0 4147 8 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 2607 6 0 0
v_cancel_customer_reserve 1478 1478 0 0.00 0 2457 7 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 6247 21 0 0
v_cancel_supplier_invoice 856 826 30 3.50 0 2620 22 0 30
v_cancel_supplier_order 830 830 0 0.00 0 1976 6 0 0
v_cancel_write_off 1938 1938 0 0.00 0 2580 16 0 0
v_max_id_clo_ord 5589 5589 0 0.00 0 317 1 0 0
v_max_id_clo_res 952 952 0 0.00 0 1535 11 0 0
v_max_id_ord_sup 5746 5746 0 0.00 0 4995 4 0 0
v_max_non_paid_invoice 1645 1645 0 0.00 0 4215 6 0 0
v_max_non_paid_realizn 3914 3914 0 0.00 0 4105 6 0 0
v_min_id_clo_ord 5589 5589 0 0.00 0 15038 14 0 0
v_min_id_clo_res 952 952 0 0.00 0 4687 9 0 0
v_min_id_ord_sup 5746 5746 0 0.00 0 6499 8 0 0
v_min_non_paid_invoice 6107 6107 0 0.00 0 28542 27 0 0
v_min_non_paid_realizn 7423 7423 0 0.00 0 14742 28 0 0
v_our_firm 10404 10404 0 0.00 0 12 1 0 0
v_reserve_write_off 20738 20738 0 0.00 0 33427 9 0 0
x_lock_depdocs_on_canc_invoice 11845 11845 0 0.00 0 32575 102 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 8486 327 0 0
x_make_qstorno_1000_1200 5590 5590 0 0.00 1 39766 1264 0 0
x_make_qstorno_1200_2000 5760 5760 0 0.00 2 70604 2779 0 0
x_make_qstorno_2100_3300 472358 472358 0 0.00 0 132489 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-11-2021 19:38:59 to 14-11-2021 19:39:03.

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
17:07:59 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:00 34039 8192000000 9913323536 10228756480 992457856 10.805 100 87 174 1 1021236384 6182256 22596272
17:10:47 27055 8192000000 9957616624 10282553344 1037094976 11.237 100 89 176 1 1068594704 8840096 22659632
17:12:20 17917 8192000000 9994664304 10336182272 1081224496 11.659 100 84 168 0 1112273488 10437376 20611616
17:13:44 9076 8192000000 10034692000 10386186240 1124168880 12.066 100 77 154 1 1153882064 11460320 18252864
17:15:00 10513 8192000000 10092494144 10455044096 1179915600 12.589 100 80 160 0 1210279216 11833984 18529632
17:16:12 3931 8192000000 10133996416 10504855552 1222990096 12.989 100 85 168 1 1255598832 14327296 18281440
17:17:17 2849 8192000000 10170939072 10548305920 1254836672 13.283 100 79 158 3 1285115440 12850368 17428400
17:18:21 2160 8192000000 10201478848 10582990848 1281711488 13.529 100 91 181 0 1315824656 13440400 20672768
17:19:24 1831 8192000000 10200844480 10597560320 1298096592 13.678 100 62 123 0 1323421440 11582512 13742336
17:20:29 19668 8192000000 10193210704 10611814400 1317788464 13.857 100 25 50 0 1329334352 5127728 6418160
17:21:50 15570 8192000000 10208930816 10627190784 1320437040 13.881 100 56 112 0 1341160624 7028752 13694832
17:23:08 4059 8192000000 10227692704 10639269888 1324998576 13.922 100 41 83 0 1342541360 8437968 9104816
17:24:13 8876 8192000000 10215748400 10640879616 1327860160 13.948 100 18 36 1 1338335008 6167328 4307520
17:25:22 17658 8192000000 10229450144 10653372416 1336152576 14.023 100 21 42 0 1349840320 9304032 4383712
17:26:43 24018 8192000000 10225749136 10653052928 1336332880 14.024 100 17 34 1 1346573888 5952208 4288800
17:28:08 6406 8192000000 10215902656 10631876608 1315167200 13.833 99 18 37 0 1328373168 9370400 3835568
17:29:14 12750 8192000000 10151574416 10557849600 1243098848 13.175 97 16 32 0 1255858368 9398976 3360544
17:30:27 64584 8192000000 10115626112 10511548416 1145946112 12.271 91 47 93 3 1162011104 6133264 9931728
17:32:36 5617 8192000000 10155794000 10557235200 1260974464 13.339 100 33 66 1 1275962000 7214048 7773488
17:33:44 7650 8192000000 10151805584 10557988864 1262883472 13.356 100 18 36 1 1272848608 6131696 3833440
17:34:55 11597 8192000000 10173066704 10582773760 1281786944 13.529 100 18 36 1 1292003296 5600192 4616160
17:36:07 35218 8192000000 10182625072 10599694336 1293413408 13.635 100 36 72 1 1311798000 9464992 8919600
17:37:45 7569 8192000000 10196826400 10608517120 1301269280 13.707 100 15 30 0 1310644944 5722656 3653008
17:38:57 4683 8192000000 10238598432 10644684800 1327608080 13.946 100 52 104 1 1352885344 12985120 12292144
17:40:08 18279 8192000000 10263393072 10676273152 1353909952 14.183 100 46 92 3 1378664928 14093744 10661232
17:41:29 20508 8192000000 10274009664 10694926336 1366965840 14.300 100 43 84 1 1391803280 14502528 10334912
17:42:53 63098 8192000000 10302043584 10725892096 1385238432 14.463 100 40 80 0 1416495312 19810768 11446112
17:44:59 22008 8192000000 10307346032 10728968192 1388534512 14.493 100 36 72 1 1417855520 20363056 8957952
17:46:26 4297 8192000000 10326784528 10746445824 1400422720 14.599 100 50 100 0 1435152240 22765328 11964192
17:47:35 12682 8192000000 10348200320 10759876608 1394278560 14.544 99 70 137 0 1445251168 34259776 16712832
17:48:49 3040 8192000000 10345093360 10759766016 1399375616 14.589 100 78 156 0 1453973504 35173760 19424128
17:49:56 3090 8192000000 10350223840 10761469952 1398512256 14.582 100 85 170 3 1453783952 34936992 20334704
17:51:02 2840 8192000000 10345775136 10758488064 1397457440 14.572 100 78 156 0 1450628512 33156736 20014336
17:52:07 5642 8192000000 10331011536 10755248128 1393300048 14.535 100 54 108 0 1443344160 35707552 14336560
17:53:19 10537 8192000000 10319134528 10741518336 1380363664 14.420 100 51 102 1 1427971920 34095360 13512896
17:54:39 3550 8192000000 10331832176 10746023936 1372961536 14.354 99 73 146 3 1431672752 39234064 19477152
17:55:46 8750 8192000000 10320013280 10742931456 1382079728 14.435 100 52 104 1 1430149760 34551120 13518912
17:56:58 10394 8192000000 10327656880 10755706880 1384225248 14.454 99 37 72 1 1425054336 30024448 10804640
17:58:09 12378 8192000000 10314853232 10735628288 1357628624 14.216 99 43 86 3 1406024288 36200416 12195248
17:59:24 22440 8192000000 10317497568 10748563456 1371780640 14.343 100 36 72 0 1417775552 35556272 10438640
18:00:52 13060 8192000000 10299534368 10743894016 1369217872 14.320 100 29 58 3 1412295472 35301664 7775936
18:02:08 54208 8192000000 10286375056 10727575552 1351893712 14.165 99 16 32 2 1379279472 22942848 4442912
18:04:03 106788 8192000000 10259044800 10702475264 1341878880 14.074 99 22 44 1 1361113312 14269344 4965088
18:06:52 61971 8192000000 10260435744 10687332352 1297271216 13.670 96 23 46 0 1310454800 8287808 4895776
18:08:55 85602 8192000000 10275556368 10726752256 1366891424 14.299 100 19 38 0 1387311984 15864576 4555984
18:11:38 17234 8192000000 10277001856 10712031232 1340218272 14.059 98 24 48 1 1359865504 14079584 5567648
18:12:56 55923 8192000000 10266155008 10714415104 1347804784 14.128 99 24 49 1 1369801344 16799104 5197456
18:16:22 18541 8192000000 10266119632 10718564352 1369789344 14.325 100 13 26 0 1384240064 11624768 2825952
18:17:42 99510 8192000000 10271644416 10725801984 1351953216 14.165 98 23 47 1 1373579232 16102800 5523216
18:21:23 72493 8192000000 10292962560 10740510720 1379925776 14.416 100 27 54 1 1397815792 11402112 6487904
18:23:38 55590 8192000000 10298106496 10754023424 1368489808 14.314 98 17 34 1 1383924144 11881776 3552560
18:25:34 56624 8192000000 10298939728 10749423616 1382878624 14.442 99 12 24 1 1398994240 13618448 2497168
18:27:34 40766 8192000000 10307100368 10771238912 1386932192 14.478 99 26 51 0 1408509744 15669856 5907696
18:29:24 63786 8192000000 10305757184 10763714560 1386696240 14.476 99 22 44 0 1405732864 14051856 4984768
18:31:39 71558 8192000000 10327550496 10766893056 1384212032 14.454 99 27 54 0 1408689392 18476928 6000432
18:33:59 50972 8192000000 10306338240 10771275776 1382551616 14.439 99 17 32 3 1414969920 27990944 4427360
18:35:57 74737 8192000000 10299445760 10750525440 1355077712 14.193 97 26 51 1 1367878272 7137264 5663296
18:38:26 10491 8192000000 10297498112 10751508480 1393516256 14.537 100 28 56 0 1409863616 10144656 6202704
18:39:40 56345 8192000000 10288006096 10757455872 1379895072 14.416 99 13 26 0 1395847264 12702848 3249344
18:41:42 27331 8192000000 10274120704 10738819072 1372370384 14.348 99 8 16 0 1387272656 13753536 1148736
18:43:10 56671 8192000000 10269624528 10723819520 1321708400 13.892 96 17 35 1 1335193056 9360832 4123824
18:45:09 68103 8192000000 10267308288 10725351424 1335277696 14.015 97 7 14 0 1350016992 13524912 1214384
18:47:18 48016 8192000000 10281109952 10731900928 1355646928 14.198 98 17 34 0 1369618768 10032960 3938880
18:49:12 92758 8192000000 10284718832 10715602944 1340089408 14.058 98 20 40 0 1358323328 12811552 5422368
18:52:31 65781 8192000000 10268440928 10705506304 1318259904 13.861 97 22 44 0 1332440016 9431552 4748560
18:54:41 73401 8192000000 10277094352 10708905984 1336544800 14.026 99 24 48 0 1352319456 10742720 5031936
18:56:58 59693 8192000000 10268283184 10725339136 1360397840 14.241 100 28 56 0 1381892768 15533104 5961824
18:58:58 126770 8192000000 10250958624 10693115904 1292027712 13.623 95 36 70 2 1312951744 12729968 8194064
19:02:05 50538 8192000000 10265048016 10709667840 1358397088 14.223 100 28 56 0 1374418848 10118544 5903216
19:03:57 55022 8192000000 10264865376 10717937664 1352240992 14.168 99 18 34 1 1367328608 11188336 3899280
19:06:14 60764 8192000000 10286135376 10741080064 1381737568 14.432 100 19 38 3 1404957312 18440880 4778864
19:08:23 48499 8192000000 10303525104 10760130560 1385522352 14.466 100 15 30 0 1403716160 14546592 3647216
19:10:12 63149 8192000000 10288970816 10747535360 1355230224 14.195 97 11 22 0 1367428640 9997904 2200512
19:12:16 26069 8192000000 10303981696 10755596288 1369910800 14.326 98 21 42 0 1388619408 14477024 4231584
19:13:51 68459 8192000000 10312825424 10786398208 1369886848 14.326 98 15 30 0 1396919920 23352048 3681024
19:16:13 106711 8192000000 10298068144 10757468160 1336390112 14.025 96 15 30 0 1354312144 14831296 3090736
19:19:03 48422 8192000000 10318051568 10773438464 1399807760 14.593 100 31 62 2 1418614912 11315424 7491728
19:20:55 38202 8192000000 10302251360 10746830848 1368358352 14.312 98 33 66 1 1384365776 8612000 7395424
19:22:38 133459 8192000000 10276405824 10731790336 1366873104 14.299 98 21 40 0 1382035248 9416688 5745456
19:25:56 56924 8192000000 10289237728 10738855936 1357598992 14.216 98 32 64 2 1380700416 16511792 6589632
19:27:54 17815 8192000000 10264169504 10723721216 1344042560 14.094 98 19 37 0 1360478624 11919504 4516560
19:29:16 82017 8192000000 10271545248 10732302336 1351009408 14.157 98 11 22 0 1368285536 14499184 2776944
19:31:47 67538 8192000000 10250021936 10704990208 1325068544 13.923 97 13 25 1 1340095344 11756560 3270240
19:34:23 66306 8192000000 10258754096 10709823488 1328769536 13.956 98 22 43 1 1342709792 9233072 4707184
19:36:29 41150 8192000000 10243113872 10691768320 1328084560 13.950 99 20 40 1 1342972832 10650288 4237984

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-11-2021 19:39:05 to 14-11-2021 19:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 14-11-2021 19:39:21 to 14-11-2021 19:39:22.

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          243363
MON$OLDEST_ACTIVE               245108
MON$OLDEST_SNAPSHOT             245108
MON$NEXT_TRANSACTION            245108
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                       2026336
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:295: 
:296: mscs-test-161.budzdorov.ru    Sun Nov 14 19:37:59 2021
:297:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:298: 
:299: 
:300: mscs-test-161.budzdorov.ru    Sun Nov 14 19:37:59 2021
:301:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:302: 
:303: 
:304: mscs-test-161.budzdorov.ru    Sun Nov 14 19:38:01 2021
:305:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:306: 
:307: 
:308: mscs-test-161.budzdorov.ru    Sun Nov 14 19:38:01 2021
:309:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:310: 
:311: 
:312: mscs-test-161.budzdorov.ru    Sun Nov 14 19:38:01 2021
:313:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:314: 
:315: 
:316: mscs-test-161.budzdorov.ru    Sun Nov 14 19:38:01 2021
:317:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:318: 
--- end of diff output ---

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

14.11.21 19:39:23. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-11-14 19:39:26 No severe PSQL-related problems occured
14.11.21 19:39:27. End of report.