Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.09.2021 10: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 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15866348 kB
MemAvailable 30799768 kB
Buffers 0 kB
Cached 15817536 kB
SwapCached 668 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.1
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-09-21 10:37:08
DTS_END                         2021-09-21 10:37:08.5680
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(68:17)
IP                              ::1/50508
TRN_ID                          236089
ATT_ID                          599
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: 8889.91 27175 1066789
customer order: creation 161.82 397 19418
customer order: refuse 13.81 361 1657
order to supplier: creation 44.91 1578 5389
order to supplier: removal 3.43 111510 412
invoice (draft): creation 43.98 3799 5277
invoice (draft): removal 52.03 946 6244
invoice accept: apply 39.82 18885 4778
invoice accept: cancel 46.93 836 5631
customer reserve: creation 3646.14 186 437537
customer reserve: removal 4555.76 66 546691
realization accept: apply 81.25 202 9750
realization accept: cancel 77.15 139 9258
payment from customer: creation 56.79 120 6815
payment from customer: removal 5.59 98 671
payment to supplier: creation 46.71 135 5605
payment to supplier: removal 3.35 99 402
service: total inventory turnovers 3.58 4044 430
service: total monetary turnovers 6.04 642 725
service: aggregate perf. data 0.79 19113 95
service: refresh index statistics 0.03 380334 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-09-2021 10:38:17 to 21-09-2021 10:38:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4931
TEST_TIME 2 4559
TEST_TIME 3 5609
TEST_TIME 4 5701
TEST_TIME 5 6257
TEST_TIME 6 6157
TEST_TIME 7 6087
TEST_TIME 8 6118
TEST_TIME 9 6179
TEST_TIME 10 6378
TEST_TIME 11 6941
TEST_TIME 12 7058
TEST_TIME 13 7203
TEST_TIME 14 7330
TEST_TIME 15 7479
TEST_TIME 16 7674
TEST_TIME 17 7782
TEST_TIME 18 8021
TEST_TIME 19 8308
TEST_TIME 20 8353
TEST_TIME 21 8580
TEST_TIME 22 8778
TEST_TIME 23 9077
TEST_TIME 24 9451
TEST_TIME 25 9215
TEST_TIME 26 9321
TEST_TIME 27 9351
TEST_TIME 28 9206
TEST_TIME 29 11214
TEST_TIME 30 11092
TEST_TIME 31 10896
TEST_TIME 32 10606
TEST_TIME 33 10423
TEST_TIME 34 10507
TEST_TIME 35 10291
TEST_TIME 36 10185
TEST_TIME 37 10055
TEST_TIME 38 10006
TEST_TIME 39 9957
TEST_TIME 40 9826
TEST_TIME 41 9690
TEST_TIME 42 9554
TEST_TIME 43 9619
TEST_TIME 44 9495
TEST_TIME 45 9419
TEST_TIME 46 9358
TEST_TIME 47 9360
TEST_TIME 48 9370
TEST_TIME 49 9317
TEST_TIME 50 9295
TEST_TIME 51 9352
TEST_TIME 52 9301
TEST_TIME 53 9278
TEST_TIME 54 9271
TEST_TIME 55 9307
TEST_TIME 56 9383
TEST_TIME 57 9381
TEST_TIME 58 9426
TEST_TIME 59 9468
TEST_TIME 60 9610
TEST_TIME 61 9497
TEST_TIME 62 9387
TEST_TIME 63 9300
TEST_TIME 64 9239
TEST_TIME 65 9226
TEST_TIME 66 9200
TEST_TIME 67 9172
TEST_TIME 68 9086
TEST_TIME 69 9052
TEST_TIME 70 9024
TEST_TIME 71 9085
TEST_TIME 72 9061
TEST_TIME 73 9030
TEST_TIME 74 9081
TEST_TIME 75 9112
TEST_TIME 76 9114
TEST_TIME 77 9080
TEST_TIME 78 9195
TEST_TIME 79 9248
TEST_TIME 80 9214
TEST_TIME 81 9233
TEST_TIME 82 9264
TEST_TIME 83 9281
TEST_TIME 84 9269
TEST_TIME 85 9192
TEST_TIME 86 9154
TEST_TIME 87 9114
TEST_TIME 88 9129
TEST_TIME 89 9100
TEST_TIME 90 9063
TEST_TIME 91 9065
TEST_TIME 92 8998
TEST_TIME 93 9018
TEST_TIME 94 9058
TEST_TIME 95 9034
TEST_TIME 96 9007
TEST_TIME 97 9085
TEST_TIME 98 9094
TEST_TIME 99 9089
TEST_TIME 100 9086
TEST_TIME 101 9178
TEST_TIME 102 9173
TEST_TIME 103 9266
TEST_TIME 104 9282
TEST_TIME 105 9290
TEST_TIME 106 9206
TEST_TIME 107 9153
TEST_TIME 108 9137
TEST_TIME 109 9106
TEST_TIME 110 9053
TEST_TIME 111 9048
TEST_TIME 112 9004
TEST_TIME 113 9026
TEST_TIME 114 9008
TEST_TIME 115 8996
TEST_TIME 116 8975
TEST_TIME 117 8986
TEST_TIME 118 8969
TEST_TIME 119 8959
TEST_TIME 120 8901

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-09-2021 10:38:20 to 21-09-2021 10:38:24.

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 1472270 1472270 0 0.00 0 32790 7 0 0
doc_list_biud 1472269 1472269 0 0.00 0 30935 31 0 0
sp_add_invoice_to_stock 4860 4778 82 1.68 55 162923 18885 0 82
sp_cancel_adding_invoice 5631 5631 0 0.00 5 64499 836 0 0
sp_cancel_client_order 1657 1657 0 0.00 4 26910 362 0 0
sp_cancel_customer_reserve 546691 546691 0 0.00 1 29286 66 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 7963 98 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 6517 99 0 0
sp_cancel_supplier_invoice 6251 6244 7 0.11 2 23707 946 0 7
sp_cancel_supplier_order 412 412 0 0.00 7 951467 111511 0 0
sp_cancel_write_off 9258 9258 0 0.00 1 12743 139 0 0
sp_client_order 19418 19418 0 0.00 4 29333 397 0 0
sp_customer_reserve 437537 437537 0 0.00 0 48583 187 0 0
sp_fill_shopping_cart 30076 30076 0 0.00 1 25188 51 0 0
sp_fill_shopping_cart_clo_res 437524 437524 0 0.00 0 14222 16 0 0
sp_get_clo_for_invoice 4765 4765 0 0.00 0 22179 407 0 0
sp_kill_cost_storno 15962 15962 0 0.00 0 7957 67 0 0
sp_kill_qstorno_ret_qs2qd 1109296 1109296 0 0.00 0 29250 36 0 0
sp_kill_qty_storno 584408 584408 0 0.00 0 30935 77 0 0
sp_lock_selected_doc 20102 20102 0 0.00 0 7701 5 0 0
sp_make_cost_storno 26935 26935 0 0.00 0 32783 65 0 0
sp_multiply_rows_for_pdistr 26935 26935 0 0.00 0 9719 35 0 0
sp_multiply_rows_for_qdistr 19418 19418 0 0.00 0 26648 189 0 0
sp_pay_from_customer 6815 6815 0 0.00 4 10029 120 0 0
sp_pay_to_supplier 5605 5605 0 0.00 5 15502 135 0 0
sp_payment_common 12419 12419 0 0.00 1 14870 26 0 0
sp_qd_handle_on_cancel_clo 1657 1657 0 0.00 0 26903 333 0 0
sp_qd_handle_on_invoice_upd_sts 10396 10396 0 0.00 0 30935 343 0 0
sp_qd_handle_on_reserve_upd_sts 9750 9750 0 0.00 0 27529 67 0 0
sp_reserve_write_off 9750 9750 0 0.00 7 27543 203 0 0
sp_supplier_invoice 5277 5277 0 0.00 7 89849 3799 0 0
sp_supplier_order 5389 5389 0 0.00 6 43647 1579 0 0
srv_aggregate_perf_data 95 95 0 0.00 3098 286028 19114 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1551 110808 47344 0 0
srv_find_qd_qs_mism 1021603 1021603 0 0.00 0 15094 6 0 0
srv_make_invnt_saldo 430 430 0 0.00 56 115001 4044 0 0
srv_make_money_saldo 725 725 0 0.00 0 15866 643 0 0
srv_recalc_idx_stat 4 4 0 0.00 263306 502813 380335 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4782 4782 0 0.00 0 7151 169 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 6948 158 0 0
t$perf-norm:sp_cancel_client_order 1657 1657 0 0.00 0 4808 16 0 0
t$perf-norm:sp_cancel_customer_reserve 633 633 0 0.00 0 2036 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 580 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 5722 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 423 17 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 17908 1634 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 814 5 0 0
t$perf-norm:sp_client_order 19418 19418 0 0.00 0 23220 20 0 0
t$perf-norm:sp_customer_reserve 917 917 0 0.00 0 3657 13 0 0
t$perf-norm:sp_pay_from_customer 6815 6815 0 0.00 0 6417 14 0 0
t$perf-norm:sp_pay_to_supplier 5605 5605 0 0.00 0 7473 15 0 0
t$perf-norm:sp_reserve_write_off 9750 9750 0 0.00 0 6615 15 0 0
t$perf-norm:sp_supplier_invoice 5277 5277 0 0.00 0 21552 22 0 0
t$perf-norm:sp_supplier_order 5389 5389 0 0.00 0 3502 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 1141 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 87 2 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 413 9 0 0
t$perf-norm:srv_make_money_saldo 725 725 0 0.00 0 611 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 32 10 0 0
v_add_invoice_to_stock 9694 9530 164 1.69 0 8872 17 0 164
v_all_customers 35480 35480 0 0.00 0 140 1 0 0
v_all_suppliers 29338 29338 0 0.00 0 30 1 0 0
v_all_wares 38834 38834 0 0.00 0 224 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1717 10 0 0
v_cancel_client_order 3314 3314 0 0.00 0 717 3 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 313 4 0 0
v_cancel_customer_reserve 1266 1266 0 0.00 0 525 4 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 2001 7 0 0
v_cancel_supplier_invoice 822 808 14 1.70 0 380 14 0 14
v_cancel_supplier_order 824 824 0 0.00 0 693 5 0 0
v_cancel_write_off 2016 2016 0 0.00 0 3957 18 0 0
v_max_id_clo_ord 5387 5387 0 0.00 0 15855 5 0 0
v_max_id_clo_res 917 917 0 0.00 0 4883 17 0 0
v_max_id_ord_sup 5272 5272 0 0.00 0 9949 10 0 0
v_max_non_paid_invoice 1594 1594 0 0.00 0 50 2 0 0
v_max_non_paid_realizn 3699 3699 0 0.00 0 925 3 0 0
v_min_id_clo_ord 5387 5387 0 0.00 0 4090 9 0 0
v_min_id_clo_res 917 917 0 0.00 0 4760 28 0 0
v_min_id_ord_sup 5272 5272 0 0.00 0 6198 12 0 0
v_min_non_paid_invoice 5604 5604 0 0.00 0 14865 17 0 0
v_min_non_paid_realizn 6815 6815 0 0.00 0 9642 19 0 0
v_our_firm 9586 9586 0 0.00 0 16 1 0 0
v_reserve_write_off 19500 19500 0 0.00 0 5449 5 0 0
x_lock_depdocs_on_canc_invoice 11471 11471 0 0.00 0 12467 126 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 6076 317 0 0
x_make_qstorno_1000_1200 5389 5389 0 0.00 2 43619 1473 0 0
x_make_qstorno_1200_2000 5277 5277 0 0.00 3 89836 3619 0 0
x_make_qstorno_2100_3300 437537 437537 0 0.00 0 48579 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-09-2021 10:38:25 to 21-09-2021 10:38:29.

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
08:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 24250 8192000000 9720127104 10016296960 794634096 8.842 100 88 175 1 823792832 7863168 21295568
08:09:43 23659 8192000000 9751737936 10056249344 822945536 9.128 100 89 178 0 857297616 12701968 21650112
08:11:12 15921 8192000000 9792457600 10112507904 855391472 9.454 100 77 152 0 895358064 22287968 17678624
08:12:30 15001 8192000000 9861373632 10195791872 915191936 10.049 100 80 160 0 970681296 37848464 17640896
08:13:50 10667 8192000000 9917291696 10264883200 951955600 10.410 100 79 157 0 1032105984 62720384 17430000
08:15:04 5836 8192000000 9956775536 10313969664 997208256 10.851 100 74 148 2 1071896640 59633792 15054592
08:16:12 3020 8192000000 10034583680 10392760320 1025267216 11.123 100 89 177 1 1135793808 92905088 17621504
08:17:16 1747 8192000000 10033361392 10403270656 1057008272 11.428 100 89 179 0 1142991376 67367600 18615504
08:18:20 3830 8192000000 10023756480 10409627648 1074931648 11.599 100 63 128 0 1145543264 57984608 12627008
08:19:24 22384 8192000000 10006006112 10400231424 1076845664 11.617 100 34 68 3 1101122496 17692096 6584736
08:20:51 5869 8192000000 9996103952 10396577792 1084599328 11.691 100 40 80 0 1108687408 15393072 8695008
08:22:02 5355 8192000000 10038820736 10433986560 1099163280 11.830 100 52 103 2 1149319328 39929392 10226656
08:23:09 70614 8192000000 10025779744 10445901824 1105314864 11.888 100 36 72 2 1133466912 19570176 8581872
08:25:20 5900 8192000000 10041725056 10442412032 1110407008 11.936 100 43 84 1 1153203296 34075696 8720592
08:26:28 4096 8192000000 10021296880 10427482112 1101438656 11.851 100 42 85 0 1135493456 24694912 9359888
08:27:35 4064 8192000000 9987602512 10369298432 1039894560 11.264 97 41 81 3 1067067040 18454688 8717792
08:28:47 16792 8192000000 9931087312 10318635008 977082880 10.656 92 31 62 0 1002045024 18010368 6951776
08:30:07 4738 8192000000 9970122560 10357268480 1041863632 11.283 100 52 103 1 1073197360 19171232 12162496
08:31:12 6001 8192000000 9940701296 10336284672 1022484448 11.096 99 35 70 1 1054636544 24674160 7477936
08:32:19 65468 8192000000 9954999984 10347466752 1031535296 11.183 99 28 56 3 1060010000 22157248 6317456
08:34:25 4577 8192000000 9986037984 10380660736 1062918352 11.484 100 57 113 2 1090791024 14963920 12908752
08:35:31 7238 8192000000 9997780320 10395987968 1070804976 11.560 100 47 93 2 1096141376 14889504 10446896
08:36:39 8878 8192000000 9995913392 10392326144 1078867168 11.637 100 24 48 0 1102812880 19025200 4920512
08:37:52 88201 8192000000 10032747232 10443964416 1097154688 11.811 100 40 80 1 1132677088 26918192 8604208
08:40:21 9002 8192000000 10067403904 10483281920 1109672608 11.929 100 47 93 1 1175133600 55570608 9890384
08:41:35 12519 8192000000 10098157248 10504810496 1119962608 12.027 100 62 124 1 1197298640 64103376 13232656
08:43:01 11551 8192000000 10124004528 10537480192 1139614528 12.212 100 42 83 0 1233907104 85420624 8871952
08:44:18 20163 8192000000 10148469856 10566385664 1146407584 12.276 100 37 73 0 1261864928 107034864 8422480
08:45:49 21783 8192000000 10179669920 10604789760 1158548800 12.390 100 42 84 0 1284041344 115937824 9554720
08:47:20 10356 8192000000 10239729744 10651230208 1177865408 12.570 100 68 135 1 1341346240 149088432 14392400
08:48:38 9825 8192000000 10241356912 10671706112 1162488656 12.427 100 63 124 0 1353539296 176915872 14134768
08:49:53 2271 8192000000 10233343296 10657058816 1171980256 12.515 100 80 157 2 1338861792 148945632 17935904
08:51:00 3374 8192000000 10267186384 10673082368 1173442416 12.529 100 75 147 0 1367878768 178384976 16051376
08:52:10 4017 8192000000 10289835216 10709929984 1160091424 12.404 100 77 150 2 1390386384 213908032 16386928
08:53:18 1550 8192000000 10300333856 10702151680 1157109232 12.376 100 86 168 0 1396916240 221582912 18224096
08:54:25 3181 8192000000 10330305920 10732486656 1151383632 12.322 100 80 160 1 1427497744 257757904 18356208
08:55:32 3204 8192000000 10366484960 10764443648 1157827504 12.383 100 88 169 3 1456252576 279803648 18621424
08:56:40 2294 8192000000 10348978512 10759307264 1149990304 12.309 99 82 164 2 1448898208 279695056 19212848
08:57:46 11562 8192000000 10423368608 10844528640 1149665648 12.306 100 41 82 0 1519819424 359176080 10977696
08:59:15 12163 8192000000 10410137744 10831921152 1136336016 12.181 98 51 100 0 1491598368 342357888 12904464
09:00:34 6328 8192000000 10370807584 10793263104 1157089888 12.376 100 51 100 1 1463726912 293921376 12715648
09:01:43 13441 8192000000 10356190896 10820874240 1135284976 12.171 98 31 62 0 1457408736 313330864 8792896
09:03:01 22823 8192000000 10422756032 10880430080 1149070720 12.301 99 30 60 3 1508001280 350870976 8059584
09:04:32 51582 8192000000 10457629664 10906497024 1125736576 12.081 99 22 45 1 1542032224 409791824 6503824
09:06:49 39525 8192000000 10087831568 10565812224 1117988352 12.008 99 13 26 1 1184740256 63757424 2994480
09:08:29 95103 8192000000 10090891296 10530758656 1125734336 12.081 100 46 91 1 1191510256 55719984 10055936
09:11:14 43533 8192000000 10039483744 10513973248 1097529904 11.814 98 28 55 0 1144930352 42121328 5279120
09:13:21 82755 8192000000 10075051568 10532040704 1113810992 11.968 99 25 49 0 1155564224 36542544 5210688
09:15:44 88353 8192000000 10075275840 10526883840 1131474320 12.135 100 19 36 1 1179193552 43808944 3910288
09:18:13 94557 8192000000 10141517216 10589081600 1148982608 12.300 100 19 38 0 1220211888 67049712 4179568
09:21:46 19046 8192000000 10087558704 10550804480 1137616944 12.193 99 32 64 0 1177892448 33830432 6445072
09:23:07 44603 8192000000 10103214016 10586660864 1139862896 12.214 99 15 30 1 1222632112 79707248 3061968
09:25:00 73183 8192000000 10038495664 10480533504 1090635760 11.749 95 19 38 2 1131600992 36954080 4011152
09:27:20 45273 8192000000 10085851120 10530103296 1150603632 12.315 100 31 62 0 1203426768 45881056 6942080
09:29:06 69034 8192000000 10081473824 10527010816 1111886272 11.950 96 8 15 0 1146647728 33136896 1624560
09:31:15 81159 8192000000 10123354208 10614235136 1140486016 12.220 99 18 36 0 1207203312 63021664 3695632
09:33:50 39544 8192000000 10091918512 10539249664 1110050416 11.933 97 24 48 0 1175432544 59761920 5620208
09:35:30 69747 8192000000 10098694928 10604261376 1095893952 11.799 97 11 22 0 1179895280 81727440 2273888
09:39:20 65319 8192000000 10058562032 10495713280 1102462240 11.861 97 41 82 1 1148963632 37791184 8710208
09:41:34 45155 8192000000 10115714304 10584752128 1134326048 12.162 100 32 64 1 1202286240 61042640 6917552
09:44:27 72426 8192000000 10073837440 10529042432 1110729296 11.939 98 7 14 0 1186608432 74397920 1481216
09:46:41 68993 8192000000 10114026192 10599354368 1136003616 12.178 100 18 36 0 1198373488 58671168 3698704
09:48:59 38184 8192000000 10072388832 10518368256 1104358720 11.879 97 13 27 0 1133463920 26376848 2728352
09:50:41 69522 8192000000 10115959696 10589499392 1089075968 11.734 95 12 24 1 1189028416 97270112 2682336
09:52:54 86115 8192000000 10098550976 10559320064 1074349472 11.594 95 12 24 0 1159176896 82386688 2440736
09:55:22 46032 8192000000 10069138832 10517348352 1043790464 11.301 93 12 24 0 1122047424 75405904 2851056
09:57:08 82703 8192000000 10063765824 10520096768 1102993632 11.866 99 28 56 0 1161593680 52946192 5653856
09:59:39 40221 8192000000 10066499888 10547949568 1105964048 11.894 99 21 42 0 1203115872 93156752 3995072
10:01:24 81352 8192000000 10020279040 10462572544 1110108528 11.933 99 14 28 0 1161671104 48625600 2936976
10:03:47 57736 8192000000 10081564704 10515566592 1110530048 11.937 99 19 38 0 1189253744 74530320 4193376
10:05:45 63689 8192000000 10067387968 10523770880 1119395184 12.021 99 12 24 0 1174386112 52647424 2343504
10:07:49 64269 8192000000 10091937456 10577027072 1127440432 12.097 100 15 30 0 1212578512 81647472 3490608
10:09:54 110808 8192000000 10051799712 10554716160 1109402928 11.927 98 9 18 0 1160328512 49170400 1755184
10:12:50 31955 8192000000 10079397344 10522402816 1130021072 12.122 99 21 42 1 1164883792 30530240 4332480
10:14:28 68354 8192000000 10071495312 10555514880 1130239632 12.124 99 18 36 0 1228700416 94541280 3919504
10:16:45 57681 8192000000 10164727856 10621440000 1142421296 12.238 99 31 62 0 1217615632 69114544 6079792
10:18:52 62431 8192000000 10098916624 10531549184 1107862848 11.912 96 15 28 1 1161656208 50933136 2860224
10:20:55 59375 8192000000 10086384128 10555621376 1122982544 12.055 98 20 40 1 1185870896 59207808 3680544
10:22:55 104743 8192000000 10078130160 10520121344 1146686816 12.278 100 37 74 0 1172501824 17801136 8013872
10:25:44 61060 8192000000 10087745984 10541682688 1131455184 12.135 98 22 44 2 1182176976 45817408 4904384
10:27:51 53593 8192000000 10115233744 10591768576 1135569440 12.174 99 11 22 0 1197416912 59706832 2140640
10:30:23 59044 8192000000 10059111584 10510278656 1108626784 11.919 97 9 18 0 1154446000 44182016 1637200
10:32:23 60418 8192000000 10062170688 10507702272 1100486448 11.842 96 25 50 0 1143865760 38536192 4843120
10:34:24 80632 8192000000 10064685472 10512252928 1089945744 11.742 96 16 32 0 1133520272 39932944 3641584
10:36:45 36841 8192000000 9388883456 9774997504 510382704 5.864 44 9 18 0 587529792 74852304 2294784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-09-2021 10:38:31 to 21-09-2021 10:38:45.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-09-2021 10:38:46 to 21-09-2021 10:38:48.

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          236429
MON$OLDEST_ACTIVE               236430
MON$OLDEST_SNAPSHOT             236430
MON$NEXT_TRANSACTION            236430
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                       2060512
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             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2613 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2613 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2613 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2613 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
--- end of diff output ---

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

21.09.21 10:38:49. 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-09-21 10:38:52 No severe PSQL-related problems occured
21.09.21 10:38:53. End of report.