Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.12.2021 19: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 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 16461224 kB
MemAvailable 31359188 kB
Buffers 0 kB
Cached 15231732 kB
SwapCached 1664 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.9
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-31 19:38:00
DTS_END                         2021-12-31 19:38:00.8900
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/34188
TRN_ID                          237248
ATT_ID                          657
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: 9336.92 25709 1120430
customer order: creation 172.07 344 20648
customer order: refuse 15.09 337 1811
order to supplier: creation 46.70 1258 5604
order to supplier: removal 3.54 95150 425
invoice (draft): creation 46.69 3505 5603
invoice (draft): removal 54.23 771 6508
invoice accept: apply 41.57 18096 4988
invoice accept: cancel 48.96 768 5875
customer reserve: creation 3845.76 179 461491
customer reserve: removal 4757.33 54 570879
realization accept: apply 84.10 193 10092
realization accept: cancel 89.93 126 10791
payment from customer: creation 60.42 133 7250
payment from customer: removal 5.79 96 695
payment to supplier: creation 49.82 159 5978
payment to supplier: removal 3.21 86 385
service: total inventory turnovers 4.47 1163 536
service: total monetary turnovers 6.39 40 767
service: aggregate perf. data 0.83 22720 100
service: refresh index statistics 0.03 368993 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-12-2021 19:39:34 to 31-12-2021 19:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5248
TEST_TIME 2 3918
TEST_TIME 3 3860
TEST_TIME 4 3880
TEST_TIME 5 4069
TEST_TIME 6 4103
TEST_TIME 7 4727
TEST_TIME 8 4875
TEST_TIME 9 4902
TEST_TIME 10 5402
TEST_TIME 11 5626
TEST_TIME 12 6386
TEST_TIME 13 6667
TEST_TIME 14 6761
TEST_TIME 15 7221
TEST_TIME 16 7267
TEST_TIME 17 7381
TEST_TIME 18 7849
TEST_TIME 19 7736
TEST_TIME 20 8353
TEST_TIME 21 8658
TEST_TIME 22 8941
TEST_TIME 23 9110
TEST_TIME 24 9238
TEST_TIME 25 9921
TEST_TIME 26 10016
TEST_TIME 27 11210
TEST_TIME 28 11025
TEST_TIME 29 10974
TEST_TIME 30 10713
TEST_TIME 31 10630
TEST_TIME 32 10647
TEST_TIME 33 10785
TEST_TIME 34 10651
TEST_TIME 35 10544
TEST_TIME 36 10333
TEST_TIME 37 10210
TEST_TIME 38 10196
TEST_TIME 39 10170
TEST_TIME 40 10091
TEST_TIME 41 9962
TEST_TIME 42 10057
TEST_TIME 43 10054
TEST_TIME 44 9918
TEST_TIME 45 9999
TEST_TIME 46 10025
TEST_TIME 47 9949
TEST_TIME 48 9919
TEST_TIME 49 9787
TEST_TIME 50 9695
TEST_TIME 51 9702
TEST_TIME 52 9685
TEST_TIME 53 9620
TEST_TIME 54 9617
TEST_TIME 55 9698
TEST_TIME 56 9753
TEST_TIME 57 9762
TEST_TIME 58 9783
TEST_TIME 59 9799
TEST_TIME 60 9860
TEST_TIME 61 9899
TEST_TIME 62 10002
TEST_TIME 63 10006
TEST_TIME 64 10142
TEST_TIME 65 10155
TEST_TIME 66 10021
TEST_TIME 67 9900
TEST_TIME 68 9942
TEST_TIME 69 9915
TEST_TIME 70 9857
TEST_TIME 71 9799
TEST_TIME 72 9792
TEST_TIME 73 9819
TEST_TIME 74 9791
TEST_TIME 75 9846
TEST_TIME 76 9844
TEST_TIME 77 9880
TEST_TIME 78 9864
TEST_TIME 79 9872
TEST_TIME 80 9891
TEST_TIME 81 9876
TEST_TIME 82 9967
TEST_TIME 83 9927
TEST_TIME 84 9847
TEST_TIME 85 9804
TEST_TIME 86 9788
TEST_TIME 87 9730
TEST_TIME 88 9723
TEST_TIME 89 9731
TEST_TIME 90 9669
TEST_TIME 91 9681
TEST_TIME 92 9620
TEST_TIME 93 9580
TEST_TIME 94 9578
TEST_TIME 95 9536
TEST_TIME 96 9592
TEST_TIME 97 9571
TEST_TIME 98 9584
TEST_TIME 99 9641
TEST_TIME 100 9650
TEST_TIME 101 9618
TEST_TIME 102 9622
TEST_TIME 103 9611
TEST_TIME 104 9671
TEST_TIME 105 9630
TEST_TIME 106 9709
TEST_TIME 107 9654
TEST_TIME 108 9717
TEST_TIME 109 9660
TEST_TIME 110 9599
TEST_TIME 111 9591
TEST_TIME 112 9573
TEST_TIME 113 9557
TEST_TIME 114 9511
TEST_TIME 115 9497
TEST_TIME 116 9458
TEST_TIME 117 9405
TEST_TIME 118 9420
TEST_TIME 119 9415
TEST_TIME 120 9341

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-12-2021 19:39:38 to 31-12-2021 19:39:41.

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 1549028 1549028 0 0.00 0 33738 6 0 0
doc_list_biud 1549020 1549020 0 0.00 0 33976 26 0 0
sp_add_invoice_to_stock 5076 4988 88 1.73 19 200061 18096 0 88
sp_cancel_adding_invoice 5875 5875 0 0.00 4 56812 769 0 0
sp_cancel_client_order 1811 1811 0 0.00 4 7435 337 0 0
sp_cancel_customer_reserve 570879 570879 0 0.00 1 24518 55 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 6900 97 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 3395 87 0 0
sp_cancel_supplier_invoice 6521 6508 13 0.19 2 33989 771 0 13
sp_cancel_supplier_order 425 425 0 0.00 8 1159842 95150 0 0
sp_cancel_write_off 10791 10791 0 0.00 1 21957 127 0 0
sp_client_order 20648 20648 0 0.00 3 15796 345 0 0
sp_customer_reserve 461491 461491 0 0.00 0 70136 179 0 0
sp_fill_shopping_cart 31850 31850 0 0.00 1 11934 40 0 0
sp_fill_shopping_cart_clo_res 461485 461485 0 0.00 0 28342 14 0 0
sp_get_clo_for_invoice 4982 4982 0 0.00 0 33245 273 0 0
sp_kill_cost_storno 17746 17746 0 0.00 0 16285 62 0 0
sp_kill_qstorno_ret_qs2qd 1159482 1159482 0 0.00 0 33968 30 0 0
sp_kill_qty_storno 611361 611361 0 0.00 0 33974 65 0 0
sp_lock_selected_doc 20944 20944 0 0.00 0 7309 4 0 0
sp_make_cost_storno 28302 28302 0 0.00 0 33732 58 0 0
sp_multiply_rows_for_pdistr 28298 28298 0 0.00 0 27335 40 0 0
sp_multiply_rows_for_qdistr 20648 20648 0 0.00 0 13446 150 0 0
sp_pay_from_customer 7250 7250 0 0.00 4 19731 134 0 0
sp_pay_to_supplier 5978 5978 0 0.00 5 33977 160 0 0
sp_payment_common 13225 13225 0 0.00 1 19690 50 0 0
sp_qd_handle_on_cancel_clo 1811 1811 0 0.00 0 7430 307 0 0
sp_qd_handle_on_invoice_upd_sts 10857 10857 0 0.00 0 29774 320 0 0
sp_qd_handle_on_reserve_upd_sts 10090 10090 0 0.00 0 18164 50 0 0
sp_reserve_write_off 10092 10092 0 0.00 6 29499 194 0 0
sp_supplier_invoice 5603 5603 0 0.00 6 96441 3506 0 0
sp_supplier_order 5604 5604 0 0.00 5 24678 1258 0 0
srv_aggregate_perf_data 100 100 0 0.00 3005 447625 22721 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2702 104873 46081 0 0
srv_find_qd_qs_mism 1073044 1073044 0 0.00 0 21290 5 0 0
srv_make_invnt_saldo 536 536 0 0.00 35 59673 1164 0 0
srv_make_money_saldo 767 767 0 0.00 1 7821 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 198034 544804 368994 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4988 4988 0 0.00 0 20530 174 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 19015 156 0 0
t$perf-norm:sp_cancel_client_order 1811 1811 0 0.00 0 14555 20 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 1856 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 515 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 815 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 868 15 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 23049 1472 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 8596 21 0 0
t$perf-norm:sp_client_order 20648 20648 0 0.00 0 10371 19 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 5357 18 0 0
t$perf-norm:sp_pay_from_customer 7250 7250 0 0.00 0 17440 14 0 0
t$perf-norm:sp_pay_to_supplier 5978 5978 0 0.00 0 12726 12 0 0
t$perf-norm:sp_reserve_write_off 10092 10092 0 0.00 0 15908 18 0 0
t$perf-norm:sp_supplier_invoice 5603 5603 0 0.00 0 8355 15 0 0
t$perf-norm:sp_supplier_order 5604 5604 0 0.00 0 8286 19 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 368 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 48 1 0 0
t$perf-norm:srv_make_invnt_saldo 536 536 0 0.00 0 5943 32 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 471 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10140 9964 176 1.73 0 6249 18 0 176
v_all_customers 38112 38112 0 0.00 0 17 1 0 0
v_all_suppliers 31112 31112 0 0.00 0 35 1 0 0
v_all_wares 41296 41296 0 0.00 0 63 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 2015 9 0 0
v_cancel_client_order 3622 3622 0 0.00 0 6216 10 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 2004 7 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 2972 11 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 3292 16 0 0
v_cancel_supplier_invoice 846 820 26 3.07 0 440 12 0 26
v_cancel_supplier_order 850 850 0 0.00 0 856 3 0 0
v_cancel_write_off 2086 2086 0 0.00 0 2824 17 0 0
v_max_id_clo_ord 5602 5602 0 0.00 0 296 1 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 4035 14 0 0
v_max_id_ord_sup 5600 5600 0 0.00 0 7771 5 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 7456 10 0 0
v_max_non_paid_realizn 3696 3696 0 0.00 0 3232 5 0 0
v_min_id_clo_ord 5602 5602 0 0.00 0 7826 7 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 5793 18 0 0
v_min_id_ord_sup 5600 5600 0 0.00 0 8501 9 0 0
v_min_non_paid_invoice 5976 5976 0 0.00 0 18795 43 0 0
v_min_non_paid_realizn 7249 7249 0 0.00 0 18117 42 0 0
v_our_firm 10290 10290 0 0.00 0 12 1 0 0
v_reserve_write_off 20179 20179 0 0.00 0 8333 8 0 0
x_lock_depdocs_on_canc_invoice 11973 11973 0 0.00 0 45039 119 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 3329 309 0 0
x_make_qstorno_1000_1200 5604 5604 0 0.00 2 24661 1175 0 0
x_make_qstorno_1200_2000 5603 5603 0 0.00 3 96133 3362 0 0
x_make_qstorno_2100_3300 461491 461491 0 0.00 0 70134 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2021 19:39:42 to 31-12-2021 19:39:46.

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:08:04 8 8192000000 8806696960 8820592640 11228608 0.136 3 3 4 0 11412528 76800 107120
17:09:05 24469 8192000000 9903499120 10214375424 985904720 10.742 100 86 172 0 1014522544 6169136 22448688
17:10:39 29863 8192000000 9959552672 10282242048 1030785088 11.176 100 88 176 1 1061289440 8387152 22117200
17:12:13 19021 8192000000 9982259184 10321678336 1070452896 11.556 100 75 150 0 1098752176 10451136 17848144
17:13:42 13659 8192000000 10049811584 10403762176 1138642368 12.203 100 82 164 0 1173598752 15837776 19118608
17:15:02 9261 8192000000 10110186256 10472308736 1192621344 12.708 100 80 161 0 1223072688 12346784 18104560
17:16:14 5060 8192000000 10132955136 10505646080 1224626288 13.004 100 85 168 0 1256456448 13232960 18597200
17:17:21 4484 8192000000 10157329376 10535571456 1246910160 13.210 100 72 144 0 1276545632 14013024 15622448
17:18:29 2583 8192000000 10207767264 10588839936 1289785392 13.602 100 87 173 0 1322500400 13610128 19104880
17:19:33 2554 8192000000 10218139280 10603065344 1299554880 13.691 100 85 170 1 1332452848 13989648 18908320
17:20:37 19314 8192000000 10214696688 10614636544 1308138864 13.769 100 56 110 2 1332272432 10341088 13792480
17:21:59 4492 8192000000 10203644448 10617192448 1314842832 13.830 100 34 68 3 1334275024 11710432 7721760
17:23:03 11002 8192000000 10206397280 10622308352 1319750112 13.874 100 17 34 1 1329926320 6111872 4064336
17:24:17 8127 8192000000 10222846672 10640797696 1334114880 14.004 100 25 50 2 1350046944 10131600 5800464
17:25:28 57807 8192000000 10229785808 10655453184 1338947504 14.048 100 22 44 1 1353808688 9454848 5406336
17:27:31 5362 8192000000 10251533360 10666049536 1345996432 14.111 100 39 78 3 1360657504 5081296 9579776
17:28:36 10123 8192000000 10221766336 10637922304 1319948192 13.876 99 25 50 1 1332476720 6479632 6048896
17:29:49 72701 8192000000 10151092016 10566393856 1172333504 12.519 90 21 42 0 1182646432 4946032 5366896
17:32:03 7026 8192000000 10150672752 10551541760 1247480464 13.215 99 33 66 0 1262327792 7541792 7305536
17:33:10 5642 8192000000 10141324304 10546274304 1250931696 13.247 100 24 47 0 1261477248 5974640 4570912
17:34:16 7378 8192000000 10157238528 10561814528 1248126048 13.221 98 36 73 1 1263666976 6904608 8636320
17:35:24 35946 8192000000 10165890816 10577846272 1268623824 13.409 99 21 41 1 1278560400 5341536 4595040
17:37:01 7045 8192000000 10186512000 10600828928 1295808368 13.657 100 32 64 1 1309807472 5904464 8094640
17:38:12 28199 8192000000 10236010576 10637303808 1322705376 13.901 100 49 100 1 1342504480 8660400 11138704
17:39:47 102484 8192000000 10259610336 10678800384 1344060880 14.094 100 39 77 1 1363746528 10984704 8700944
17:43:02 28778 8192000000 10277644960 10701398016 1364751776 14.280 100 33 65 1 1392650880 20590016 7309088
17:44:35 17891 8192000000 10302610656 10720555008 1379255952 14.410 100 50 99 3 1411425888 20187616 11982320
17:46:21 6861 8192000000 10322296144 10740948992 1394313488 14.544 100 49 93 1 1425772432 20560032 10898912
17:47:36 7355 8192000000 10343511088 10760130560 1406390144 14.652 100 59 118 1 1448816080 28610448 13815488
17:48:47 4709 8192000000 10353522288 10766864384 1408276112 14.669 100 79 156 3 1457744992 31613472 17855408
17:49:54 4348 8192000000 10355388560 10776879104 1414002672 14.719 100 74 148 0 1467939424 35033488 18903264
17:51:08 4357 8192000000 10354446592 10779574272 1414999600 14.728 100 60 120 0 1462935184 33110960 14824624
17:52:19 2701 8192000000 10374940096 10793861120 1418469568 14.759 100 80 158 0 1477106576 38495296 20141712
17:53:26 3489 8192000000 10361461664 10785861632 1396291280 14.562 98 72 144 0 1452864000 38817936 17754784
17:54:36 8310 8192000000 10378740560 10794762240 1425393056 14.820 100 74 145 0 1481717744 38358688 17966000
17:55:53 6689 8192000000 10373228080 10788999168 1406853504 14.656 99 79 158 3 1468069504 40812192 20403808
17:57:10 7384 8192000000 10357156880 10779156480 1383984576 14.452 98 49 98 2 1442371600 45380432 13006592
17:58:22 7556 8192000000 10369879296 10794934272 1408970624 14.675 100 73 146 2 1483148656 54870096 19307936
18:00:02 22121 8192000000 10331972880 10767704064 1367581472 14.305 98 42 82 1 1427525184 48359216 11584496
18:01:27 16182 8192000000 10318508208 10752143360 1366097584 14.292 100 29 58 0 1420482640 46169472 8215584
18:02:50 23656 8192000000 10334828592 10769506304 1373031760 14.354 100 27 54 1 1436657392 56065472 7560160
18:04:28 21597 8192000000 10293897424 10738016256 1324423776 13.917 98 23 46 1 1377903712 46253840 7226096
18:05:51 38026 8192000000 10262805376 10694823936 1325924016 13.930 98 29 61 2 1345046208 11484496 7637696
18:07:31 67823 8192000000 10260708416 10688118784 1285530160 13.563 94 22 44 1 1296839568 6878624 4430784
18:09:41 72158 8192000000 10278427296 10725978112 1345073376 14.103 98 9 18 0 1361674672 14652240 1949056
18:11:55 58220 8192000000 10294503696 10736656384 1377681520 14.396 100 28 53 2 1394736416 11262432 5792464
18:13:56 69279 8192000000 10292228336 10741452800 1383938672 14.452 100 43 87 1 1404606496 9225856 11441968
18:16:08 74579 8192000000 10308085632 10743103488 1375673136 14.378 99 37 75 1 1395733088 11629264 8430688
18:18:23 42346 8192000000 10299922912 10746028032 1351249680 14.159 97 26 53 1 1368581888 11625728 5706480
18:20:22 40169 8192000000 10300675104 10765742080 1389019744 14.497 100 21 42 0 1414840816 20301872 5519200
18:22:09 70135 8192000000 10305800320 10772828160 1397460848 14.572 100 15 30 1 1418001760 16850336 3690576
18:24:23 34504 8192000000 10293142128 10760343552 1371729296 14.343 98 12 22 2 1388285536 13567136 2989104
18:25:58 35311 8192000000 10291343744 10753134592 1380117792 14.418 99 6 12 0 1387949744 6425952 1406000
18:27:37 59304 8192000000 10317228272 10764886016 1401081984 14.605 100 38 76 1 1420445600 11134528 8229088
18:29:39 49803 8192000000 10298576704 10778333184 1397301264 14.571 100 11 22 0 1413445824 13519760 2624800
18:31:30 49360 8192000000 10308460320 10772533248 1367572992 14.305 97 20 41 2 1385249200 13490336 4185872
18:33:20 26037 8192000000 10295147120 10742206464 1363392736 14.268 98 16 32 0 1375950160 9379056 3178368
18:34:52 39678 8192000000 10272518128 10729504768 1331855056 13.984 96 15 30 0 1345025328 9811232 3359040
18:36:32 53133 8192000000 10276942256 10738008064 1336426528 14.025 97 20 40 0 1354726160 13975600 4324032
18:38:28 77864 8192000000 10294241104 10741841920 1339623184 14.054 97 9 18 1 1360987088 19401056 1962848
18:40:56 104872 8192000000 10302957200 10765725696 1354664464 14.189 98 11 22 1 1375632208 18597328 2370416
18:44:33 65705 8192000000 10284125376 10727579648 1319445008 13.872 95 31 62 0 1333840896 7215440 7180448
18:46:53 68401 8192000000 10279182576 10751094784 1368074720 14.310 99 7 14 0 1381788336 12484096 1229520
18:49:01 81172 8192000000 10272138032 10725474304 1322507200 13.899 96 34 68 1 1344269488 12978096 8784192
18:51:25 69805 8192000000 10259181920 10711773184 1314988528 13.831 96 12 24 1 1327076352 9725344 2362480
18:53:35 36215 8192000000 10270903632 10720317440 1346751792 14.118 99 22 44 1 1363095584 11101376 5242416
18:55:22 46193 8192000000 10263340256 10701529088 1298999936 13.686 96 17 34 0 1312052784 9271872 3780976
18:57:12 48505 8192000000 10267347040 10708500480 1308240608 13.770 97 22 44 0 1324551568 11757728 4553232
18:59:02 80748 8192000000 10295617168 10746056704 1361509200 14.251 100 17 34 1 1382213344 17160272 3543872
19:01:29 93869 8192000000 10287052464 10732367872 1361198192 14.248 99 19 38 1 1378916816 13632544 4086080
19:04:10 95857 8192000000 10285356928 10746798080 1364702448 14.280 99 21 42 0 1387770352 18543392 4524512
19:06:47 29848 8192000000 10284236352 10748755968 1384138624 14.454 100 11 22 0 1397581824 10918064 2525136
19:08:57 62414 8192000000 10267434288 10729984000 1320904544 13.885 95 25 50 0 1334189184 7770736 5513904
19:11:04 48198 8192000000 10283351984 10742910976 1379298544 14.410 100 12 24 0 1389635872 7648320 2689008
19:12:52 61383 8192000000 10298941312 10755805184 1368720112 14.316 99 10 20 0 1383733792 13040480 1973200
19:14:54 50528 8192000000 10277787520 10732036096 1326913808 13.939 95 16 33 1 1340807600 10103840 3789952
19:16:45 60121 8192000000 10281390704 10735669248 1352340448 14.169 98 27 54 0 1372037152 13681280 6015424
19:18:51 43395 8192000000 10297847696 10748628992 1371652368 14.342 99 25 50 0 1387197264 9919392 5625504
19:20:35 27407 8192000000 10289765008 10735587328 1365046384 14.283 99 38 76 0 1385296192 11896208 8353600
19:22:25 59030 8192000000 10295431136 10737197056 1351389280 14.160 98 19 38 0 1365496832 9638256 4469296
19:24:31 73466 8192000000 10294582560 10735046656 1355732576 14.199 98 21 42 0 1371714576 10987760 4994240
19:26:45 86239 8192000000 10295107872 10754265088 1372322096 14.348 99 25 50 0 1388674192 10935376 5416720
19:29:26 63696 8192000000 10288064672 10750910464 1370521824 14.332 99 22 44 0 1389475136 13471920 5481392
19:31:30 38096 8192000000 10265734544 10733142016 1363905696 14.272 99 12 24 0 1374444224 7664224 2874304
19:33:08 78445 8192000000 10265088144 10723934208 1330952224 13.976 96 16 32 0 1344235312 9510416 3772672
19:35:28 39502 8192000000 10264006144 10722013184 1340328496 14.060 98 13 26 0 1355216128 11669552 3218080
19:37:10 53613 8192000000 9503617088 9839403008 623345808 7.071 46 4 8 0 628543872 4505968 692096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-12-2021 19:39:48 to 31-12-2021 19:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2021 19:40:04 to 31-12-2021 19:40:06.

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          238277
MON$OLDEST_ACTIVE               238536
MON$OLDEST_SNAPSHOT             238536
MON$NEXT_TRANSACTION            238536
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                       2038304
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:195: 
:196: mscs-test-161.budzdorov.ru    Fri Dec 31 19:38:05 2021
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:198: 
:199: 
:200: mscs-test-161.budzdorov.ru    Fri Dec 31 19:38:06 2021
:201:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:202: 
:203: 
:204: mscs-test-161.budzdorov.ru    Fri Dec 31 19:38:09 2021
:205:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:206: 
:207: 
:208: mscs-test-161.budzdorov.ru    Fri Dec 31 19:38:11 2021
:209:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:210: 
:211: 
:212: mscs-test-161.budzdorov.ru    Fri Dec 31 19:38:12 2021
:213:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:214: 
--- end of diff output ---

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

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