Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.12.2020 13:27
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16665036 kB
MemAvailable 31380716 kB
Buffers 0 kB
Cached 14890656 kB
SwapCached 3696 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 2020-12-20 13:26:53
DTS_END                         2020-12-20 13:26:53.8010
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/59744
TRN_ID                          239068
ATT_ID                          611
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: 9210.06 25129 1105207
customer order: creation 168.46 397 20215
customer order: refuse 15.41 322 1849
order to supplier: creation 47.48 1281 5698
order to supplier: removal 3.30 112109 396
invoice (draft): creation 47.03 3241 5643
invoice (draft): removal 50.40 833 6048
invoice accept: apply 42.15 17308 5058
invoice accept: cancel 46.23 907 5547
customer reserve: creation 3950.03 167 474004
customer reserve: removal 4537.19 64 544463
realization accept: apply 84.53 176 10144
realization accept: cancel 88.67 118 10640
payment from customer: creation 59.28 105 7114
payment from customer: removal 5.42 68 650
payment to supplier: creation 49.75 121 5970
payment to supplier: removal 3.29 80 395
service: total inventory turnovers 4.02 2919 482
service: total monetary turnovers 6.57 452 788
service: aggregate perf. data 0.83 18591 99
service: refresh index statistics 0.03 343311 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-12-2020 13:28:09 to 20-12-2020 13:28:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2257
TEST_TIME 2 4160
TEST_TIME 3 5010
TEST_TIME 4 5467
TEST_TIME 5 5510
TEST_TIME 6 5467
TEST_TIME 7 5777
TEST_TIME 8 6822
TEST_TIME 9 6868
TEST_TIME 10 6862
TEST_TIME 11 6848
TEST_TIME 12 7308
TEST_TIME 13 7275
TEST_TIME 14 7408
TEST_TIME 15 7387
TEST_TIME 16 7748
TEST_TIME 17 7931
TEST_TIME 18 8614
TEST_TIME 19 9018
TEST_TIME 20 8960
TEST_TIME 21 9091
TEST_TIME 22 9146
TEST_TIME 23 9515
TEST_TIME 24 9871
TEST_TIME 25 10537
TEST_TIME 26 11751
TEST_TIME 27 11561
TEST_TIME 28 11551
TEST_TIME 29 11897
TEST_TIME 30 11679
TEST_TIME 31 11436
TEST_TIME 32 11346
TEST_TIME 33 11352
TEST_TIME 34 11309
TEST_TIME 35 11082
TEST_TIME 36 10851
TEST_TIME 37 10959
TEST_TIME 38 10833
TEST_TIME 39 10835
TEST_TIME 40 10818
TEST_TIME 41 10685
TEST_TIME 42 10549
TEST_TIME 43 10490
TEST_TIME 44 10511
TEST_TIME 45 10463
TEST_TIME 46 10434
TEST_TIME 47 10480
TEST_TIME 48 10391
TEST_TIME 49 10428
TEST_TIME 50 10456
TEST_TIME 51 10521
TEST_TIME 52 10464
TEST_TIME 53 10439
TEST_TIME 54 10464
TEST_TIME 55 10428
TEST_TIME 56 10390
TEST_TIME 57 10412
TEST_TIME 58 10464
TEST_TIME 59 10302
TEST_TIME 60 10199
TEST_TIME 61 10239
TEST_TIME 62 10148
TEST_TIME 63 10053
TEST_TIME 64 9969
TEST_TIME 65 9944
TEST_TIME 66 9888
TEST_TIME 67 9901
TEST_TIME 68 9844
TEST_TIME 69 9835
TEST_TIME 70 9776
TEST_TIME 71 9821
TEST_TIME 72 9788
TEST_TIME 73 9760
TEST_TIME 74 9805
TEST_TIME 75 9861
TEST_TIME 76 9814
TEST_TIME 77 9880
TEST_TIME 78 9838
TEST_TIME 79 9944
TEST_TIME 80 9843
TEST_TIME 81 9731
TEST_TIME 82 9705
TEST_TIME 83 9667
TEST_TIME 84 9589
TEST_TIME 85 9551
TEST_TIME 86 9503
TEST_TIME 87 9484
TEST_TIME 88 9500
TEST_TIME 89 9471
TEST_TIME 90 9524
TEST_TIME 91 9501
TEST_TIME 92 9476
TEST_TIME 93 9470
TEST_TIME 94 9536
TEST_TIME 95 9531
TEST_TIME 96 9526
TEST_TIME 97 9495
TEST_TIME 98 9400
TEST_TIME 99 9381
TEST_TIME 100 9333
TEST_TIME 101 9274
TEST_TIME 102 9311
TEST_TIME 103 9326
TEST_TIME 104 9302
TEST_TIME 105 9298
TEST_TIME 106 9288
TEST_TIME 107 9316
TEST_TIME 108 9300
TEST_TIME 109 9320
TEST_TIME 110 9292
TEST_TIME 111 9316
TEST_TIME 112 9424
TEST_TIME 113 9460
TEST_TIME 114 9441
TEST_TIME 115 9371
TEST_TIME 116 9343
TEST_TIME 117 9295
TEST_TIME 118 9259
TEST_TIME 119 9231
TEST_TIME 120 9212

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-12-2020 13:28:12 to 20-12-2020 13:28: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 1541745 1541745 0 0.00 0 19450 6 0 0
doc_list_biud 1541743 1541743 0 0.00 0 29576 29 0 0
sp_add_invoice_to_stock 5192 5058 134 2.58 38 172306 17308 0 134
sp_cancel_adding_invoice 5547 5547 0 0.00 6 56536 908 0 0
sp_cancel_client_order 1849 1849 0 0.00 4 13007 322 0 0
sp_cancel_customer_reserve 544463 544463 0 0.00 1 29056 65 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 3 4629 69 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 4905 81 0 0
sp_cancel_supplier_invoice 6058 6048 10 0.16 2 16926 834 0 10
sp_cancel_supplier_order 396 396 0 0.00 5 1113667 112109 0 0
sp_cancel_write_off 10640 10640 0 0.00 1 11424 119 0 0
sp_client_order 20215 20215 0 0.00 4 25806 397 0 0
sp_customer_reserve 474004 474004 0 0.00 0 122548 167 0 0
sp_fill_shopping_cart 31537 31537 0 0.00 1 28472 41 0 0
sp_fill_shopping_cart_clo_res 474003 474003 0 0.00 0 24918 17 0 0
sp_get_clo_for_invoice 5057 5057 0 0.00 0 50517 422 0 0
sp_kill_cost_storno 17232 17232 0 0.00 0 7518 56 0 0
sp_kill_qstorno_ret_qs2qd 1106010 1106010 0 0.00 0 28866 34 0 0
sp_kill_qty_storno 584144 584144 0 0.00 0 29576 74 0 0
sp_lock_selected_doc 21026 21026 0 0.00 0 13557 7 0 0
sp_make_cost_storno 28285 28285 0 0.00 0 17051 58 0 0
sp_multiply_rows_for_pdistr 28285 28285 0 0.00 0 8008 30 0 0
sp_multiply_rows_for_qdistr 20213 20213 0 0.00 0 16699 225 0 0
sp_pay_from_customer 7114 7114 0 0.00 4 16858 106 0 0
sp_pay_to_supplier 5970 5970 0 0.00 4 18765 122 0 0
sp_payment_common 13084 13084 0 0.00 1 10231 19 0 0
sp_qd_handle_on_cancel_clo 1849 1849 0 0.00 0 9745 276 0 0
sp_qd_handle_on_invoice_upd_sts 10604 10604 0 0.00 0 29575 353 0 0
sp_qd_handle_on_reserve_upd_sts 10144 10144 0 0.00 0 16320 51 0 0
sp_reserve_write_off 10144 10144 0 0.00 6 26679 176 0 0
sp_supplier_invoice 5643 5643 0 0.00 7 95029 3241 0 0
sp_supplier_order 5698 5698 0 0.00 6 35183 1282 0 0
srv_aggregate_perf_data 99 99 0 0.00 2701 195983 18591 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1426 171700 50473 0 0
srv_find_qd_qs_mism 1055888 1055888 0 0.00 0 13884 5 0 0
srv_make_invnt_saldo 482 482 0 0.00 44 84972 2920 0 0
srv_make_money_saldo 788 788 0 0.00 1 12044 453 0 0
srv_recalc_idx_stat 4 4 0 0.00 214997 499268 343311 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5058 5058 0 0.00 0 11058 162 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 17613 141 0 0
t$perf-norm:sp_cancel_client_order 1849 1849 0 0.00 0 1823 10 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 15949 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 2259 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 377 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1171 13 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 24983 1603 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 1336 6 0 0
t$perf-norm:sp_client_order 20215 20215 0 0.00 0 8170 15 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 1117 5 0 0
t$perf-norm:sp_pay_from_customer 7114 7114 0 0.00 0 3433 7 0 0
t$perf-norm:sp_pay_to_supplier 5970 5970 0 0.00 0 5600 8 0 0
t$perf-norm:sp_reserve_write_off 10144 10144 0 0.00 0 10993 12 0 0
t$perf-norm:sp_supplier_invoice 5643 5643 0 0.00 0 13982 23 0 0
t$perf-norm:sp_supplier_order 5698 5698 0 0.00 0 8007 15 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 206 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 68 1 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 591 9 0 0
t$perf-norm:srv_make_money_saldo 788 788 0 0.00 0 3349 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 26 9 0 0
v_add_invoice_to_stock 10382 10114 268 2.58 0 7298 18 0 268
v_all_customers 37654 37654 0 0.00 0 19 1 0 0
v_all_suppliers 31576 31576 0 0.00 0 54 1 0 0
v_all_wares 40422 40422 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 153 6 0 0
v_cancel_client_order 3698 3698 0 0.00 0 7341 6 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 367 3 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 6395 8 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 4851 14 0 0
v_cancel_supplier_invoice 828 808 20 2.41 0 935 12 0 20
v_cancel_supplier_order 792 792 0 0.00 0 405 5 0 0
v_cancel_write_off 1996 1996 0 0.00 0 6114 21 0 0
v_max_id_clo_ord 5697 5697 0 0.00 0 996 2 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 1860 14 0 0
v_max_id_ord_sup 5628 5628 0 0.00 0 9662 8 0 0
v_max_non_paid_invoice 1507 1507 0 0.00 0 318 2 0 0
v_max_non_paid_realizn 3515 3515 0 0.00 0 1502 4 0 0
v_min_id_clo_ord 5697 5697 0 0.00 0 4695 7 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 3329 17 0 0
v_min_id_ord_sup 5628 5628 0 0.00 0 9026 6 0 0
v_min_non_paid_invoice 5970 5970 0 0.00 0 10223 13 0 0
v_min_non_paid_realizn 7114 7114 0 0.00 0 6400 13 0 0
v_our_firm 9966 9966 0 0.00 0 14 1 0 0
v_reserve_write_off 20282 20282 0 0.00 0 5299 6 0 0
x_lock_depdocs_on_canc_invoice 11191 11191 0 0.00 0 9994 109 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 26195 372 0 0
x_make_qstorno_1000_1200 5698 5698 0 0.00 2 35170 1203 0 0
x_make_qstorno_1200_2000 5643 5643 0 0.00 4 95019 3093 0 0
x_make_qstorno_2100_3300 474004 474004 0 0.00 0 122547 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-12-2020 13:28:17 to 20-12-2020 13:28: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
10:56:57 1 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
10:57:59 18245 8192000000 9705525312 9996296192 783297056 8.727 100 82 164 0 811747328 7966800 20483472
10:59:20 20314 8192000000 9750766368 10052554752 823783408 9.137 100 87 173 2 855773104 11469184 20520512
11:00:45 15301 8192000000 9794155632 10109960192 859993760 9.500 100 83 166 2 899218528 20171824 19052944
11:02:03 12774 8192000000 9840468608 10167504896 902596160 9.924 100 74 148 1 959166336 40278832 16291344
11:03:21 10166 8192000000 9907456048 10252693504 951701616 10.408 100 76 153 2 1024821360 56926800 16192944
11:04:36 4730 8192000000 9954487120 10305896448 991744864 10.798 100 79 157 0 1057364368 49878336 15741168
11:05:43 3482 8192000000 10002337408 10361573376 1017898112 11.052 100 80 161 2 1119577232 85972304 15706816
11:06:49 1829 8192000000 10013659792 10381496320 1045474576 11.317 100 87 172 2 1136189616 73616880 17098160
11:07:53 1753 8192000000 10040149456 10409353216 1062467280 11.480 100 82 160 3 1153322112 74819776 16035056
11:08:57 1532 8192000000 10045505760 10420183040 1073891808 11.589 100 83 168 2 1152217264 61393920 16931536
11:10:00 5334 8192000000 10017698848 10411171840 1082151552 11.668 100 47 93 1 1128047008 36015296 9880160
11:11:07 9428 8192000000 9996044672 10396205056 1088058336 11.724 100 37 75 2 1111168624 15346544 7763744
11:12:19 34806 8192000000 10012843856 10406010880 1095295744 11.793 100 51 98 3 1114089392 7987616 10806032
11:13:55 3552 8192000000 10048595280 10445119488 1098112352 11.820 100 63 122 0 1158213072 46730080 13370640
11:14:59 3003 8192000000 10018125888 10412990464 1101718608 11.854 100 48 91 3 1128977520 17376336 9882576
11:16:03 3860 8192000000 10023240368 10414800896 1091559296 11.757 99 36 73 0 1126429904 26895632 7974976
11:17:08 20070 8192000000 9997308080 10413391872 1075461168 11.604 98 20 39 3 1103709744 23372992 4875584
11:18:29 4807 8192000000 9962503584 10349592576 1011048064 10.986 95 36 72 1 1041770160 22403920 8318176
11:19:35 6733 8192000000 9958328128 10342092800 1019896144 11.071 98 41 82 2 1057580592 28302320 9382128
11:20:45 77008 8192000000 9950568624 10349002752 981745424 10.701 95 43 87 1 1004767984 13140976 9881584
11:23:05 6599 8192000000 9974180752 10363154432 1041776704 11.282 100 48 98 5 1065242592 13298448 10167440
11:24:12 8578 8192000000 9969740160 10361872384 1046348960 11.326 99 32 64 1 1073123248 19437696 7336592
11:25:22 63151 8192000000 9973481712 10382471168 1066278240 11.517 100 30 61 1 1086124640 13509040 6337360
11:27:31 15595 8192000000 10009482128 10406805504 1083833952 11.684 100 55 110 3 1123006576 27303360 11869264
11:28:53 28853 8192000000 10056329872 10464870400 1096311056 11.803 100 43 86 2 1174227792 69121184 8795552
11:30:28 5413 8192000000 10093525056 10493321216 1114504960 11.975 100 58 116 3 1201705760 75102192 12098608
11:31:36 32672 8192000000 10122281120 10548645888 1120385520 12.031 100 31 62 0 1228259648 100694640 7179488
11:33:17 12803 8192000000 10183143808 10597158912 1133236240 12.152 100 34 68 2 1296982992 155363792 8382960
11:34:32 13533 8192000000 10157739232 10571288576 1134316080 12.162 100 35 69 3 1263937488 121296320 8325088
11:35:55 6876 8192000000 10192792304 10603433984 1150271520 12.312 100 52 104 1 1298097952 137087328 10739104
11:37:06 14147 8192000000 10272317216 10684604416 1166959616 12.468 100 69 136 3 1371584560 190745136 13879808
11:38:25 1425 8192000000 10285542496 10686595072 1159136448 12.395 98 75 148 1 1383749616 208074928 16538240
11:39:30 1461 8192000000 10326502016 10731905024 1185424400 12.641 100 86 172 2 1429551904 224550256 19577248
11:40:36 2361 8192000000 10365789552 10764435456 1164667088 12.447 99 88 177 0 1462609808 278331552 19611168
11:41:43 3439 8192000000 10409971616 10809823232 1159778656 12.401 100 90 180 1 1509757184 329515408 20463120
11:42:52 2082 8192000000 10452322688 10867023872 1173135280 12.526 100 95 182 2 1549244240 355265312 20843648
11:44:01 2977 8192000000 10452739408 10862993408 1174165232 12.536 100 63 125 1 1556417952 367143680 15109040
11:45:16 5788 8192000000 10430648400 10863091712 1186958768 12.655 100 41 82 3 1534031568 336846768 10226032
11:46:24 8362 8192000000 10449454400 10884628480 1171285392 12.509 99 34 68 1 1538818512 358712160 8820960
11:47:37 14860 8192000000 10506931904 10965835776 1183821360 12.626 100 56 107 0 1606726800 409381392 13524048
11:48:58 34616 8192000000 10464825440 10920910848 1144378416 12.257 99 28 56 0 1556586688 405069600 7138672
11:50:45 24535 8192000000 10412661536 10843250688 1110847936 11.940 98 31 62 0 1505891296 386399792 8643568
11:52:12 26660 8192000000 10024151328 10482954240 1072858080 11.579 96 10 19 0 1184245584 108823584 2563920
11:53:40 20386 8192000000 10105252784 10532147200 1110826944 11.940 99 25 49 2 1157607648 41507728 5272976
11:55:29 59640 8192000000 10057766512 10482946048 1107384928 11.908 99 17 34 4 1142250400 31696240 3169232
11:57:50 48110 8192000000 10120029504 10540937216 1117136800 12.000 99 25 49 2 1193414384 71342208 4935376
11:59:38 66296 8192000000 10133072864 10618761216 1124396560 12.069 100 11 22 2 1235174048 107723504 3053984
12:01:45 171700 8192000000 10088673248 10521214976 1104866832 11.884 98 41 81 2 1166586176 53575584 8143760
12:05:46 24486 8192000000 10055162320 10493386752 1117717520 12.005 99 14 28 1 1155763136 35066240 2979376
12:07:11 41535 8192000000 10055743888 10491035648 1104329440 11.879 97 10 20 1 1129533728 23063888 2140400
12:09:47 130706 8192000000 10097933184 10574135296 1123915408 12.064 98 21 42 1 1216099632 87730048 4454176
12:12:57 55005 8192000000 10113903632 10572668928 1145234736 12.265 100 20 40 3 1200740176 51107536 4397904
12:14:59 38415 8192000000 10124104512 10583867392 1149810000 12.308 100 28 56 0 1223552384 68046112 5696272
12:16:41 97875 8192000000 10113275696 10590212096 1133822048 12.157 98 10 20 0 1185564272 49677872 2064352
12:19:25 67750 8192000000 10048623232 10510188544 1088920752 11.732 95 15 29 2 1120053456 28241344 2891360
12:21:37 56668 8192000000 10129590864 10587328512 1134593888 12.165 99 31 62 1 1206987760 66254608 6139264
12:23:42 83327 8192000000 10148470320 10645135360 1139825200 12.214 99 18 37 1 1214109968 70664080 3620688
12:26:12 84811 8192000000 10086440784 10567565312 1138357088 12.200 99 27 54 0 1220990336 76857888 5775360
12:28:37 76896 8192000000 10104446304 10583474176 1140747360 12.223 99 12 24 0 1185400304 42178864 2474080
12:32:15 97365 8192000000 10060294464 10530512896 1085636080 11.701 95 8 16 1 1126833808 39681536 1516192
12:34:53 27024 8192000000 10090374224 10527215616 1136506496 12.183 100 32 65 0 1183533200 40449648 6577056
12:36:22 48357 8192000000 10085892912 10518478848 1093746032 11.778 96 37 74 0 1139681344 38891408 7043904
12:38:13 28712 8192000000 10070097584 10502602752 1100342304 11.841 97 34 66 1 1140661856 33865936 6453616
12:39:42 65959 8192000000 10117993152 10582282240 1129380512 12.116 99 33 66 1 1212949920 76998352 6571056
12:42:51 80154 8192000000 10056857456 10504687616 1056669600 11.425 93 14 28 1 1132462816 73034624 2758592
12:45:14 46492 8192000000 10068883760 10560327680 1081428976 11.661 97 29 58 2 1141467504 54501312 5537216
12:47:02 97886 8192000000 10049347360 10506539008 1118971920 12.017 100 22 44 3 1151742048 28247360 4522768
12:49:41 89821 8192000000 10051416000 10509168640 1103719920 11.873 98 7 14 0 1147811184 42732192 1359072
12:52:15 43517 8192000000 10047515392 10485850112 1094773216 11.788 97 17 35 1 1128234720 29554800 3906704
12:54:08 57193 8192000000 10085868192 10534772736 1126371920 12.087 99 12 24 0 1175296656 46496512 2428224
12:56:05 70720 8192000000 10058732352 10514911232 1097576432 11.815 97 19 38 1 1155185488 53433488 4175568
12:58:22 37961 8192000000 10085879280 10518704128 1132801344 12.148 100 32 64 1 1180803504 41425040 6577120
13:00:04 77648 8192000000 10091097920 10541748224 1120338064 12.030 99 20 40 1 1208806720 83970336 4498320
13:02:21 57755 8192000000 10043454224 10487128064 1074627664 11.596 95 21 42 0 1113658768 34894688 4136416
13:04:19 106936 8192000000 10037477296 10503450624 1077678800 11.625 96 13 26 2 1119009136 38397248 2933088
13:07:19 72618 8192000000 10063862832 10548072448 1088383920 11.727 97 16 32 0 1115082336 23434560 3263856
13:09:32 65109 8192000000 10097936784 10526224384 1106686448 11.901 97 28 51 4 1163979728 51939936 5353344
13:11:46 94840 8192000000 10166375904 10612957184 1138604224 12.202 99 18 36 1 1227042736 84515712 3922800
13:14:21 54757 8192000000 10124108272 10586607616 1096680768 11.806 96 23 46 1 1174806768 73044944 5081056
13:16:16 75693 8192000000 10208829552 10680791040 1127107232 12.094 98 16 32 0 1270644544 139887888 3649424
13:18:33 72504 8192000000 10075780128 10527559680 1114879792 11.979 98 14 28 1 1207717520 89908160 2929568
13:20:46 128984 8192000000 10085196144 10551492608 1125476064 12.079 99 23 46 0 1197537952 67521392 4540496
13:24:10 42429 8192000000 10048567536 10535956480 1108494688 11.918 99 6 12 0 1148236400 38585664 1156048
13:25:53 56273 8192000000 10042779216 10490609664 1094258944 11.783 98 16 32 1 1117775296 20540976 2975376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-12-2020 13:28:23 to 20-12-2020 13:28:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-12-2020 13:28:38 to 20-12-2020 13:28:40.

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          239067
MON$OLDEST_ACTIVE               239616
MON$OLDEST_SNAPSHOT             239616
MON$NEXT_TRANSACTION            239616
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                       2104704
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             809
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2300 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.2300 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 ---
:80: 
:81: mscs-test-161.budzdorov.ru    Sun Dec 20 13:26:58 2020
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Sun Dec 20 13:26:58 2020
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Sun Dec 20 13:27:01 2020
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:91: 
--- end of diff output ---

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

20.12.20 13:28:41. 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
2020-12-20 13:28:44 No severe PSQL-related problems occured
20.12.20 13:28:45. End of report.