# Logfile created on 2016-11-21 03:15:27 -0300 by logger.rb/54362 I, [2016-11-21T03:15:27.108185 #21766] INFO -- : Starting I, [2016-11-21T03:15:27.108290 #21766] INFO -- : Stopping I, [2016-11-21T03:15:27.114810 #21771] INFO -- : Starting I, [2016-11-21T03:15:31.130676 #21776] INFO -- : Starting I, [2016-11-21T03:15:42.150470 #21771] INFO -- : Stopping I, [2016-11-21T03:15:46.161521 #21776] INFO -- : Stopping I, [2016-11-21T03:15:51.158636 #21766] INFO -- : Processing #638 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.168720 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.366124 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 197.25ms I, [2016-11-21T03:15:51.374615 #21766] INFO -- : Done I, [2016-11-21T03:15:51.380618 #21766] INFO -- : Processing #639 attempt 0 of ShareProductsJob I, [2016-11-21T03:15:51.382852 #21766] INFO -- : Performing ShareProductsJob from Sidejobs(default) I, [2016-11-21T03:15:51.383051 #21766] INFO -- : Performed ShareProductsJob from Sidejobs(default) in 0.14ms I, [2016-11-21T03:15:51.385135 #21766] INFO -- : Error: Social network unavailable I, [2016-11-21T03:15:51.386151 #21766] INFO -- : Processing #639 attempt 1 of ShareProductsJob I, [2016-11-21T03:15:51.388363 #21766] INFO -- : Performing ShareProductsJob from Sidejobs(default) I, [2016-11-21T03:15:51.388557 #21766] INFO -- : Performed ShareProductsJob from Sidejobs(default) in 0.1ms I, [2016-11-21T03:15:51.390545 #21766] INFO -- : Error: Social network unavailable I, [2016-11-21T03:15:51.391463 #21766] INFO -- : Processing #639 attempt 2 of ShareProductsJob I, [2016-11-21T03:15:51.393812 #21766] INFO -- : Performing ShareProductsJob from Sidejobs(default) I, [2016-11-21T03:15:51.393969 #21766] INFO -- : Performed ShareProductsJob from Sidejobs(default) in 0.1ms I, [2016-11-21T03:15:51.395888 #21766] INFO -- : Error: Social network unavailable I, [2016-11-21T03:15:51.450038 #21766] INFO -- : Processing #640 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.452304 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.454591 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.23ms I, [2016-11-21T03:15:51.456683 #21766] INFO -- : Done I, [2016-11-21T03:15:51.456746 #21766] INFO -- : Processing #641 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.458884 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.461272 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.32ms I, [2016-11-21T03:15:51.463444 #21766] INFO -- : Done I, [2016-11-21T03:15:51.463511 #21766] INFO -- : Processing #642 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.465649 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.468036 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.33ms I, [2016-11-21T03:15:51.470170 #21766] INFO -- : Done I, [2016-11-21T03:15:51.470237 #21766] INFO -- : Processing #643 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.472394 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.474806 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.35ms I, [2016-11-21T03:15:51.476933 #21766] INFO -- : Done I, [2016-11-21T03:15:51.476995 #21766] INFO -- : Processing #644 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.479125 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.481512 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.31ms I, [2016-11-21T03:15:51.483972 #21766] INFO -- : Done I, [2016-11-21T03:15:51.484045 #21766] INFO -- : Processing #645 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.486305 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.488470 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.11ms I, [2016-11-21T03:15:51.490545 #21766] INFO -- : Done I, [2016-11-21T03:15:51.490600 #21766] INFO -- : Processing #646 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.492674 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.494861 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.13ms I, [2016-11-21T03:15:51.496923 #21766] INFO -- : Done I, [2016-11-21T03:15:51.496979 #21766] INFO -- : Processing #647 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.499041 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.501243 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.15ms I, [2016-11-21T03:15:51.503428 #21766] INFO -- : Done I, [2016-11-21T03:15:51.503484 #21766] INFO -- : Processing #648 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.505671 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.508100 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.37ms I, [2016-11-21T03:15:51.510176 #21766] INFO -- : Done I, [2016-11-21T03:15:51.510234 #21766] INFO -- : Processing #649 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.512391 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.514610 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.17ms I, [2016-11-21T03:15:51.516831 #21766] INFO -- : Done I, [2016-11-21T03:15:51.516890 #21766] INFO -- : Processing #650 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.519291 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.521462 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.11ms I, [2016-11-21T03:15:51.523624 #21766] INFO -- : Done I, [2016-11-21T03:15:51.523680 #21766] INFO -- : Processing #651 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.525839 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.528168 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.27ms I, [2016-11-21T03:15:51.530302 #21766] INFO -- : Done I, [2016-11-21T03:15:51.530361 #21766] INFO -- : Processing #652 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.532571 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.534939 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.3ms I, [2016-11-21T03:15:51.537005 #21766] INFO -- : Done I, [2016-11-21T03:15:51.537064 #21766] INFO -- : Processing #653 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.539153 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.541735 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.51ms I, [2016-11-21T03:15:51.543820 #21766] INFO -- : Done I, [2016-11-21T03:15:51.543887 #21766] INFO -- : Processing #654 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.546088 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.548475 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.32ms I, [2016-11-21T03:15:51.550512 #21766] INFO -- : Done I, [2016-11-21T03:15:51.550569 #21766] INFO -- : Processing #655 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.553710 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.556039 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.25ms I, [2016-11-21T03:15:51.558194 #21766] INFO -- : Done I, [2016-11-21T03:15:51.558250 #21766] INFO -- : Processing #656 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.560465 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.563401 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.86ms I, [2016-11-21T03:15:51.565793 #21766] INFO -- : Done I, [2016-11-21T03:15:51.565853 #21766] INFO -- : Processing #657 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.568153 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.570420 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.2ms I, [2016-11-21T03:15:51.572549 #21766] INFO -- : Done I, [2016-11-21T03:15:51.572606 #21766] INFO -- : Processing #658 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.575682 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.577884 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.14ms I, [2016-11-21T03:15:51.580095 #21766] INFO -- : Done I, [2016-11-21T03:15:51.580159 #21766] INFO -- : Processing #659 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.582318 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.584891 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.5ms I, [2016-11-21T03:15:51.587323 #21766] INFO -- : Done I, [2016-11-21T03:15:51.593045 #21766] INFO -- : Processing #660 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.595140 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.597511 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.31ms I, [2016-11-21T03:15:51.599496 #21766] INFO -- : Done I, [2016-11-21T03:15:51.599550 #21766] INFO -- : Processing #661 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.601613 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.603933 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.26ms I, [2016-11-21T03:15:51.606611 #21766] INFO -- : Done I, [2016-11-21T03:15:51.606676 #21766] INFO -- : Processing #662 attempt 0 of ActionMailer::DeliveryJob I, [2016-11-21T03:15:51.608789 #21766] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-11-21T03:15:51.610978 #21766] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.13ms I, [2016-11-21T03:15:51.612945 #21766] INFO -- : Done I, [2016-12-11T02:28:01.025366 #13898] INFO -- : Starting I, [2016-12-11T02:28:05.040699 #13903] INFO -- : Starting I, [2016-12-11T02:28:16.095637 #13898] INFO -- : Stopping I, [2016-12-11T02:28:23.267164 #13930] INFO -- : Starting I, [2016-12-11T02:28:35.100177 #13903] INFO -- : Stopping I, [2016-12-11T02:28:38.326268 #13930] INFO -- : Stopping I, [2016-12-11T02:28:43.276997 #13923] INFO -- : Starting I, [2016-12-11T02:28:43.277210 #13923] INFO -- : Stopping I, [2016-12-11T02:28:43.460881 #13923] INFO -- : Processing #674 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.465965 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.632225 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 166.13ms I, [2016-12-11T02:28:43.634785 #13923] INFO -- : Done I, [2016-12-11T02:28:43.634841 #13923] INFO -- : Processing #675 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.636963 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.639191 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.18ms I, [2016-12-11T02:28:43.641491 #13923] INFO -- : Done I, [2016-12-11T02:28:43.641548 #13923] INFO -- : Processing #676 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.643740 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.646055 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.26ms I, [2016-12-11T02:28:43.648079 #13923] INFO -- : Done I, [2016-12-11T02:28:43.648140 #13923] INFO -- : Processing #677 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.650287 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.652505 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.17ms I, [2016-12-11T02:28:43.654392 #13923] INFO -- : Done I, [2016-12-11T02:28:43.654441 #13923] INFO -- : Processing #678 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.656554 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.658826 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.22ms I, [2016-12-11T02:28:43.660755 #13923] INFO -- : Done I, [2016-12-11T02:28:43.660805 #13923] INFO -- : Processing #679 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.662908 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.665003 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.04ms I, [2016-12-11T02:28:43.666978 #13923] INFO -- : Done I, [2016-12-11T02:28:43.667028 #13923] INFO -- : Processing #680 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.669109 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.671183 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.03ms I, [2016-12-11T02:28:43.673082 #13923] INFO -- : Done I, [2016-12-11T02:28:43.673126 #13923] INFO -- : Processing #681 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.675347 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.677275 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.88ms I, [2016-12-11T02:28:43.679000 #13923] INFO -- : Done I, [2016-12-11T02:28:43.679046 #13923] INFO -- : Processing #682 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.680980 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.682872 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.84ms I, [2016-12-11T02:28:43.684728 #13923] INFO -- : Done I, [2016-12-11T02:28:43.684782 #13923] INFO -- : Processing #683 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.686716 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.688643 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.87ms I, [2016-12-11T02:28:43.690486 #13923] INFO -- : Done I, [2016-12-11T02:28:43.690531 #13923] INFO -- : Processing #684 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.692412 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.694236 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.77ms I, [2016-12-11T02:28:43.695979 #13923] INFO -- : Done I, [2016-12-11T02:28:43.696026 #13923] INFO -- : Processing #685 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.697983 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.699971 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.93ms I, [2016-12-11T02:28:43.701842 #13923] INFO -- : Done I, [2016-12-11T02:28:43.701888 #13923] INFO -- : Processing #686 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.703755 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.705664 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.86ms I, [2016-12-11T02:28:43.707369 #13923] INFO -- : Done I, [2016-12-11T02:28:43.707412 #13923] INFO -- : Processing #687 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.709260 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.711123 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.8ms I, [2016-12-11T02:28:43.712834 #13923] INFO -- : Done I, [2016-12-11T02:28:43.712886 #13923] INFO -- : Processing #688 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.714753 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.716686 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.89ms I, [2016-12-11T02:28:43.718307 #13923] INFO -- : Done I, [2016-12-11T02:28:43.718360 #13923] INFO -- : Processing #689 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.720179 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.722044 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.82ms I, [2016-12-11T02:28:43.723676 #13923] INFO -- : Done I, [2016-12-11T02:28:43.723730 #13923] INFO -- : Processing #690 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.725785 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.727890 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.05ms I, [2016-12-11T02:28:43.729699 #13923] INFO -- : Done I, [2016-12-11T02:28:43.729753 #13923] INFO -- : Processing #691 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.731679 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.733663 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.94ms I, [2016-12-11T02:28:43.735332 #13923] INFO -- : Done I, [2016-12-11T02:28:43.735385 #13923] INFO -- : Processing #692 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.737333 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.739286 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.91ms I, [2016-12-11T02:28:43.740900 #13923] INFO -- : Done I, [2016-12-11T02:28:43.740963 #13923] INFO -- : Processing #693 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.742853 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.744830 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.93ms I, [2016-12-11T02:28:43.746465 #13923] INFO -- : Done I, [2016-12-11T02:28:43.752911 #13923] INFO -- : Processing #694 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.754880 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.757119 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.19ms I, [2016-12-11T02:28:43.758837 #13923] INFO -- : Done I, [2016-12-11T02:28:43.758898 #13923] INFO -- : Processing #695 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.760827 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.762768 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.9ms I, [2016-12-11T02:28:43.764486 #13923] INFO -- : Done I, [2016-12-11T02:28:43.764534 #13923] INFO -- : Processing #696 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.766427 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.768439 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.96ms I, [2016-12-11T02:28:43.770097 #13923] INFO -- : Done I, [2016-12-11T02:28:43.776496 #13923] INFO -- : Processing #698 attempt 0 of ShareProductsJob I, [2016-12-11T02:28:43.778634 #13923] INFO -- : Performing ShareProductsJob from Sidejobs(default) I, [2016-12-11T02:28:43.778829 #13923] INFO -- : Performed ShareProductsJob from Sidejobs(default) in 0.14ms I, [2016-12-11T02:28:43.780649 #13923] INFO -- : Error: Social network unavailable I, [2016-12-11T02:28:43.781479 #13923] INFO -- : Processing #698 attempt 1 of ShareProductsJob I, [2016-12-11T02:28:43.783262 #13923] INFO -- : Performing ShareProductsJob from Sidejobs(default) I, [2016-12-11T02:28:43.783384 #13923] INFO -- : Performed ShareProductsJob from Sidejobs(default) in 0.08ms I, [2016-12-11T02:28:43.785116 #13923] INFO -- : Error: Social network unavailable I, [2016-12-11T02:28:43.785961 #13923] INFO -- : Processing #698 attempt 2 of ShareProductsJob I, [2016-12-11T02:28:43.787884 #13923] INFO -- : Performing ShareProductsJob from Sidejobs(default) I, [2016-12-11T02:28:43.788044 #13923] INFO -- : Performed ShareProductsJob from Sidejobs(default) in 0.09ms I, [2016-12-11T02:28:43.789619 #13923] INFO -- : Error: Social network unavailable I, [2016-12-11T02:28:43.794594 #13923] INFO -- : Processing #699 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-11T02:28:43.796657 #13923] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-11T02:28:43.798987 #13923] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.27ms I, [2016-12-11T02:28:43.800793 #13923] INFO -- : Done I, [2016-12-13T17:23:15.452439 #66337] INFO -- : Starting /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activesupport-4.2.7.1/lib/active_support/inflector/methods.rb:263:in `const_get': uninitialized constant ActiveJob::QueueAdapters::SidejobsAdapter (NameError) from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activesupport-4.2.7.1/lib/active_support/inflector/methods.rb:263:in `block in constantize' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activesupport-4.2.7.1/lib/active_support/inflector/methods.rb:259:in `each' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activesupport-4.2.7.1/lib/active_support/inflector/methods.rb:259:in `inject' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activesupport-4.2.7.1/lib/active_support/inflector/methods.rb:259:in `constantize' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activesupport-4.2.7.1/lib/active_support/core_ext/string/inflections.rb:66:in `constantize' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activejob-4.2.7.1/lib/active_job/queue_adapter.rb:31:in `load_adapter' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activejob-4.2.7.1/lib/active_job/queue_adapter.rb:23:in `queue_adapter=' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activejob-4.2.7.1/lib/active_job/railtie.rb:18:in `block (3 levels) in ' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activejob-4.2.7.1/lib/active_job/railtie.rb:18:in `each' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activejob-4.2.7.1/lib/active_job/railtie.rb:18:in `block (2 levels) in ' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activesupport-4.2.7.1/lib/active_support/lazy_load_hooks.rb:38:in `instance_eval' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activesupport-4.2.7.1/lib/active_support/lazy_load_hooks.rb:38:in `execute_hook' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activesupport-4.2.7.1/lib/active_support/lazy_load_hooks.rb:45:in `block in run_load_hooks' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activesupport-4.2.7.1/lib/active_support/lazy_load_hooks.rb:44:in `each' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activesupport-4.2.7.1/lib/active_support/lazy_load_hooks.rb:44:in `run_load_hooks' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activejob-4.2.7.1/lib/active_job/base.rb:66:in `' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activejob-4.2.7.1/lib/active_job/base.rb:56:in `' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/activejob-4.2.7.1/lib/active_job/base.rb:10:in `' from /Users/Matt/Documents/Github/sidejobs/lib/sidejobs/processor.rb:5:in `process' from /Users/Matt/Documents/Github/sidejobs/lib/sidejobs/daemon.rb:97:in `process' from /Users/Matt/Documents/Github/sidejobs/lib/sidejobs/daemon.rb:26:in `start' from /Users/Matt/Documents/Github/sidejobs/test/daemon_test.rb:16:in `block (2 levels) in ' from /Users/Matt/Documents/Github/sidejobs/test/daemon_test.rb:15:in `fork' from /Users/Matt/Documents/Github/sidejobs/test/daemon_test.rb:15:in `block in ' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest/test.rb:105:in `block (3 levels) in run' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest/test.rb:202:in `capture_exceptions' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest/test.rb:102:in `block (2 levels) in run' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest/test.rb:253:in `time_it' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest/test.rb:101:in `block in run' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:349:in `on_signal' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest/test.rb:273:in `with_info_handler' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest/test.rb:100:in `run' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:822:in `run_one_method' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:323:in `run_one_method' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:310:in `block (2 levels) in run' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:309:in `each' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:309:in `block in run' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:349:in `on_signal' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:336:in `with_info_handler' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:308:in `run' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:158:in `block in __run' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:158:in `map' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:158:in `__run' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:135:in `run' from /Users/Matt/.rbenv/versions/2.3.1/lib/ruby/gems/2.3.0/gems/minitest-5.10.1/lib/minitest.rb:62:in `block in autorun' I, [2016-12-13T17:23:19.467824 #66327] INFO -- : Starting I, [2016-12-13T17:23:19.467938 #66327] INFO -- : Stopping I, [2016-12-13T17:24:04.104433 #66376] INFO -- : Starting I, [2016-12-13T17:24:08.149186 #66382] INFO -- : Starting I, [2016-12-13T17:24:19.171452 #66376] INFO -- : Stopping I, [2016-12-13T17:24:23.209475 #66382] INFO -- : Stopping I, [2016-12-13T17:24:28.166660 #66371] INFO -- : Starting I, [2016-12-13T17:24:28.166785 #66371] INFO -- : Stopping I, [2016-12-13T17:24:28.306839 #66371] INFO -- : Processing #11 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.311164 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.444846 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 133.58ms I, [2016-12-13T17:24:28.447344 #66371] INFO -- : Done I, [2016-12-13T17:24:28.447398 #66371] INFO -- : Processing #12 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.449489 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.451854 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.31ms I, [2016-12-13T17:24:28.453774 #66371] INFO -- : Done I, [2016-12-13T17:24:28.453825 #66371] INFO -- : Processing #13 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.455910 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.458079 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.09ms I, [2016-12-13T17:24:28.461346 #66371] INFO -- : Done I, [2016-12-13T17:24:28.461415 #66371] INFO -- : Processing #14 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.463903 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.466079 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.13ms I, [2016-12-13T17:24:28.468055 #66371] INFO -- : Done I, [2016-12-13T17:24:28.468105 #66371] INFO -- : Processing #15 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.470208 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.472228 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.97ms I, [2016-12-13T17:24:28.474063 #66371] INFO -- : Done I, [2016-12-13T17:24:28.474115 #66371] INFO -- : Processing #16 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.476197 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.478427 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.18ms I, [2016-12-13T17:24:28.480438 #66371] INFO -- : Done I, [2016-12-13T17:24:28.480488 #66371] INFO -- : Processing #17 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.482576 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.484776 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.11ms I, [2016-12-13T17:24:28.486629 #66371] INFO -- : Done I, [2016-12-13T17:24:28.486681 #66371] INFO -- : Processing #18 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.488737 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.490746 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.96ms I, [2016-12-13T17:24:28.492439 #66371] INFO -- : Done I, [2016-12-13T17:24:28.492491 #66371] INFO -- : Processing #19 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.494374 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.496357 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.94ms I, [2016-12-13T17:24:28.497965 #66371] INFO -- : Done I, [2016-12-13T17:24:28.498064 #66371] INFO -- : Processing #20 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.499945 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.501873 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.88ms I, [2016-12-13T17:24:28.503648 #66371] INFO -- : Done I, [2016-12-13T17:24:28.503700 #66371] INFO -- : Processing #21 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.505665 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.507611 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.9ms I, [2016-12-13T17:24:28.509257 #66371] INFO -- : Done I, [2016-12-13T17:24:28.509304 #66371] INFO -- : Processing #22 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.511302 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.513183 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.82ms I, [2016-12-13T17:24:28.514824 #66371] INFO -- : Done I, [2016-12-13T17:24:28.514869 #66371] INFO -- : Processing #23 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.516789 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.518613 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.76ms I, [2016-12-13T17:24:28.520267 #66371] INFO -- : Done I, [2016-12-13T17:24:28.520312 #66371] INFO -- : Processing #24 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.522146 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.523897 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.7ms I, [2016-12-13T17:24:28.525578 #66371] INFO -- : Done I, [2016-12-13T17:24:28.525622 #66371] INFO -- : Processing #25 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.527449 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.529298 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.8ms I, [2016-12-13T17:24:28.531158 #66371] INFO -- : Done I, [2016-12-13T17:24:28.531206 #66371] INFO -- : Processing #26 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.533060 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.534970 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.86ms I, [2016-12-13T17:24:28.536598 #66371] INFO -- : Done I, [2016-12-13T17:24:28.536651 #66371] INFO -- : Processing #27 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.538438 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.540423 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.94ms I, [2016-12-13T17:24:28.542196 #66371] INFO -- : Done I, [2016-12-13T17:24:28.542247 #66371] INFO -- : Processing #28 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.544100 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.546106 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.96ms I, [2016-12-13T17:24:28.547726 #66371] INFO -- : Done I, [2016-12-13T17:24:28.547779 #66371] INFO -- : Processing #29 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.549612 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.551466 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.8ms I, [2016-12-13T17:24:28.553068 #66371] INFO -- : Done I, [2016-12-13T17:24:28.553112 #66371] INFO -- : Processing #30 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.555174 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.557204 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.98ms I, [2016-12-13T17:24:28.558869 #66371] INFO -- : Done I, [2016-12-13T17:24:28.564428 #66371] INFO -- : Processing #31 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.566502 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.568648 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.1ms I, [2016-12-13T17:24:28.570316 #66371] INFO -- : Done I, [2016-12-13T17:24:28.570360 #66371] INFO -- : Processing #32 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.572281 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.574161 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.83ms I, [2016-12-13T17:24:28.575871 #66371] INFO -- : Done I, [2016-12-13T17:24:28.575914 #66371] INFO -- : Processing #33 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.577832 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.579737 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.86ms I, [2016-12-13T17:24:28.581518 #66371] INFO -- : Done I, [2016-12-13T17:24:28.586618 #66371] INFO -- : Processing #35 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-13T17:24:28.588566 #66371] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-13T17:24:28.590566 #66371] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.95ms I, [2016-12-13T17:24:28.592271 #66371] INFO -- : Done I, [2016-12-13T17:24:28.597285 #66371] INFO -- : Processing #36 attempt 0 of ShareProductsJob I, [2016-12-13T17:24:28.599258 #66371] INFO -- : Performing ShareProductsJob from Sidejobs(default) I, [2016-12-13T17:24:28.599433 #66371] INFO -- : Performed ShareProductsJob from Sidejobs(default) in 0.13ms I, [2016-12-13T17:24:28.601272 #66371] INFO -- : Error: Social network unavailable I, [2016-12-13T17:24:28.602052 #66371] INFO -- : Processing #36 attempt 1 of ShareProductsJob I, [2016-12-13T17:24:28.603931 #66371] INFO -- : Performing ShareProductsJob from Sidejobs(default) I, [2016-12-13T17:24:28.604057 #66371] INFO -- : Performed ShareProductsJob from Sidejobs(default) in 0.08ms I, [2016-12-13T17:24:28.605825 #66371] INFO -- : Error: Social network unavailable I, [2016-12-13T17:24:28.606566 #66371] INFO -- : Processing #36 attempt 2 of ShareProductsJob I, [2016-12-13T17:24:28.608441 #66371] INFO -- : Performing ShareProductsJob from Sidejobs(default) I, [2016-12-13T17:24:28.608579 #66371] INFO -- : Performed ShareProductsJob from Sidejobs(default) in 0.09ms I, [2016-12-13T17:24:28.610192 #66371] INFO -- : Error: Social network unavailable I, [2016-12-14T15:37:46.611185 #2347] INFO -- : Processing #47 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.640101 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.807045 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 166.85ms I, [2016-12-14T15:37:46.809603 #2347] INFO -- : Done I, [2016-12-14T15:37:46.809689 #2347] INFO -- : Processing #48 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.812038 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.814352 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.26ms I, [2016-12-14T15:37:46.816328 #2347] INFO -- : Done I, [2016-12-14T15:37:46.816422 #2347] INFO -- : Processing #49 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.818498 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.820592 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.04ms I, [2016-12-14T15:37:46.822652 #2347] INFO -- : Done I, [2016-12-14T15:37:46.822705 #2347] INFO -- : Processing #50 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.824811 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.827090 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.23ms I, [2016-12-14T15:37:46.829093 #2347] INFO -- : Done I, [2016-12-14T15:37:46.829144 #2347] INFO -- : Processing #51 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.831341 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.833873 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.48ms I, [2016-12-14T15:37:46.836319 #2347] INFO -- : Done I, [2016-12-14T15:37:46.836386 #2347] INFO -- : Processing #52 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.838768 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.841034 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.22ms I, [2016-12-14T15:37:46.843238 #2347] INFO -- : Done I, [2016-12-14T15:37:46.843297 #2347] INFO -- : Processing #53 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.845802 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.848134 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.28ms I, [2016-12-14T15:37:46.850206 #2347] INFO -- : Done I, [2016-12-14T15:37:46.850259 #2347] INFO -- : Processing #54 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.852109 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.854267 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.11ms I, [2016-12-14T15:37:46.855909 #2347] INFO -- : Done I, [2016-12-14T15:37:46.855958 #2347] INFO -- : Processing #55 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.857962 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.860042 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.03ms I, [2016-12-14T15:37:46.861789 #2347] INFO -- : Done I, [2016-12-14T15:37:46.861889 #2347] INFO -- : Processing #56 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.863843 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.865924 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.02ms I, [2016-12-14T15:37:46.867969 #2347] INFO -- : Done I, [2016-12-14T15:37:46.868024 #2347] INFO -- : Processing #57 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.870031 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.872109 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.03ms I, [2016-12-14T15:37:46.873794 #2347] INFO -- : Done I, [2016-12-14T15:37:46.873845 #2347] INFO -- : Processing #58 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.875664 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.877597 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.88ms I, [2016-12-14T15:37:46.879231 #2347] INFO -- : Done I, [2016-12-14T15:37:46.879283 #2347] INFO -- : Processing #59 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.881131 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.883119 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.94ms I, [2016-12-14T15:37:46.884931 #2347] INFO -- : Done I, [2016-12-14T15:37:46.885023 #2347] INFO -- : Processing #60 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.886906 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.889134 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.18ms I, [2016-12-14T15:37:46.890951 #2347] INFO -- : Done I, [2016-12-14T15:37:46.891001 #2347] INFO -- : Processing #61 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.893030 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.895228 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.14ms I, [2016-12-14T15:37:46.896990 #2347] INFO -- : Done I, [2016-12-14T15:37:46.897046 #2347] INFO -- : Processing #62 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.899044 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.901252 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.16ms I, [2016-12-14T15:37:46.903022 #2347] INFO -- : Done I, [2016-12-14T15:37:46.903072 #2347] INFO -- : Processing #63 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.904867 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.906781 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 1.87ms I, [2016-12-14T15:37:46.908485 #2347] INFO -- : Done I, [2016-12-14T15:37:46.908534 #2347] INFO -- : Processing #64 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.910672 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.912907 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.18ms I, [2016-12-14T15:37:46.915004 #2347] INFO -- : Done I, [2016-12-14T15:37:46.915092 #2347] INFO -- : Processing #65 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.917244 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.919636 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.33ms I, [2016-12-14T15:37:46.921754 #2347] INFO -- : Done I, [2016-12-14T15:37:46.921809 #2347] INFO -- : Processing #66 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.923952 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.926272 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.25ms I, [2016-12-14T15:37:46.928583 #2347] INFO -- : Done I, [2016-12-14T15:37:46.935023 #2347] INFO -- : Processing #67 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.937571 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.939906 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.26ms I, [2016-12-14T15:37:46.942013 #2347] INFO -- : Done I, [2016-12-14T15:37:46.942065 #2347] INFO -- : Processing #68 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.944073 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.946343 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.22ms I, [2016-12-14T15:37:46.948133 #2347] INFO -- : Done I, [2016-12-14T15:37:46.948182 #2347] INFO -- : Processing #69 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.950302 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.952474 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.12ms I, [2016-12-14T15:37:46.954161 #2347] INFO -- : Done I, [2016-12-14T15:37:46.959347 #2347] INFO -- : Processing #71 attempt 0 of ActionMailer::DeliveryJob I, [2016-12-14T15:37:46.961352 #2347] INFO -- : Performing ActionMailer::DeliveryJob from Sidejobs(mailers) with arguments: "UserMailer", "invite", "deliver_now", "test@mail.com" I, [2016-12-14T15:37:46.963639 #2347] INFO -- : Performed ActionMailer::DeliveryJob from Sidejobs(mailers) in 2.23ms I, [2016-12-14T15:37:46.965562 #2347] INFO -- : Done I, [2016-12-14T15:37:46.970757 #2347] INFO -- : Processing #72 attempt 0 of ShareProductsJob I, [2016-12-14T15:37:46.972709 #2347] INFO -- : Performing ShareProductsJob from Sidejobs(default) I, [2016-12-14T15:37:46.972902 #2347] INFO -- : Performed ShareProductsJob from Sidejobs(default) in 0.12ms I, [2016-12-14T15:37:46.974835 #2347] INFO -- : Error: Social network unavailable I, [2016-12-14T15:37:46.975658 #2347] INFO -- : Processing #72 attempt 1 of ShareProductsJob I, [2016-12-14T15:37:46.977488 #2347] INFO -- : Performing ShareProductsJob from Sidejobs(default) I, [2016-12-14T15:37:46.977630 #2347] INFO -- : Performed ShareProductsJob from Sidejobs(default) in 0.09ms I, [2016-12-14T15:37:46.979459 #2347] INFO -- : Error: Social network unavailable I, [2016-12-14T15:37:46.980300 #2347] INFO -- : Processing #72 attempt 2 of ShareProductsJob I, [2016-12-14T15:37:46.982203 #2347] INFO -- : Performing ShareProductsJob from Sidejobs(default) I, [2016-12-14T15:37:46.982359 #2347] INFO -- : Performed ShareProductsJob from Sidejobs(default) in 0.1ms I, [2016-12-14T15:37:46.984021 #2347] INFO -- : Error: Social network unavailable I, [2016-12-14T15:37:46.993819 #2352] INFO -- : Starting I, [2016-12-14T15:37:51.005963 #2361] INFO -- : Starting I, [2016-12-14T15:38:02.019377 #2352] INFO -- : Stopping I, [2016-12-14T15:38:06.029169 #2361] INFO -- : Stopping I, [2016-12-14T15:38:11.641612 #2347] INFO -- : Starting I, [2016-12-14T15:38:11.642084 #2347] INFO -- : Stopping I, [2016-12-14T16:08:39.396886 #4270] INFO -- : Starting D, [2016-12-14T16:08:39.440513 #4270] DEBUG -- : Sidejobs::Job Load (1.8ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-14 19:08:39.427396' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 I, [2016-12-14T16:08:43.408407 #4277] INFO -- : Starting D, [2016-12-14T16:08:43.451282 #4277] DEBUG -- : Sidejobs::Job Load (1.7ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-14 19:08:43.436260' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 I, [2016-12-14T16:08:54.442295 #4270] INFO -- : Stopping I, [2016-12-14T16:08:58.453732 #4277] INFO -- : Stopping I, [2016-12-14T16:09:03.420239 #4264] INFO -- : Starting I, [2016-12-14T16:09:03.420380 #4264] INFO -- : Stopping D, [2016-12-14T16:09:03.558394 #4264] DEBUG -- : Sidejobs::Job Load (0.7ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-14 19:09:03.557168' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 I, [2016-12-14T16:09:03.558886 #4264] INFO -- : Processing ActionMailer::DeliveryJob #83 attempt 1 at mailers D, [2016-12-14T16:09:03.559066 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.561203 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.558908"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.559619"], ["id", 83]] D, [2016-12-14T16:09:03.561793 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.723446 #4264] DEBUG -- :  (0.2ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.725101 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.723189"], ["updated_at", "2016-12-14 19:09:03.724043"], ["id", 83]] D, [2016-12-14T16:09:03.725587 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.725621 #4264] INFO -- : Done I, [2016-12-14T16:09:03.725674 #4264] INFO -- : Processing ActionMailer::DeliveryJob #84 attempt 1 at mailers D, [2016-12-14T16:09:03.725825 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.726995 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.725688"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.726292"], ["id", 84]] D, [2016-12-14T16:09:03.727522 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.730253 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.731390 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.730099"], ["updated_at", "2016-12-14 19:09:03.730677"], ["id", 84]] D, [2016-12-14T16:09:03.731840 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.731868 #4264] INFO -- : Done I, [2016-12-14T16:09:03.731914 #4264] INFO -- : Processing ActionMailer::DeliveryJob #85 attempt 1 at mailers D, [2016-12-14T16:09:03.732073 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.733221 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.731927"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.732546"], ["id", 85]] D, [2016-12-14T16:09:03.733725 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.736108 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.737474 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.735938"], ["updated_at", "2016-12-14 19:09:03.736654"], ["id", 85]] D, [2016-12-14T16:09:03.737909 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.737940 #4264] INFO -- : Done I, [2016-12-14T16:09:03.737987 #4264] INFO -- : Processing ActionMailer::DeliveryJob #86 attempt 1 at mailers D, [2016-12-14T16:09:03.738188 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.739345 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.738017"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.738608"], ["id", 86]] D, [2016-12-14T16:09:03.739834 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.742481 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.744050 #4264] DEBUG -- : SQL (0.4ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.742305"], ["updated_at", "2016-12-14 19:09:03.742991"], ["id", 86]] D, [2016-12-14T16:09:03.744625 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.744658 #4264] INFO -- : Done I, [2016-12-14T16:09:03.744711 #4264] INFO -- : Processing ActionMailer::DeliveryJob #87 attempt 1 at mailers D, [2016-12-14T16:09:03.744882 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.746306 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.744724"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.745444"], ["id", 87]] D, [2016-12-14T16:09:03.746820 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.749670 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.750923 #4264] DEBUG -- : SQL (0.3ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.749486"], ["updated_at", "2016-12-14 19:09:03.750142"], ["id", 87]] D, [2016-12-14T16:09:03.751405 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.751434 #4264] INFO -- : Done I, [2016-12-14T16:09:03.751480 #4264] INFO -- : Processing ActionMailer::DeliveryJob #88 attempt 1 at mailers D, [2016-12-14T16:09:03.751639 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.752777 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.751493"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.752067"], ["id", 88]] D, [2016-12-14T16:09:03.753277 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.755841 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.756987 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.755686"], ["updated_at", "2016-12-14 19:09:03.756241"], ["id", 88]] D, [2016-12-14T16:09:03.757453 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.757484 #4264] INFO -- : Done I, [2016-12-14T16:09:03.757571 #4264] INFO -- : Processing ActionMailer::DeliveryJob #89 attempt 1 at mailers D, [2016-12-14T16:09:03.757718 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.758811 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.757585"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.758139"], ["id", 89]] D, [2016-12-14T16:09:03.759312 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.762054 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.763449 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.761864"], ["updated_at", "2016-12-14 19:09:03.762590"], ["id", 89]] D, [2016-12-14T16:09:03.763935 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.763962 #4264] INFO -- : Done I, [2016-12-14T16:09:03.764005 #4264] INFO -- : Processing ActionMailer::DeliveryJob #90 attempt 1 at mailers D, [2016-12-14T16:09:03.764197 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.765358 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.764017"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.764673"], ["id", 90]] D, [2016-12-14T16:09:03.765897 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.768464 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.769562 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.768318"], ["updated_at", "2016-12-14 19:09:03.768888"], ["id", 90]] D, [2016-12-14T16:09:03.770015 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.770114 #4264] INFO -- : Done I, [2016-12-14T16:09:03.770195 #4264] INFO -- : Processing ActionMailer::DeliveryJob #91 attempt 1 at mailers D, [2016-12-14T16:09:03.770379 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.771423 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.770208"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.770751"], ["id", 91]] D, [2016-12-14T16:09:03.771906 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.774156 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.775272 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.773947"], ["updated_at", "2016-12-14 19:09:03.774586"], ["id", 91]] D, [2016-12-14T16:09:03.775695 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.775720 #4264] INFO -- : Done I, [2016-12-14T16:09:03.775760 #4264] INFO -- : Processing ActionMailer::DeliveryJob #92 attempt 1 at mailers D, [2016-12-14T16:09:03.775958 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.777038 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.775772"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.776341"], ["id", 92]] D, [2016-12-14T16:09:03.777510 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.779806 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.780960 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.779625"], ["updated_at", "2016-12-14 19:09:03.780240"], ["id", 92]] D, [2016-12-14T16:09:03.781390 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.781415 #4264] INFO -- : Done I, [2016-12-14T16:09:03.781456 #4264] INFO -- : Processing ActionMailer::DeliveryJob #93 attempt 1 at mailers D, [2016-12-14T16:09:03.781637 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.782627 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.781467"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.782014"], ["id", 93]] D, [2016-12-14T16:09:03.783104 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.785382 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.786442 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.785206"], ["updated_at", "2016-12-14 19:09:03.785795"], ["id", 93]] D, [2016-12-14T16:09:03.786897 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.786923 #4264] INFO -- : Done I, [2016-12-14T16:09:03.786968 #4264] INFO -- : Processing ActionMailer::DeliveryJob #94 attempt 1 at mailers D, [2016-12-14T16:09:03.787151 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.788154 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.786981"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.787532"], ["id", 94]] D, [2016-12-14T16:09:03.788625 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.791041 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.792117 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.790883"], ["updated_at", "2016-12-14 19:09:03.791435"], ["id", 94]] D, [2016-12-14T16:09:03.792599 #4264] DEBUG -- :  (0.2ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.792645 #4264] INFO -- : Done I, [2016-12-14T16:09:03.792688 #4264] INFO -- : Processing ActionMailer::DeliveryJob #95 attempt 1 at mailers D, [2016-12-14T16:09:03.792861 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.793853 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.792699"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.793238"], ["id", 95]] D, [2016-12-14T16:09:03.794321 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.796746 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.797793 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.796571"], ["updated_at", "2016-12-14 19:09:03.797130"], ["id", 95]] D, [2016-12-14T16:09:03.798246 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.798276 #4264] INFO -- : Done I, [2016-12-14T16:09:03.798328 #4264] INFO -- : Processing ActionMailer::DeliveryJob #96 attempt 1 at mailers D, [2016-12-14T16:09:03.798508 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.799484 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.798340"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.798877"], ["id", 96]] D, [2016-12-14T16:09:03.799936 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.802204 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.803196 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.802047"], ["updated_at", "2016-12-14 19:09:03.802573"], ["id", 96]] D, [2016-12-14T16:09:03.803608 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.803637 #4264] INFO -- : Done I, [2016-12-14T16:09:03.803681 #4264] INFO -- : Processing ActionMailer::DeliveryJob #97 attempt 1 at mailers D, [2016-12-14T16:09:03.803844 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.804871 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.803694"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.804254"], ["id", 97]] D, [2016-12-14T16:09:03.805323 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.807545 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.808518 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.807389"], ["updated_at", "2016-12-14 19:09:03.807910"], ["id", 97]] D, [2016-12-14T16:09:03.808955 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.808986 #4264] INFO -- : Done I, [2016-12-14T16:09:03.809032 #4264] INFO -- : Processing ActionMailer::DeliveryJob #98 attempt 1 at mailers D, [2016-12-14T16:09:03.809179 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.810240 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.809045"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.809586"], ["id", 98]] D, [2016-12-14T16:09:03.810695 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.812975 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.813983 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.812801"], ["updated_at", "2016-12-14 19:09:03.813343"], ["id", 98]] D, [2016-12-14T16:09:03.814434 #4264] DEBUG -- :  (0.2ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.814478 #4264] INFO -- : Done I, [2016-12-14T16:09:03.814549 #4264] INFO -- : Processing ActionMailer::DeliveryJob #99 attempt 1 at mailers D, [2016-12-14T16:09:03.814754 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.815873 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.814577"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.815188"], ["id", 99]] D, [2016-12-14T16:09:03.816380 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.818814 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.819885 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.818617"], ["updated_at", "2016-12-14 19:09:03.819219"], ["id", 99]] D, [2016-12-14T16:09:03.820304 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.820330 #4264] INFO -- : Done I, [2016-12-14T16:09:03.820377 #4264] INFO -- : Processing ActionMailer::DeliveryJob #100 attempt 1 at mailers D, [2016-12-14T16:09:03.820598 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.821713 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.820388"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.821038"], ["id", 100]] D, [2016-12-14T16:09:03.822419 #4264] DEBUG -- :  (0.2ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.824990 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.826172 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.824800"], ["updated_at", "2016-12-14 19:09:03.825485"], ["id", 100]] D, [2016-12-14T16:09:03.826638 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.826667 #4264] INFO -- : Done I, [2016-12-14T16:09:03.826714 #4264] INFO -- : Processing ActionMailer::DeliveryJob #101 attempt 1 at mailers D, [2016-12-14T16:09:03.826873 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.827921 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.826727"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.827306"], ["id", 101]] D, [2016-12-14T16:09:03.828382 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.830780 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.831815 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.830604"], ["updated_at", "2016-12-14 19:09:03.831156"], ["id", 101]] D, [2016-12-14T16:09:03.832261 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.832292 #4264] INFO -- : Done I, [2016-12-14T16:09:03.832348 #4264] INFO -- : Processing ActionMailer::DeliveryJob #102 attempt 1 at mailers D, [2016-12-14T16:09:03.832497 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.833587 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.832362"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.832921"], ["id", 102]] D, [2016-12-14T16:09:03.834054 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.836474 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.837476 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.836322"], ["updated_at", "2016-12-14 19:09:03.836847"], ["id", 102]] D, [2016-12-14T16:09:03.837938 #4264] DEBUG -- :  (0.2ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.837969 #4264] INFO -- : Done D, [2016-12-14T16:09:03.843191 #4264] DEBUG -- : Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-14 19:09:03.842645' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 I, [2016-12-14T16:09:03.843389 #4264] INFO -- : Processing ActionMailer::DeliveryJob #103 attempt 1 at mailers D, [2016-12-14T16:09:03.843560 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.844649 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.843408"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.843987"], ["id", 103]] D, [2016-12-14T16:09:03.845116 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.847615 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.848619 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.847471"], ["updated_at", "2016-12-14 19:09:03.847991"], ["id", 103]] D, [2016-12-14T16:09:03.849081 #4264] DEBUG -- :  (0.2ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.849109 #4264] INFO -- : Done I, [2016-12-14T16:09:03.849180 #4264] INFO -- : Processing ActionMailer::DeliveryJob #104 attempt 1 at mailers D, [2016-12-14T16:09:03.849361 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.850477 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.849192"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.849817"], ["id", 104]] D, [2016-12-14T16:09:03.850957 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.853287 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.854346 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.853129"], ["updated_at", "2016-12-14 19:09:03.853685"], ["id", 104]] D, [2016-12-14T16:09:03.854773 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.854799 #4264] INFO -- : Done I, [2016-12-14T16:09:03.854841 #4264] INFO -- : Processing ActionMailer::DeliveryJob #105 attempt 1 at mailers D, [2016-12-14T16:09:03.855029 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.856183 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.854853"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.855524"], ["id", 105]] D, [2016-12-14T16:09:03.856676 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.859012 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.860069 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.858820"], ["updated_at", "2016-12-14 19:09:03.859431"], ["id", 105]] D, [2016-12-14T16:09:03.860504 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.860530 #4264] INFO -- : Done D, [2016-12-14T16:09:03.865547 #4264] DEBUG -- : Sidejobs::Job Load (0.4ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-14 19:09:03.864968' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 I, [2016-12-14T16:09:03.865724 #4264] INFO -- : Processing ActionMailer::DeliveryJob #107 attempt 1 at mailers D, [2016-12-14T16:09:03.865897 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.867078 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.865743"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.866343"], ["id", 107]] D, [2016-12-14T16:09:03.867556 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.870198 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.871297 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "completed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "complete"], ["completed_at", "2016-12-14 19:09:03.870049"], ["updated_at", "2016-12-14 19:09:03.870623"], ["id", 107]] D, [2016-12-14T16:09:03.871728 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.871754 #4264] INFO -- : Done D, [2016-12-14T16:09:03.877140 #4264] DEBUG -- : Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-14 19:09:03.876545' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 I, [2016-12-14T16:09:03.877326 #4264] INFO -- : Processing ShareProductsJob #108 attempt 1 at default D, [2016-12-14T16:09:03.877491 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.878587 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.877346"], ["attempts", 1], ["updated_at", "2016-12-14 19:09:03.877897"], ["id", 108]] D, [2016-12-14T16:09:03.879023 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.879547 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.880715 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "failed_at" = $2, "error" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "failing"], ["failed_at", "2016-12-14 19:09:03.879389"], ["error", "Social network unavailable"], ["updated_at", "2016-12-14 19:09:03.879908"], ["id", 108]] D, [2016-12-14T16:09:03.881149 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.881182 #4264] INFO -- : Error: Social network unavailable D, [2016-12-14T16:09:03.881882 #4264] DEBUG -- : Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-14 19:09:03.881300' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 I, [2016-12-14T16:09:03.882050 #4264] INFO -- : Processing ShareProductsJob #108 attempt 2 at default D, [2016-12-14T16:09:03.882215 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.883341 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.882070"], ["attempts", 2], ["updated_at", "2016-12-14 19:09:03.882647"], ["id", 108]] D, [2016-12-14T16:09:03.883785 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.884278 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.885453 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "failed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "failing"], ["failed_at", "2016-12-14 19:09:03.884086"], ["updated_at", "2016-12-14 19:09:03.884678"], ["id", 108]] D, [2016-12-14T16:09:03.885858 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.885887 #4264] INFO -- : Error: Social network unavailable D, [2016-12-14T16:09:03.886504 #4264] DEBUG -- : Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-14 19:09:03.886005' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20 I, [2016-12-14T16:09:03.886674 #4264] INFO -- : Processing ShareProductsJob #108 attempt 3 at default D, [2016-12-14T16:09:03.886840 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.887978 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "processed_at" = $2, "attempts" = $3, "updated_at" = $4 WHERE "sidejobs"."id" = $5 [["status", "processing"], ["processed_at", "2016-12-14 19:09:03.886694"], ["attempts", 3], ["updated_at", "2016-12-14 19:09:03.887337"], ["id", 108]] D, [2016-12-14T16:09:03.888470 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.888891 #4264] DEBUG -- :  (0.1ms) SAVEPOINT active_record_1 D, [2016-12-14T16:09:03.889821 #4264] DEBUG -- : SQL (0.2ms) UPDATE "sidejobs" SET "status" = $1, "failed_at" = $2, "updated_at" = $3 WHERE "sidejobs"."id" = $4 [["status", "failing"], ["failed_at", "2016-12-14 19:09:03.888736"], ["updated_at", "2016-12-14 19:09:03.889235"], ["id", 108]] D, [2016-12-14T16:09:03.890193 #4264] DEBUG -- :  (0.1ms) RELEASE SAVEPOINT active_record_1 I, [2016-12-14T16:09:03.890222 #4264] INFO -- : Error: Social network unavailable D, [2016-12-14T16:09:03.890825 #4264] DEBUG -- : Sidejobs::Job Load (0.3ms) SELECT "sidejobs".* FROM "sidejobs" WHERE "sidejobs"."status" IN ('pending', 'failing') AND (attempts < 3) AND (scheduled_at <= '2016-12-14 19:09:03.890327' OR scheduled_at IS NULL) ORDER BY "sidejobs"."priority" DESC LIMIT 20