Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.04.2022 01:40
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 16501296 kB
MemAvailable 31572504 kB
Buffers 0 kB
Cached 15239428 kB
SwapCached 2364 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.10
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 2022-04-10 01:38:10
DTS_END                         2022-04-10 01:38:10.8960
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/38944
TRN_ID                          248027
ATT_ID                          697
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: 9562.99 22068 1147559
customer order: creation 181.47 361 21776
customer order: refuse 15.80 378 1896
order to supplier: creation 49.15 1222 5898
order to supplier: removal 3.59 93154 431
invoice (draft): creation 48.18 3327 5782
invoice (draft): removal 51.75 781 6210
invoice accept: apply 44.15 18550 5298
invoice accept: cancel 46.58 846 5589
customer reserve: creation 4163.94 180 499673
customer reserve: removal 4653.39 57 558407
realization accept: apply 87.66 180 10519
realization accept: cancel 78.49 99 9419
payment from customer: creation 64.43 91 7732
payment from customer: removal 6.35 82 762
payment to supplier: creation 51.43 115 6171
payment to supplier: removal 3.59 110 431
service: total inventory turnovers 4.97 998 597
service: total monetary turnovers 7.15 26 858
service: aggregate perf. data 0.88 21946 106
service: refresh index statistics 0.03 298859 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-04-2022 01:40:33 to 10-04-2022 01:40:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9095
TEST_TIME 2 6514
TEST_TIME 3 5267
TEST_TIME 4 5354
TEST_TIME 5 5622
TEST_TIME 6 5449
TEST_TIME 7 5228
TEST_TIME 8 5382
TEST_TIME 9 5803
TEST_TIME 10 6240
TEST_TIME 11 6343
TEST_TIME 12 6245
TEST_TIME 13 6672
TEST_TIME 14 6945
TEST_TIME 15 7117
TEST_TIME 16 7228
TEST_TIME 17 7315
TEST_TIME 18 7901
TEST_TIME 19 8134
TEST_TIME 20 8196
TEST_TIME 21 8609
TEST_TIME 22 8808
TEST_TIME 23 9067
TEST_TIME 24 9395
TEST_TIME 25 9200
TEST_TIME 26 9420
TEST_TIME 27 9745
TEST_TIME 28 9852
TEST_TIME 29 10497
TEST_TIME 30 10835
TEST_TIME 31 10615
TEST_TIME 32 10656
TEST_TIME 33 10865
TEST_TIME 34 10874
TEST_TIME 35 10740
TEST_TIME 36 10618
TEST_TIME 37 10384
TEST_TIME 38 10320
TEST_TIME 39 10368
TEST_TIME 40 10358
TEST_TIME 41 10175
TEST_TIME 42 10142
TEST_TIME 43 10175
TEST_TIME 44 10120
TEST_TIME 45 10131
TEST_TIME 46 10192
TEST_TIME 47 10173
TEST_TIME 48 10109
TEST_TIME 49 10053
TEST_TIME 50 9903
TEST_TIME 51 9811
TEST_TIME 52 9927
TEST_TIME 53 9856
TEST_TIME 54 9813
TEST_TIME 55 9850
TEST_TIME 56 9842
TEST_TIME 57 9823
TEST_TIME 58 9817
TEST_TIME 59 9914
TEST_TIME 60 9879
TEST_TIME 61 9971
TEST_TIME 62 9990
TEST_TIME 63 10110
TEST_TIME 64 10188
TEST_TIME 65 10168
TEST_TIME 66 10143
TEST_TIME 67 10157
TEST_TIME 68 10104
TEST_TIME 69 10035
TEST_TIME 70 9936
TEST_TIME 71 9878
TEST_TIME 72 9888
TEST_TIME 73 9804
TEST_TIME 74 9748
TEST_TIME 75 9800
TEST_TIME 76 9825
TEST_TIME 77 9779
TEST_TIME 78 9749
TEST_TIME 79 9825
TEST_TIME 80 9805
TEST_TIME 81 9805
TEST_TIME 82 9818
TEST_TIME 83 9836
TEST_TIME 84 9867
TEST_TIME 85 9856
TEST_TIME 86 9926
TEST_TIME 87 9927
TEST_TIME 88 9971
TEST_TIME 89 9991
TEST_TIME 90 10033
TEST_TIME 91 9947
TEST_TIME 92 9869
TEST_TIME 93 9872
TEST_TIME 94 9870
TEST_TIME 95 9828
TEST_TIME 96 9813
TEST_TIME 97 9783
TEST_TIME 98 9789
TEST_TIME 99 9757
TEST_TIME 100 9831
TEST_TIME 101 9834
TEST_TIME 102 9821
TEST_TIME 103 9795
TEST_TIME 104 9834
TEST_TIME 105 9813
TEST_TIME 106 9818
TEST_TIME 107 9788
TEST_TIME 108 9822
TEST_TIME 109 9876
TEST_TIME 110 9834
TEST_TIME 111 9788
TEST_TIME 112 9768
TEST_TIME 113 9747
TEST_TIME 114 9727
TEST_TIME 115 9752
TEST_TIME 116 9698
TEST_TIME 117 9694
TEST_TIME 118 9663
TEST_TIME 119 9645
TEST_TIME 120 9566

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-04-2022 01:40:37 to 10-04-2022 01:40:40.

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 1608020 1608020 0 0.00 0 25481 6 0 0
doc_list_biud 1608019 1608019 0 0.00 0 37403 25 0 0
sp_add_invoice_to_stock 5438 5298 140 2.57 21 220413 18550 0 140
sp_cancel_adding_invoice 5589 5589 0 0.00 4 58462 847 0 0
sp_cancel_client_order 1896 1896 0 0.00 4 10066 379 0 0
sp_cancel_customer_reserve 558407 558407 0 0.00 1 19318 57 0 0
sp_cancel_pay_from_customer 762 762 0 0.00 2 5201 83 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 9064 110 0 0
sp_cancel_supplier_invoice 6215 6210 5 0.08 1 37415 782 0 5
sp_cancel_supplier_order 431 431 0 0.00 6 1066242 93155 0 0
sp_cancel_write_off 9419 9419 0 0.00 1 8062 99 0 0
sp_client_order 21776 21776 0 0.00 3 17601 361 0 0
sp_customer_reserve 499673 499673 0 0.00 0 82087 180 0 0
sp_fill_shopping_cart 33448 33448 0 0.00 1 7667 36 0 0
sp_fill_shopping_cart_clo_res 499664 499664 0 0.00 0 17074 13 0 0
sp_get_clo_for_invoice 5289 5289 0 0.00 0 27979 288 0 0
sp_kill_cost_storno 16201 16201 0 0.00 0 25469 58 0 0
sp_kill_qstorno_ret_qs2qd 1132874 1132874 0 0.00 0 37397 31 0 0
sp_kill_qty_storno 597759 597759 0 0.00 0 37401 67 0 0
sp_lock_selected_doc 21983 21983 0 0.00 0 9282 7 0 0
sp_make_cost_storno 29711 29711 0 0.00 0 15987 51 0 0
sp_multiply_rows_for_pdistr 29711 29711 0 0.00 0 10069 26 0 0
sp_multiply_rows_for_qdistr 21776 21776 0 0.00 0 17590 177 0 0
sp_pay_from_customer 7732 7732 0 0.00 4 9176 91 0 0
sp_pay_to_supplier 6171 6171 0 0.00 4 10010 116 0 0
sp_payment_common 13903 13903 0 0.00 1 9929 25 0 0
sp_qd_handle_on_cancel_clo 1896 1896 0 0.00 0 7787 334 0 0
sp_qd_handle_on_invoice_upd_sts 10877 10877 0 0.00 0 15556 322 0 0
sp_qd_handle_on_reserve_upd_sts 10519 10519 0 0.00 0 14912 62 0 0
sp_reserve_write_off 10519 10519 0 0.00 7 14940 181 0 0
sp_supplier_invoice 5782 5782 0 0.00 7 91180 3328 0 0
sp_supplier_order 5898 5898 0 0.00 5 20969 1223 0 0
srv_aggregate_perf_data 106 106 0 0.00 3286 287370 21947 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1631 116957 41698 0 0
srv_find_qd_qs_mism 1095347 1095347 0 0.00 0 10914 6 0 0
srv_make_invnt_saldo 597 597 0 0.00 38 53397 999 0 0
srv_make_money_saldo 858 858 0 0.00 0 4169 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 132710 486050 298860 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5298 5298 0 0.00 0 13254 156 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 1 3852 110 0 0
t$perf-norm:sp_cancel_client_order 1896 1896 0 0.00 0 2037 10 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 4977 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 762 762 0 0.00 0 1325 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 1388 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 1843 26 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 25312 1424 0 0
t$perf-norm:sp_cancel_write_off 1078 1078 0 0.00 0 2677 8 0 0
t$perf-norm:sp_client_order 21776 21776 0 0.00 0 12724 19 0 0
t$perf-norm:sp_customer_reserve 1110 1110 0 0.00 0 3822 11 0 0
t$perf-norm:sp_pay_from_customer 7732 7732 0 0.00 0 15576 10 0 0
t$perf-norm:sp_pay_to_supplier 6171 6171 0 0.00 0 5684 8 0 0
t$perf-norm:sp_reserve_write_off 10519 10519 0 0.00 0 6197 9 0 0
t$perf-norm:sp_supplier_invoice 5782 5782 0 0.00 0 6197 18 0 0
t$perf-norm:sp_supplier_order 5898 5898 0 0.00 0 11145 20 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 213 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 92 2 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 1051 22 0 0
t$perf-norm:srv_make_money_saldo 858 858 0 0.00 0 2161 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 6 0 0
v_add_invoice_to_stock 10850 10570 280 2.58 0 8766 16 0 280
v_all_customers 39348 39348 0 0.00 0 141 1 0 0
v_all_suppliers 32266 32266 0 0.00 0 16 1 0 0
v_all_wares 43550 43550 0 0.00 0 91 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 1686 8 0 0
v_cancel_client_order 3792 3792 0 0.00 0 3557 5 0 0
v_cancel_customer_prepayment 1524 1524 0 0.00 0 3419 8 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 1610 5 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 1565 5 0 0
v_cancel_supplier_invoice 876 866 10 1.14 0 2999 18 0 10
v_cancel_supplier_order 862 862 0 0.00 0 2978 10 0 0
v_cancel_write_off 2156 2156 0 0.00 0 1632 12 0 0
v_max_id_clo_ord 5893 5893 0 0.00 0 230 1 0 0
v_max_id_clo_res 1110 1110 0 0.00 0 3892 17 0 0
v_max_id_ord_sup 5778 5778 0 0.00 0 4215 4 0 0
v_max_non_paid_invoice 1709 1709 0 0.00 0 2181 4 0 0
v_max_non_paid_realizn 4324 4324 0 0.00 0 3346 5 0 0
v_min_id_clo_ord 5893 5893 0 0.00 0 6586 7 0 0
v_min_id_clo_res 1110 1110 0 0.00 0 7719 24 0 0
v_min_id_ord_sup 5778 5778 0 0.00 0 5672 8 0 0
v_min_non_paid_invoice 6171 6171 0 0.00 0 9925 20 0 0
v_min_non_paid_realizn 7732 7732 0 0.00 0 8914 15 0 0
v_our_firm 11018 11018 0 0.00 0 16 1 0 0
v_reserve_write_off 21038 21038 0 0.00 0 5097 5 0 0
x_lock_depdocs_on_canc_invoice 11366 11366 0 0.00 0 15816 116 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 15711 298 0 0
x_make_qstorno_1000_1200 5898 5898 0 0.00 2 20960 1133 0 0
x_make_qstorno_1200_2000 5782 5782 0 0.00 4 87822 3208 0 0
x_make_qstorno_2100_3300 499673 499673 0 0.00 0 82086 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-04-2022 01:40:41 to 10-04-2022 01:40:45.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:14 5 8192000000 8805098944 8818429952 10092576 0.123 3 2 3 0 10235024 67504 74944
23:09:15 20641 8192000000 9887741696 10194399232 972034864 10.607 100 87 174 0 1001072688 5781632 23256192
23:10:44 16805 8192000000 9944684656 10262355968 1027429296 11.144 100 90 180 0 1058147440 7728064 22990080
23:12:09 23808 8192000000 9982186160 10311790592 1063244336 11.488 100 87 174 0 1094487008 9120128 22122544
23:13:41 12889 8192000000 10022667104 10374365184 1116456240 11.993 100 70 140 0 1143592592 10410192 16726160
23:15:02 9042 8192000000 10084271296 10444566528 1168263552 12.481 100 74 147 0 1197045920 12053344 16729024
23:16:15 5975 8192000000 10117124784 10487504896 1214198752 12.908 100 82 163 1 1244591376 12510992 17881632
23:17:25 2711 8192000000 10162726816 10534256640 1246299584 13.204 100 90 175 2 1280795008 14327424 20168000
23:18:28 1693 8192000000 10192078848 10573688832 1279148288 13.505 100 88 176 1 1310779312 12065488 19565536
23:19:31 1047 8192000000 10218794656 10600943616 1298718864 13.684 100 100 199 2 1334621552 13691856 22210832
23:20:33 8663 8192000000 10198439376 10605006848 1306743344 13.757 100 40 80 0 1325063808 9277824 9042640
23:21:45 39235 8192000000 10201957360 10619293696 1316255408 13.843 100 44 87 1 1332109440 5461600 10392432
23:23:26 5964 8192000000 10213559648 10630791168 1324125296 13.914 100 23 46 0 1336454432 7403808 4925328
23:24:33 6315 8192000000 10224685936 10640314368 1332971616 13.994 100 24 48 0 1348872800 10045968 5855216
23:25:41 16504 8192000000 10233243264 10642907136 1334194592 14.005 100 19 37 0 1344294384 5215008 4884784
23:26:58 16951 8192000000 10220639248 10643247104 1320729920 13.883 99 24 46 2 1332470240 5008048 6732272
23:28:16 9325 8192000000 10210161232 10622312448 1316493152 13.845 99 24 48 0 1326941952 4758624 5690176
23:29:26 15281 8192000000 10177083904 10591592448 1249073280 13.230 96 13 26 1 1257233472 5435744 2724448
23:30:43 71333 8192000000 10125034464 10528169984 1162032560 12.422 92 29 58 0 1174365600 5566080 6766960
23:32:58 7192 8192000000 10153897328 10555940864 1241825808 13.163 98 30 60 0 1255789712 7023168 6940736
23:34:06 7695 8192000000 10175827392 10584211456 1280212720 13.515 100 19 38 0 1294436320 9810608 4412992
23:35:14 16831 8192000000 10182337104 10592575488 1294444048 13.645 100 22 44 2 1305296592 5241152 5611392
23:36:34 17044 8192000000 10176584784 10596237312 1293918928 13.640 100 13 26 0 1302957008 5945520 3092560
23:37:52 6297 8192000000 10195117728 10608402432 1303995088 13.732 100 24 48 1 1315737008 5477184 6264736
23:39:06 40774 8192000000 10240768576 10657853440 1338310592 14.042 100 28 56 0 1352274400 7063616 6900192
23:40:58 13879 8192000000 10269581616 10683011072 1358918576 14.228 100 57 113 2 1381884576 9838240 13127760
23:42:34 40490 8192000000 10286535936 10710192128 1379760336 14.414 100 46 92 0 1405924256 15577056 10586864
23:44:18 21516 8192000000 10294882544 10719215616 1385568416 14.466 100 52 104 1 1412842976 14581312 12693248
23:45:47 13911 8192000000 10315483712 10742984704 1401115680 14.605 100 47 93 0 1434925648 23016192 10793776
23:47:06 2139 8192000000 10341971312 10756763648 1405558480 14.644 100 74 147 1 1448238896 25062192 17618224
23:48:14 9078 8192000000 10344746784 10769252352 1412787376 14.709 100 59 118 1 1453434384 26548560 14098448
23:49:27 7756 8192000000 10334896512 10765762560 1401013072 14.604 99 52 103 0 1442322400 28845856 12463472
23:50:41 2147 8192000000 10359531264 10781552640 1419572960 14.769 100 77 154 1 1468667280 30522816 18571504
23:51:49 4405 8192000000 10362358192 10782744576 1407872640 14.665 99 77 154 1 1461414208 34080448 19461120
23:52:58 1631 8192000000 10357639200 10769973248 1407645312 14.663 100 81 162 1 1463162896 35685408 19832176
23:54:03 4983 8192000000 10347033584 10758176768 1394672560 14.548 100 80 159 1 1450427664 36680480 19074624
23:55:15 2549 8192000000 10353042704 10756730880 1397338224 14.571 100 83 165 2 1459866336 41871664 20656448
23:56:25 1979 8192000000 10356029168 10761400320 1385897808 14.469 100 84 164 0 1454868336 48619808 20350720
23:57:34 13264 8192000000 10328218224 10751033344 1368815808 14.316 98 36 70 1 1418503120 40705328 8981984
23:58:51 10233 8192000000 10332744384 10753871872 1371646640 14.342 100 62 124 0 1445288016 57560848 16080528
00:00:08 14193 8192000000 10306173040 10730188800 1351781728 14.164 99 34 68 1 1404619920 43817808 9020384
00:01:30 5805 8192000000 10354545376 10766852096 1386683504 14.476 100 69 139 1 1457942736 53415808 17843424
00:02:39 29068 8192000000 10322530128 10753466368 1371637840 14.342 99 30 60 2 1421687888 41946192 8103856
00:04:19 17709 8192000000 10305338576 10739503104 1340459248 14.062 98 28 56 0 1398195488 49258032 8478208
00:06:06 26625 8192000000 10301965968 10737725440 1361374848 14.250 100 17 34 1 1407853136 41541504 4936784
00:07:40 53809 8192000000 10271760512 10723508224 1328755056 13.956 98 15 30 0 1359528528 27133888 3639584
00:09:34 47722 8192000000 10288254544 10724712448 1351043984 14.157 98 25 50 3 1369703680 13280560 5379136
00:11:25 49803 8192000000 10277430560 10718527488 1346987392 14.120 98 8 16 0 1358422080 10162560 1272128
00:13:26 108731 8192000000 10283670480 10727067648 1358161312 14.221 98 23 44 0 1375070400 11033104 5875984
00:16:57 56970 8192000000 10307649472 10756661248 1390789824 14.513 100 17 34 0 1402436560 7490720 4156016
00:18:55 17516 8192000000 10301468224 10748514304 1386073728 14.471 99 17 34 0 1395553328 5764768 3714832
00:20:24 14644 8192000000 10330109280 10776604672 1410864880 14.692 100 33 65 3 1426805008 8198336 7741792
00:21:39 23826 8192000000 10325844512 10782519296 1411759328 14.700 100 18 36 0 1433389968 17241968 4388672
00:23:07 38689 8192000000 10320728064 10780307456 1391134208 14.516 98 36 72 3 1415277664 15830416 8313040
00:24:47 95876 8192000000 10305728592 10761809920 1393224064 14.535 99 8 16 0 1403104128 8177664 1702400
00:27:43 69539 8192000000 10298564256 10758430720 1374363584 14.366 97 18 36 0 1389673920 10521728 4788608
00:30:03 26293 8192000000 10294999568 10775089152 1402335760 14.616 100 28 56 1 1424781488 16111808 6333920
00:31:33 74627 8192000000 10309339440 10779242496 1382780288 14.441 98 13 26 1 1392994544 7608288 2605968
00:33:48 29513 8192000000 10286805776 10748964864 1346623248 14.117 96 19 38 1 1359411056 8044896 4742912
00:35:22 18345 8192000000 10278767680 10725015552 1338247936 14.042 97 28 56 1 1353639456 8972240 6419280
00:36:41 35841 8192000000 10282867440 10731454464 1358122016 14.220 99 27 54 3 1376350832 11961632 6267184
00:38:40 49489 8192000000 10300796640 10759045120 1363772224 14.271 99 19 38 0 1389507840 21518848 4216768
00:40:43 86835 8192000000 10282762704 10745503744 1297921152 13.676 94 14 28 1 1323862320 22905552 3035616
00:43:10 63767 8192000000 10277277808 10718003200 1312638640 13.810 96 17 34 0 1326681312 10236720 3805952
00:45:28 53879 8192000000 10249634192 10699874304 1333884592 14.002 98 29 58 0 1352711056 12187200 6639264
00:47:22 60253 8192000000 10268712272 10705534976 1290791952 13.611 95 24 47 1 1303112816 6514624 5806240
00:49:26 63188 8192000000 10269915536 10729373696 1355272704 14.195 99 29 58 1 1376057824 13678336 7106784
00:51:32 50278 8192000000 10273981888 10729074688 1359767408 14.235 99 12 24 0 1377293200 14594576 2931216
00:53:24 96803 8192000000 10278153776 10756136960 1359790128 14.235 99 10 20 0 1380414464 18130576 2493760
00:56:01 82210 8192000000 10277271184 10741198848 1322988720 13.904 96 17 34 0 1340728176 13781824 3957632
00:58:27 70042 8192000000 10280418288 10737975296 1349507248 14.143 98 22 44 2 1364394880 10086336 4801296
01:01:15 54829 8192000000 10296382112 10745946112 1377476384 14.394 100 26 53 0 1399251504 16191040 5584080
01:03:13 31928 8192000000 10298073328 10745905152 1378329584 14.402 100 29 58 0 1394944976 10370032 6245360
01:04:47 54679 8192000000 10295671840 10756186112 1371561600 14.341 99 7 14 1 1387122000 13855168 1705232
01:06:44 115797 8192000000 10296044224 10763763712 1355798976 14.200 98 13 25 2 1378393376 19990224 2604176
01:10:53 37023 8192000000 10288491248 10745942016 1370221840 14.329 99 25 50 0 1389229360 13976256 5031264
01:12:33 71670 8192000000 10295332720 10763862016 1400607008 14.600 100 12 24 0 1409291472 6609904 2074560
01:14:45 55048 8192000000 10298857984 10759913472 1364143440 14.275 97 10 20 0 1373201264 6970176 2087648
01:16:42 36444 8192000000 10307635568 10770644992 1392809344 14.531 99 23 46 1 1406717056 8274800 5632912
01:18:22 42136 8192000000 10280041824 10741149696 1342814400 14.083 96 16 32 1 1356243264 9691280 3737584
01:20:05 47855 8192000000 10297821872 10760667136 1389028560 14.497 100 19 38 0 1402036080 8592640 4414880
01:21:53 41603 8192000000 10292515504 10758365184 1367026512 14.300 98 22 44 0 1382236544 10149296 5060736
01:23:35 59799 8192000000 10293400352 10743234560 1336167664 14.023 96 22 44 2 1349781216 9213280 4400272
01:25:38 25002 8192000000 10305896816 10750709760 1366997392 14.300 99 43 85 0 1393763168 17390816 9374960
01:27:11 69159 8192000000 10294291696 10752212992 1358228720 14.221 98 9 18 1 1370186576 9997040 1960816
01:29:20 116957 8192000000 10282013072 10733203456 1365753248 14.289 98 23 46 1 1379204512 8060592 5390672
01:32:18 16320 8192000000 10268119264 10722758656 1354477600 14.188 98 29 58 2 1375487376 14504832 6504944
01:34:03 42512 8192000000 10246322704 10713104384 1336460464 14.025 99 8 16 0 1351726528 13427872 1838192
01:35:45 49828 8192000000 10244507600 10701545472 1323698880 13.910 97 14 28 0 1337767840 10497296 3571664
01:37:36 60766 8192000000 9124187856 9381924864 288621728 3.403 22 3 5 0 295172688 5826224 724736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-04-2022 01:40:47 to 10-04-2022 01:41:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-04-2022 01:41:03 to 10-04-2022 01:41:05.

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          248409
MON$OLDEST_ACTIVE               248410
MON$OLDEST_SNAPSHOT             248410
MON$NEXT_TRANSACTION            248410
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                       2048160
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:149: 
:150: mscs-test-161.budzdorov.ru    Sun Apr 10 01:38:40 2022
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:152: 
--- end of diff output ---

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

10.04.22 01:41:06. 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
2022-04-10 01:41:09 No severe PSQL-related problems occured
10.04.22 01:41:10. End of report.