Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.03.2021 07:37
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16708784 kB
MemAvailable 31551824 kB
Buffers 0 kB
Cached 14910916 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-03-12 07:36:55
DTS_END                         2021-03-12 07:36:55.2400
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(68:17)
IP                              ::1/35376
TRN_ID                          235086
ATT_ID                          691
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: 8834.73 22420 1060167
customer order: creation 167.71 386 20125
customer order: refuse 14.38 355 1726
order to supplier: creation 45.21 1324 5425
order to supplier: removal 3.42 112750 410
invoice (draft): creation 44.33 3460 5320
invoice (draft): removal 50.44 932 6053
invoice accept: apply 40.13 17005 4815
invoice accept: cancel 45.38 888 5446
customer reserve: creation 3706.63 164 444795
customer reserve: removal 4429.33 68 531519
realization accept: apply 81.97 188 9836
realization accept: cancel 79.99 114 9599
payment from customer: creation 58.04 110 6965
payment from customer: removal 5.79 113 695
payment to supplier: creation 48.08 137 5769
payment to supplier: removal 3.53 94 423
service: total inventory turnovers 3.70 3585 444
service: total monetary turnovers 5.78 535 694
service: aggregate perf. data 0.87 17179 104
service: refresh index statistics 0.03 289018 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-03-2021 07:38:09 to 12-03-2021 07:38:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3623
TEST_TIME 2 2786
TEST_TIME 3 2512
TEST_TIME 4 3568
TEST_TIME 5 3727
TEST_TIME 6 4365
TEST_TIME 7 4399
TEST_TIME 8 4685
TEST_TIME 9 4761
TEST_TIME 10 4934
TEST_TIME 11 4841
TEST_TIME 12 4993
TEST_TIME 13 5231
TEST_TIME 14 5448
TEST_TIME 15 6041
TEST_TIME 16 6286
TEST_TIME 17 6447
TEST_TIME 18 6735
TEST_TIME 19 7704
TEST_TIME 20 7774
TEST_TIME 21 7807
TEST_TIME 22 8012
TEST_TIME 23 8286
TEST_TIME 24 8168
TEST_TIME 25 8284
TEST_TIME 26 8595
TEST_TIME 27 8949
TEST_TIME 28 9313
TEST_TIME 29 9144
TEST_TIME 30 9538
TEST_TIME 31 9911
TEST_TIME 32 10307
TEST_TIME 33 10076
TEST_TIME 34 9955
TEST_TIME 35 9850
TEST_TIME 36 10012
TEST_TIME 37 9958
TEST_TIME 38 9844
TEST_TIME 39 9741
TEST_TIME 40 9724
TEST_TIME 41 9751
TEST_TIME 42 9724
TEST_TIME 43 9740
TEST_TIME 44 9766
TEST_TIME 45 9717
TEST_TIME 46 9524
TEST_TIME 47 9413
TEST_TIME 48 9272
TEST_TIME 49 9319
TEST_TIME 50 9297
TEST_TIME 51 9260
TEST_TIME 52 9321
TEST_TIME 53 9344
TEST_TIME 54 9280
TEST_TIME 55 9257
TEST_TIME 56 9162
TEST_TIME 57 9027
TEST_TIME 58 9012
TEST_TIME 59 8984
TEST_TIME 60 8945
TEST_TIME 61 8968
TEST_TIME 62 9055
TEST_TIME 63 9096
TEST_TIME 64 9158
TEST_TIME 65 9194
TEST_TIME 66 9170
TEST_TIME 67 9211
TEST_TIME 68 9172
TEST_TIME 69 9180
TEST_TIME 70 9248
TEST_TIME 71 9331
TEST_TIME 72 9281
TEST_TIME 73 9281
TEST_TIME 74 9201
TEST_TIME 75 9137
TEST_TIME 76 9139
TEST_TIME 77 9075
TEST_TIME 78 8995
TEST_TIME 79 8957
TEST_TIME 80 8991
TEST_TIME 81 8975
TEST_TIME 82 8932
TEST_TIME 83 8910
TEST_TIME 84 8983
TEST_TIME 85 8931
TEST_TIME 86 9024
TEST_TIME 87 9107
TEST_TIME 88 9128
TEST_TIME 89 9196
TEST_TIME 90 9125
TEST_TIME 91 9238
TEST_TIME 92 9194
TEST_TIME 93 9099
TEST_TIME 94 9098
TEST_TIME 95 9054
TEST_TIME 96 9028
TEST_TIME 97 8997
TEST_TIME 98 9000
TEST_TIME 99 8972
TEST_TIME 100 8945
TEST_TIME 101 8909
TEST_TIME 102 8906
TEST_TIME 103 8889
TEST_TIME 104 9012
TEST_TIME 105 9123
TEST_TIME 106 9115
TEST_TIME 107 9150
TEST_TIME 108 9149
TEST_TIME 109 9091
TEST_TIME 110 9016
TEST_TIME 111 8986
TEST_TIME 112 8994
TEST_TIME 113 8959
TEST_TIME 114 8917
TEST_TIME 115 8865
TEST_TIME 116 8855
TEST_TIME 117 8886
TEST_TIME 118 8871
TEST_TIME 119 8857
TEST_TIME 120 8838

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-03-2021 07:38:13 to 12-03-2021 07:38:16.

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 1473151 1473151 0 0.00 0 28489 7 0 0
doc_list_biud 1473150 1473150 0 0.00 0 57416 32 0 0
sp_add_invoice_to_stock 4940 4815 125 2.53 29 187483 17006 0 125
sp_cancel_adding_invoice 5446 5446 0 0.00 6 68287 888 0 0
sp_cancel_client_order 1726 1726 0 0.00 4 14264 356 0 0
sp_cancel_customer_reserve 531519 531519 0 0.00 1 57417 69 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 2 15589 113 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 7440 94 0 0
sp_cancel_supplier_invoice 6059 6053 6 0.09 2 32936 933 0 6
sp_cancel_supplier_order 410 410 0 0.00 12 1188357 112750 0 0
sp_cancel_write_off 9599 9599 0 0.00 1 13883 114 0 0
sp_client_order 20125 20125 0 0.00 4 22193 387 0 0
sp_customer_reserve 444795 444795 0 0.00 0 90418 165 0 0
sp_fill_shopping_cart 30859 30859 0 0.00 1 20569 41 0 0
sp_fill_shopping_cart_clo_res 444790 444790 0 0.00 0 16130 15 0 0
sp_get_clo_for_invoice 4810 4810 0 0.00 0 34166 361 0 0
sp_kill_cost_storno 16163 16163 0 0.00 0 15576 63 0 0
sp_kill_qstorno_ret_qs2qd 1079100 1079100 0 0.00 0 57414 37 0 0
sp_kill_qty_storno 569399 569399 0 0.00 0 57416 81 0 0
sp_lock_selected_doc 20338 20338 0 0.00 0 12318 5 0 0
sp_make_cost_storno 27380 27380 0 0.00 0 22469 64 0 0
sp_multiply_rows_for_pdistr 27380 27380 0 0.00 0 15493 34 0 0
sp_multiply_rows_for_qdistr 20125 20125 0 0.00 0 22184 213 0 0
sp_pay_from_customer 6965 6965 0 0.00 4 16600 110 0 0
sp_pay_to_supplier 5769 5769 0 0.00 5 18027 138 0 0
sp_payment_common 12734 12734 0 0.00 1 12358 21 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 14251 333 0 0
sp_qd_handle_on_invoice_upd_sts 10256 10256 0 0.00 0 17220 417 0 0
sp_qd_handle_on_reserve_upd_sts 9836 9836 0 0.00 0 28065 52 0 0
sp_reserve_write_off 9836 9836 0 0.00 7 28086 189 0 0
sp_supplier_invoice 5320 5320 0 0.00 6 116675 3460 0 0
sp_supplier_order 5425 5425 0 0.00 5 27004 1324 0 0
srv_aggregate_perf_data 104 104 0 0.00 2840 206324 17179 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1667 133401 45134 0 0
srv_find_qd_qs_mism 1014356 1014356 0 0.00 0 25454 5 0 0
srv_make_invnt_saldo 444 444 0 0.00 46 103993 3586 0 0
srv_make_money_saldo 694 694 0 0.00 0 19973 535 0 0
srv_recalc_idx_stat 4 4 0 0.00 99536 452377 289019 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4815 4815 0 0.00 0 18756 172 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 6348 83 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 3992 11 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 637 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1167 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 2736 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 471 13 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 19500 1419 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 2745 8 0 0
t$perf-norm:sp_client_order 20125 20125 0 0.00 0 19360 20 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 648 6 0 0
t$perf-norm:sp_pay_from_customer 6965 6965 0 0.00 0 3074 7 0 0
t$perf-norm:sp_pay_to_supplier 5769 5769 0 0.00 0 5614 6 0 0
t$perf-norm:sp_reserve_write_off 9836 9836 0 0.00 0 19468 13 0 0
t$perf-norm:sp_supplier_invoice 5320 5320 0 0.00 0 5710 27 0 0
t$perf-norm:sp_supplier_order 5425 5425 0 0.00 0 10019 16 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 565 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1269 29 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 745 10 0 0
t$perf-norm:srv_make_money_saldo 694 694 0 0.00 0 618 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 5 0 0
v_add_invoice_to_stock 9868 9618 250 2.53 0 6139 17 0 250
v_all_customers 36696 36696 0 0.00 0 41 1 0 0
v_all_suppliers 29730 29730 0 0.00 0 76 1 0 0
v_all_wares 40248 40248 0 0.00 0 50 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 306 6 0 0
v_cancel_client_order 3452 3452 0 0.00 0 2620 6 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 1204 7 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 1398 5 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1525 5 0 0
v_cancel_supplier_invoice 824 812 12 1.45 0 3706 21 0 12
v_cancel_supplier_order 820 820 0 0.00 0 87 2 0 0
v_cancel_write_off 1960 1960 0 0.00 0 2923 15 0 0
v_max_id_clo_ord 5422 5422 0 0.00 0 3176 2 0 0
v_max_id_clo_res 988 988 0 0.00 0 2831 20 0 0
v_max_id_ord_sup 5313 5313 0 0.00 0 9717 8 0 0
v_max_non_paid_invoice 1639 1639 0 0.00 0 66 2 0 0
v_max_non_paid_realizn 3739 3739 0 0.00 0 3132 4 0 0
v_min_id_clo_ord 5422 5422 0 0.00 0 3230 6 0 0
v_min_id_clo_res 988 988 0 0.00 0 2065 6 0 0
v_min_id_ord_sup 5313 5313 0 0.00 0 6976 5 0 0
v_min_non_paid_invoice 5769 5769 0 0.00 0 6803 10 0 0
v_min_non_paid_realizn 6965 6965 0 0.00 0 9588 17 0 0
v_our_firm 10004 10004 0 0.00 0 17 1 0 0
v_reserve_write_off 19672 19672 0 0.00 0 3156 6 0 0
x_lock_depdocs_on_canc_invoice 11093 11093 0 0.00 0 25064 114 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 21759 310 0 0
x_make_qstorno_1000_1200 5425 5425 0 0.00 2 26918 1242 0 0
x_make_qstorno_1200_2000 5320 5320 0 0.00 3 116668 3312 0 0
x_make_qstorno_2100_3300 444795 444795 0 0.00 0 90415 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-03-2021 07:38:17 to 12-03-2021 07:38:21.

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
05:06:59 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:08:00 31716 8192000000 9729036320 10024665088 798125472 8.877 100 87 174 1 827365632 7839728 21400432
05:09:42 16448 8192000000 9761127200 10068021248 833248912 9.232 100 87 174 0 866706096 12064464 21392720
05:11:04 15641 8192000000 9797798800 10114334720 866864048 9.569 100 86 172 2 908084448 20702160 20518240
05:12:28 10660 8192000000 9872233760 10208997376 921279488 10.109 100 76 150 1 983233360 45336896 16616976
05:13:41 8523 8192000000 9942488704 10284199936 965953824 10.547 100 79 158 1 1049220672 66525008 16741840
05:14:52 4724 8192000000 9969546624 10323537920 1000751168 10.886 100 81 160 0 1076551296 59017584 16782544
05:15:58 3094 8192000000 10008025456 10372636672 1023555616 11.106 100 85 168 1 1123272560 82853632 16863312
05:17:02 2092 8192000000 10026205648 10391379968 1043407296 11.297 100 90 178 1 1136715376 74841584 18466496
05:18:06 1974 8192000000 10023551936 10394836992 1065203472 11.506 100 93 184 3 1139437952 55218448 19016032
05:19:09 5925 8192000000 9999801456 10397614080 1065009392 11.504 100 31 62 0 1127974288 56230128 6734768
05:20:17 4025 8192000000 9994583488 10383151104 1074003088 11.590 100 38 74 1 1106226608 24037504 8186016
05:21:22 9382 8192000000 9998936560 10398261248 1079281792 11.641 100 34 68 0 1118728992 32451952 6995248
05:22:32 61708 8192000000 9988447056 10389962752 1082679776 11.673 100 30 60 1 1113574320 23317040 7577504
05:24:34 5354 8192000000 10021423552 10416807936 1091950848 11.761 100 44 87 0 1137651888 36789040 8912000
05:25:42 5091 8192000000 10016218608 10413572096 1081840160 11.665 99 35 69 1 1115414864 26093040 7481664
05:26:49 9438 8192000000 10001934800 10408112128 1059596304 11.453 97 32 58 3 1079305440 13621424 6087712
05:28:05 28371 8192000000 9974567040 10381008896 1040491440 11.269 97 41 82 3 1076911616 27110912 9309264
05:29:36 3407 8192000000 9955851104 10335981568 1029226144 11.161 99 46 95 0 1049876896 10895392 9755360
05:30:40 9940 8192000000 9949452992 10343120896 1036923504 11.235 100 46 90 4 1070805952 23073296 10809152
05:31:51 6736 8192000000 9944833968 10335948800 1021650736 11.088 99 35 70 1 1055732528 25957152 8124640
05:33:03 35883 8192000000 9947238784 10358714368 1044371744 11.307 100 27 54 1 1080364432 29756576 6236112
05:34:40 6807 8192000000 9974301104 10369384448 1057746720 11.435 100 39 77 0 1077997920 12021808 8229392
05:35:48 7965 8192000000 9985269728 10396213248 1066852080 11.522 100 49 98 0 1095069328 17345392 10871856
05:36:59 28747 8192000000 10009587568 10407772160 1093169584 11.773 100 39 75 1 1118614944 17254000 8191360
05:38:35 28678 8192000000 10048974272 10448691200 1108310800 11.916 100 45 90 1 1154913776 37059392 9543584
05:40:11 14425 8192000000 10122224656 10524889088 1122049824 12.046 100 58 115 3 1226344960 92675136 11620000
05:41:28 23035 8192000000 10115690544 10528043008 1129033552 12.112 100 39 78 0 1226983920 89581696 8368672
05:42:56 3268 8192000000 10154095920 10553901056 1144551344 12.258 100 61 119 1 1258861936 101461936 12848656
05:44:04 4736 8192000000 10184855808 10591772672 1150583184 12.315 100 55 109 0 1294534992 132473152 11478656
05:45:13 7622 8192000000 10177484432 10603892736 1156341008 12.369 100 49 98 1 1284159280 116332544 11485728
05:46:24 5627 8192000000 10260768704 10669211648 1173901184 12.533 100 61 122 1 1360905456 173775888 13228384
05:47:37 11393 8192000000 10272138912 10685841408 1171006560 12.506 100 60 119 1 1376110096 191575840 13527696
05:48:52 11403 8192000000 10354236896 10772332544 1177902032 12.571 100 60 120 0 1456083184 263779280 14401872
05:50:07 13220 8192000000 10334902160 10771128320 1172235328 12.518 100 53 106 0 1440229888 255328816 12665744
05:51:25 4761 8192000000 10339159408 10754605056 1170385824 12.500 100 81 163 0 1435761536 247559952 17815760
05:52:36 4685 8192000000 10362961568 10772533248 1179670832 12.587 100 76 152 0 1460104800 262939824 17494144
05:53:46 1666 8192000000 10412940256 10813923328 1169878832 12.496 100 93 186 2 1504028896 313033216 21116848
05:54:57 2058 8192000000 10366420448 10793877504 1162517472 12.427 100 76 150 2 1481483616 302626288 16339856
05:56:01 9554 8192000000 10274333136 10712047616 1110775008 11.940 99 26 52 1 1363176880 245544416 6857456
05:57:30 8731 8192000000 10300643248 10723454976 1125958560 12.083 100 39 78 2 1393328608 257851200 9518848
05:58:43 6873 8192000000 10292422336 10717396992 1132400160 12.144 100 52 102 0 1393006640 247957552 12648928
05:59:56 6074 8192000000 10299488784 10721714176 1152312720 12.331 100 50 97 2 1393019888 229390176 11316992
06:01:09 2940 8192000000 10362514304 10765783040 1156846032 12.374 100 90 179 0 1453192720 276331776 20014912
06:02:18 15926 8192000000 10336041616 10799132672 1158321936 12.388 100 31 62 0 1463822784 296843760 8657088
06:03:45 11739 8192000000 10323200816 10762276864 1141710848 12.232 99 28 56 1 1433866064 284987856 7167360
06:05:05 41631 8192000000 10327021504 10766135296 1149200272 12.302 100 22 44 1 1425419360 270291632 5927456
06:07:01 23760 8192000000 10141231568 10633900032 1119699920 12.024 98 19 38 0 1284953328 161005712 4247696
06:08:26 86736 8192000000 10076420512 10551996416 1113963904 11.970 98 13 26 0 1202816240 85802224 3050112
06:10:53 48380 8192000000 10122301648 10579808256 1138126208 12.198 100 9 18 0 1198782320 58871840 1784272
06:12:49 99285 8192000000 10064693520 10514882560 1130842400 12.129 99 11 20 1 1181059360 47523808 2693152
06:15:51 65225 8192000000 10094367888 10542907392 1146208832 12.274 100 17 34 0 1174715280 25182384 3324064
06:18:04 93132 8192000000 10085397168 10527744000 1106203648 11.896 96 11 22 0 1156442784 47444032 2795104
06:20:37 99468 8192000000 10119293248 10595962880 1133835440 12.158 98 12 24 1 1178943888 42568000 2540448
06:23:43 75540 8192000000 10116970864 10570878976 1149910480 12.309 100 22 45 1 1215411680 60814064 4687136
06:26:00 46316 8192000000 10111008832 10544705536 1130839856 12.129 98 44 89 1 1209624176 69113056 9671264
06:27:48 37551 8192000000 10136907008 10615332864 1138266288 12.199 99 14 28 3 1239010208 97536000 3207920
06:29:44 7912 8192000000 10064394592 10561163264 1146815648 12.280 100 17 34 0 1173871552 23043664 4012240
06:30:53 20267 8192000000 10047795936 10492260352 1113117824 11.962 98 15 29 1 1139524864 23381024 3026016
06:32:58 61234 8192000000 10073551776 10529636352 1115387184 11.983 98 17 34 1 1160805168 41991744 3426240
06:35:52 60985 8192000000 10082920144 10541109248 1118584432 12.014 98 10 19 2 1164716304 43893280 2238592
06:37:56 85170 8192000000 10106395840 10583949312 1106586240 11.900 96 17 34 0 1213897648 103396112 3915296
06:40:22 60564 8192000000 10070247040 10516037632 1068552160 11.538 94 21 42 1 1104771808 31915008 4304640
06:42:27 49422 8192000000 10106403168 10556600320 1098628656 11.825 96 13 26 0 1165583776 64129296 2825824
06:44:21 25418 8192000000 10098971056 10538225664 1132304160 12.143 100 48 96 0 1182434464 40580400 9549904
06:45:47 79875 8192000000 10093045264 10579988480 1103375984 11.870 97 15 30 0 1182007744 75568544 3063216
06:48:07 51264 8192000000 10061687632 10501709824 1058825600 11.445 94 31 62 1 1093270944 28074384 6370960
06:50:00 110198 8192000000 10087403936 10547195904 1113900608 11.969 99 31 62 2 1190600144 70157920 6541616
06:53:13 48755 8192000000 10060380976 10541977600 1103165264 11.868 98 6 12 0 1146168624 41782208 1221152
06:55:04 76688 8192000000 10049374560 10529009664 1099556992 11.833 98 7 14 0 1151566880 50590288 1419600
06:57:35 49466 8192000000 10086640032 10540781568 1109957824 11.932 98 21 42 2 1172663904 58245024 4461056
06:59:38 45314 8192000000 10174039472 10650021888 1127122400 12.094 99 26 52 0 1273774032 140515920 6135712
07:01:23 61750 8192000000 10142964720 10626957312 1141111632 12.226 100 18 36 0 1283999120 138749872 4137616
07:03:39 71234 8192000000 10088282272 10556047360 1118488208 12.013 99 19 38 0 1165133472 42646480 3998784
07:05:51 70140 8192000000 10129244192 10564136960 1126549136 12.089 99 29 58 2 1186500944 53790544 6161264
07:08:34 73738 8192000000 10107296208 10555858944 1142003488 12.234 100 19 38 2 1201801936 55872880 3925568
07:10:59 86640 8192000000 10064986288 10544631808 1085092672 11.696 95 8 16 0 1125849856 39268256 1488928
07:13:29 106893 8192000000 10080231136 10556858368 1089430160 11.737 96 20 43 0 1113221120 19497360 4293600
07:16:16 49137 8192000000 10133468912 10578464768 1094293728 11.783 96 27 54 0 1174743472 74609600 5840144
07:18:11 75156 8192000000 10215406624 10681761792 1149903856 12.309 100 20 40 1 1296013856 141366320 4743680
07:20:30 77826 8192000000 10099979808 10661179392 1149889616 12.308 100 15 30 1 1312108656 158433600 3785440
07:22:58 26674 8192000000 10106744032 10549551104 1152845744 12.336 100 42 84 0 1196123328 34792624 8484960
07:24:29 48115 8192000000 10112387024 10552066048 1120263232 12.029 97 24 48 0 1142503328 16805040 5435056
07:26:22 133401 8192000000 10051073232 10540716032 1085822064 11.703 95 14 28 0 1146206624 57512000 2872560
07:30:15 59034 8192000000 10078645104 10530648064 1122009280 12.046 99 6 12 0 1166055696 43031328 1015088
07:33:34 73592 8192000000 10075631600 10529087488 1079846736 11.646 96 16 31 2 1111692032 29132448 2712848
07:35:52 53308 8192000000 10107606944 10542571520 1112424688 11.955 98 38 76 0 1182033568 61344400 8264480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-03-2021 07:38:23 to 12-03-2021 07:38:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-03-2021 07:38:39 to 12-03-2021 07:38:41.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          230803
MON$OLDEST_ACTIVE               235195
MON$OLDEST_SNAPSHOT             235195
MON$NEXT_TRANSACTION            235195
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-30 07:30:08.6030 GMT
MON$PAGES                       2122496
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2386 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2386 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2386 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2386 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Mar 12 07:36:58 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Mar 12 07:36:58 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Mar 12 07:36:59 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri Mar 12 07:37:00 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

12.03.21 07:38:42. There are 3 logs in /var/tmp/logs-oltp40 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-03-12 07:38:44 No severe PSQL-related problems occured
12.03.21 07:38:45. End of report.